Oct 03 16:06:00 volumio systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Oct 03 16:06:00 volumio systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Oct 03 16:06:00 volumio systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Oct 03 16:06:00 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 03 16:06:00 volumio systemd[1]: Starting rpcbind.service - RPC bind portmap service... Oct 03 16:06:00 volumio systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 03 16:06:00 volumio systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Oct 03 16:06:00 volumio systemd[1]: Reached target rpc_pipefs.target. Oct 03 16:06:00 volumio 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). Oct 03 16:06:00 volumio systemd[1]: Reached target nfs-client.target - NFS client services. Oct 03 16:06:00 volumio systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Oct 03 16:06:00 volumio kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Oct 03 16:06:00 volumio kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Oct 03 16:06:00 volumio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 03 16:06:00 volumio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Oct 03 16:06:00 volumio ifup[588]: command failed: No such device (-19) Oct 03 16:06:00 volumio systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Oct 03 16:06:00 volumio systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Oct 03 16:06:00 volumio systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 03 16:06:00 volumio systemd[1]: Reached target paths.target - Path Units. Oct 03 16:06:00 volumio systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Oct 03 16:06:00 volumio systemd[1]: apparmor.service - Load AppArmor profiles was skipped because of an unmet condition check (ConditionSecurity=apparmor). Oct 03 16:06:00 volumio systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 03 16:06:00 volumio systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 03 16:06:00 volumio sh[602]: command failed: No such device (-19) Oct 03 16:06:00 volumio systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 03 16:06:00 volumio systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 03 16:06:00 volumio systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Oct 03 16:06:00 volumio 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). Oct 03 16:06:00 volumio systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 03 16:06:00 volumio systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 03 16:06:00 volumio systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 03 16:06:00 volumio systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 03 16:06:00 volumio systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 03 16:06:00 volumio systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 03 16:06:00 volumio systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 03 16:06:00 volumio systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 03 16:06:00 volumio systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 03 16:06:00 volumio systemd[1]: Reached target sysinit.target - System Initialization. Oct 03 16:06:00 volumio sh[642]: dhcpcd-9.4.1 starting Oct 03 16:06:00 volumio dhcpcd[642]: dhcpcd-9.4.1 starting Oct 03 16:06:00 volumio systemd[1]: apt-daily.timer: Not using persistent file timestamp Wed 2026-10-14 21:28:04 JST as it is in the future. Oct 03 16:06:00 volumio systemd[1]: Started apt-daily.timer - Daily apt download activities. Oct 03 16:06:00 volumio systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Wed 2026-10-14 17:15:11 JST as it is in the future. Oct 03 16:06:00 volumio systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Oct 03 16:06:00 volumio systemd[1]: dpkg-db-backup.timer: Not using persistent file timestamp Wed 2026-10-14 17:13:15 JST as it is in the future. Oct 03 16:06:00 volumio systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Oct 03 16:06:00 volumio systemd[1]: e2scrub_all.timer: Not using persistent file timestamp Wed 2026-10-14 17:13:15 JST as it is in the future. Oct 03 16:06:00 volumio systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Oct 03 16:06:00 volumio systemd[1]: fstrim.timer: Not using persistent file timestamp Wed 2026-10-14 17:25:06 JST as it is in the future. Oct 03 16:06:00 volumio systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Oct 03 16:06:00 volumio systemd[1]: ntpsec-rotate-stats.timer: Not using persistent file timestamp Wed 2026-10-14 17:13:15 JST as it is in the future. Oct 03 16:06:00 volumio systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Oct 03 16:06:00 volumio systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Oct 03 16:06:00 volumio ifup[647]: command failed: No such device (-19) Oct 03 16:06:00 volumio systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 03 16:06:00 volumio systemd[1]: Reached target timers.target - Timer Units. Oct 03 16:06:00 volumio systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Oct 03 16:06:00 volumio systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 03 16:06:00 volumio systemd[1]: Reached target sockets.target - Socket Units. Oct 03 16:06:00 volumio 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). Oct 03 16:06:00 volumio systemd[1]: Reached target basic.target - Basic System. Oct 03 16:06:00 volumio systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Oct 03 16:06:00 volumio dhcpcd[652]: DUID 00:01:00:01:31:3d:c1:85:9c:eb:e8:7c:d3:c3 Oct 03 16:06:00 volumio sh[642]: DUID 00:01:00:01:31:3d:c1:85:9c:eb:e8:7c:d3:c3 Oct 03 16:06:00 volumio systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Oct 03 16:06:00 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 03 16:06:00 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 03 16:06:00 volumio systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 03 16:06:00 volumio systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Oct 03 16:06:00 volumio 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). Oct 03 16:06:00 volumio systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Oct 03 16:06:00 volumio systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Oct 03 16:06:00 volumio systemd[1]: Started iptables.service - Volumio Iptables Module. Oct 03 16:06:00 volumio systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Oct 03 16:06:00 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Oct 03 16:06:00 volumio systemd[1]: Starting systemd-logind.service - User Login Management... Oct 03 16:06:00 volumio haveged[608]: haveged: command socket is listening at fd 3 Oct 03 16:06:00 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Oct 03 16:06:00 volumio kernel: usbcore: registered new interface driver brcmfmac Oct 03 16:06:00 volumio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Oct 03 16:06:00 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 16:06:00 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 03 16:06:00 volumio systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Oct 03 16:06:01 volumio systemd[1]: Starting wireless.service - Wireless Services... Oct 03 16:06:01 volumio systemd[1]: Started rpcbind.service - RPC bind portmap service. Oct 03 16:06:01 volumio systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 03 16:06:01 volumio systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 03 16:06:01 volumio avahi-daemon[665]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Oct 03 16:06:01 volumio systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Oct 03 16:06:01 volumio avahi-daemon[665]: Successfully dropped root privileges. Oct 03 16:06:01 volumio avahi-daemon[665]: avahi-daemon 0.8 starting up. Oct 03 16:06:01 volumio systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Oct 03 16:06:01 volumio 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). Oct 03 16:06:01 volumio thd[676]: Unable to parse trigger line: Oct 03 16:06:01 volumio thd[676]: Unable to parse trigger line: Oct 03 16:06:01 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Oct 03 16:06:01 volumio systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Oct 03 16:06:01 volumio systemd[1]: th-udev-rebind.service: Deactivated successfully. Oct 03 16:06:01 volumio systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Oct 03 16:06:01 volumio rpi-eeprom-update[671]: Device does not a have a Raspberry Pi bootloader EEPROM (e.g. Pi 4 or Pi 5). Skipping bootloader update. Oct 03 16:06:01 volumio systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Oct 03 16:06:01 volumio alsactl[702]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 16:06:01 volumio alsactl[702]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 03 16:06:01 volumio alsactl[702]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Digital Playback Switch:0' : Input/output error Oct 03 16:06:01 volumio alsactl[702]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument Oct 03 16:06:01 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -5 Oct 03 16:06:01 volumio systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Oct 03 16:06:01 volumio systemd[1]: Reached target sound.target - Sound Card. Oct 03 16:06:01 volumio sh[642]: eth0: waiting for carrier Oct 03 16:06:01 volumio sh[642]: eth0: carrier acquired Oct 03 16:06:01 volumio dhcpcd[652]: eth0: waiting for carrier Oct 03 16:06:01 volumio dhcpcd[652]: eth0: carrier acquired Oct 03 16:06:01 volumio kernel: r8152 1-1.4:1.0 eth0: carrier on Oct 03 16:06:01 volumio sh[642]: eth0: IAID e8:7c:d3:c3 Oct 03 16:06:01 volumio dhcpcd[652]: eth0: IAID e8:7c:d3:c3 Oct 03 16:06:01 volumio sh[642]: eth0: adding address fe80::55ef:3c7b:a915:73bb Oct 03 16:06:01 volumio dhcpcd[652]: eth0: adding address fe80::55ef:3c7b:a915:73bb Oct 03 16:06:01 volumio kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Oct 03 16:06:01 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jun 14 2023 07:27:45 version 7.45.96.s1 (gf031a129) FWID 01-70bd2af7 es7 Oct 03 16:06:01 volumio sh[642]: ipv6_addaddr1: Permission denied Oct 03 16:06:01 volumio sh[642]: eth0: carrier lost Oct 03 16:06:01 volumio sh[642]: eth0: carrier acquired Oct 03 16:06:01 volumio dhcpcd[652]: ipv6_addaddr1: Permission denied Oct 03 16:06:01 volumio dhcpcd[652]: eth0: carrier lost Oct 03 16:06:01 volumio dhcpcd[652]: eth0: carrier acquired Oct 03 16:06:01 volumio dhcpcd[652]: eth0: IAID e8:7c:d3:c3 Oct 03 16:06:01 volumio sh[642]: eth0: IAID e8:7c:d3:c3 Oct 03 16:06:01 volumio systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 03 16:06:01 volumio avahi-daemon[665]: Successfully called chroot(). Oct 03 16:06:01 volumio avahi-daemon[665]: Successfully dropped remaining capabilities. Oct 03 16:06:01 volumio avahi-daemon[665]: No service file found in /etc/avahi/services. Oct 03 16:06:01 volumio avahi-daemon[665]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 03 16:06:01 volumio avahi-daemon[665]: New relevant interface lo.IPv4 for mDNS. Oct 03 16:06:01 volumio avahi-daemon[665]: Network interface enumeration completed. Oct 03 16:06:01 volumio avahi-daemon[665]: Registering new address record for 127.0.0.1 on lo.IPv4. Oct 03 16:06:01 volumio systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Oct 03 16:06:01 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 03 16:06:02 volumio systemd[1]: e2scrub_reap.service: Deactivated successfully. Oct 03 16:06:02 volumio systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Oct 03 16:06:02 volumio kernel: r8152 1-1.4:1.0 eth0: carrier off Oct 03 16:06:02 volumio systemd[1]: Finished networking.service - Raise network interfaces. Oct 03 16:06:02 volumio dhcpcd[669]: dhcpcd-9.4.1 starting Oct 03 16:06:02 volumio sh[642]: eth0: carrier lost Oct 03 16:06:02 volumio dhcpcd[652]: eth0: carrier lost Oct 03 16:06:02 volumio systemd[1]: iptables.service: Deactivated successfully. Oct 03 16:06:02 volumio dhcpcd[781]: dev: loaded udev Oct 03 16:06:02 volumio loadcpufreq[688]: Loading cpufreq kernel modules...done (none). Oct 03 16:06:02 volumio systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Oct 03 16:06:02 volumio dhcpcd[781]: DUID 00:01:00:01:31:3d:c1:85:9c:eb:e8:7c:d3:c3 Oct 03 16:06:02 volumio systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Oct 03 16:06:02 volumio systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Oct 03 16:06:02 volumio avahi-daemon[665]: Server startup complete. Host name is volumio.local. Local service cookie is 2646751944. Oct 03 16:06:02 volumio wpa_supplicant[732]: Successfully initialized wpa_supplicant Oct 03 16:06:02 volumio systemd[1]: Started wpa_supplicant.service - WPA supplicant. Oct 03 16:06:02 volumio dhcpcd[781]: eth0: waiting for carrier Oct 03 16:06:02 volumio systemd[1]: Reached target network.target - Network. Oct 03 16:06:02 volumio systemd[1]: Reached target network-online.target - Network is Online. Oct 03 16:06:02 volumio systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Oct 03 16:06:02 volumio systemd[1]: Starting nmbd.service - Samba NMB Daemon... Oct 03 16:06:02 volumio systemd-logind[675]: New seat seat0. Oct 03 16:06:02 volumio systemd[1]: Starting ntpsec.service - Network Time Service... Oct 03 16:06:02 volumio systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Oct 03 16:06:02 volumio systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Oct 03 16:06:03 volumio systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Oct 03 16:06:03 volumio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Oct 03 16:06:03 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 03 16:06:03 volumio sm-notify[820]: Version 2.6.2 starting Oct 03 16:06:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 16:06:03 volumio volumio-remote-updater[680]: Volumio OTA Client: 1.7.0 Oct 03 16:06:03 volumio volumio-remote-updater[680]: Error: No active session Oct 03 16:06:03 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Oct 03 16:06:03 volumio volumio-remote-updater[680]: [2026-10-03 16:06:03] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 03 16:06:03 volumio volumio-remote-updater[680]: [2026-10-03 16:06:03] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 03 16:06:03 volumio volumio-remote-updater[680]: [2026-10-03 16:06:03] [error] handle_connect error: Connection refused Oct 03 16:06:03 volumio systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 03 16:06:03 volumio systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Oct 03 16:06:03 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 03 16:06:03 volumio systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Oct 03 16:06:03 volumio systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Oct 03 16:06:03 volumio systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 03 16:06:03 volumio welcome[840]: Resolved ip:[0] Oct 03 16:06:03 volumio upmpdcli[678]: Could not open config: /tmp/upmpdcli.conf Oct 03 16:06:03 volumio systemd[1]: Started systemd-logind.service - User Login Management. Oct 03 16:06:03 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 16:06:03 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 16:06:03 volumio ntpd[834]: INIT: ntpd ntpsec-1.2.2: Starting Oct 03 16:06:03 volumio ntp-systemd-wrapper[834]: 2026-10-03T16:06:03 ntpd[834]: INIT: ntpd ntpsec-1.2.2: Starting Oct 03 16:06:03 volumio ntp-systemd-wrapper[834]: 2026-10-03T16:06:03 ntpd[834]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Oct 03 16:06:03 volumio ntpd[834]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Oct 03 16:06:03 volumio systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Oct 03 16:06:03 volumio systemd[1]: Started ntpsec.service - Network Time Service. Oct 03 16:06:03 volumio ntpd[861]: INIT: precision = 1.354 usec (-19) Oct 03 16:06:03 volumio ntpd[861]: INIT: successfully locked into RAM Oct 03 16:06:03 volumio ntpd[861]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Oct 03 16:06:03 volumio ntpd[861]: CONFIG: restrict nopeer ignored Oct 03 16:06:04 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 03 16:06:04 volumio ntpd[861]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 03 16:06:04 volumio ntpd[861]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Oct 03 16:06:04 volumio ntpd[861]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 98 days ago Oct 03 16:06:04 volumio ntpd[861]: INIT: Using SO_TIMESTAMPNS(ns) Oct 03 16:06:04 volumio ntpd[861]: IO: Listen and drop on 0 v6wildcard [::]:123 Oct 03 16:06:04 volumio ntpd[861]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 03 16:06:04 volumio ntpd[861]: IO: Listen normally on 2 lo 127.0.0.1:123 Oct 03 16:06:04 volumio ntpd[861]: IO: Listening on routing socket on fd #19 for interface updates Oct 03 16:06:04 volumio ifplugd(eth0)[880]: ifplugd 0.28 initializing. Oct 03 16:06:04 volumio ifplugd(eth0)[880]: Using interface eth0/9C:EB:E8:7C:D3:C3 with driver (version: v1.12.13) Oct 03 16:06:04 volumio ifplugd(eth0)[880]: Using detection mode: SIOCETHTOOL Oct 03 16:06:04 volumio ifplugd(eth0)[880]: Initialization complete, link beat not detected. Oct 03 16:06:04 volumio cpufrequtils[798]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 03 16:06:04 volumio systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Oct 03 16:06:04 volumio ifplugd[809]: Network Interface Plugging Daemon...start eth0...done. Oct 03 16:06:04 volumio ntpd[861]: INIT: This system has a 32-bit time_t. Oct 03 16:06:04 volumio ntpd[861]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Oct 03 16:06:04 volumio ntpd[861]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 03 16:06:04 volumio ntpd[861]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Oct 03 16:06:04 volumio ntpd[861]: INIT: Running with OpenSSL 3.0.19 27 Jan 2026, 30000130 Oct 03 16:06:04 volumio ntpd[861]: NTSc: Using system default root certificates. Oct 03 16:06:04 volumio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Oct 03 16:06:04 volumio haveged[608]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Oct 03 16:06:04 volumio haveged[608]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14748/63356 Oct 03 16:06:04 volumio haveged[608]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99739 Oct 03 16:06:04 volumio haveged[608]: haveged: fills: 0, generated: 0 Oct 03 16:06:04 volumio sshd[887]: Server listening on 0.0.0.0 port 22. Oct 03 16:06:04 volumio sshd[887]: Server listening on :: port 22. Oct 03 16:06:04 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Oct 03 16:06:04 volumio kernel: r8152 1-1.4:1.0 eth0: carrier on Oct 03 16:06:04 volumio dhcpcd[652]: eth0: carrier acquired Oct 03 16:06:04 volumio sh[642]: eth0: carrier acquired Oct 03 16:06:04 volumio dhcpcd[781]: eth0: carrier acquired Oct 03 16:06:04 volumio dhcpcd[652]: eth0: IAID e8:7c:d3:c3 Oct 03 16:06:04 volumio sh[642]: eth0: IAID e8:7c:d3:c3 Oct 03 16:06:04 volumio ntpd[861]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Oct 03 16:06:04 volumio dhcpcd[781]: eth0: IAID e8:7c:d3:c3 Oct 03 16:06:04 volumio dhcpcd[781]: eth0: adding address fe80::55ef:3c7b:a915:73bb Oct 03 16:06:04 volumio dhcpcd[781]: ipv6_addaddr1: Permission denied Oct 03 16:06:05 volumio dhcpcd[652]: eth0: rebinding lease of 192.168.0.20 Oct 03 16:06:05 volumio sh[642]: eth0: rebinding lease of 192.168.0.20 Oct 03 16:06:05 volumio ifplugd(eth0)[880]: Link beat detected. Oct 03 16:06:05 volumio systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 03 16:06:05 volumio ifplugd(eth0)[880]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 03 16:06:05 volumio systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Oct 03 16:06:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:05 volumio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Oct 03 16:06:05 volumio (udev-worker)[477]: Network interface NamePolicy= disabled on kernel command line. Oct 03 16:06:05 volumio ifplugd(eth0)[880]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Oct 03 16:06:05 volumio sh[642]: eth0: soliciting an IPv6 router Oct 03 16:06:05 volumio dhcpcd[652]: eth0: soliciting an IPv6 router Oct 03 16:06:05 volumio dhcpcd[781]: eth0: soliciting an IPv6 router Oct 03 16:06:05 volumio systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... Oct 03 16:06:05 volumio ntpd[861]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 16:06:05 volumio ntpd[861]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 03 16:06:05 volumio ntpd[861]: DNS: dns_check: DNS error: -11, System error Oct 03 16:06:05 volumio ntpd[861]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Oct 03 16:06:05 volumio systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. Oct 03 16:06:06 volumio systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Oct 03 16:06:06 volumio dhcpcd[781]: wlan0: waiting for carrier Oct 03 16:06:06 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled Oct 03 16:06:06 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 03 16:06:06 volumio dhcpcd[781]: eth0: rebinding lease of 192.168.0.20 Oct 03 16:06:06 volumio systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Oct 03 16:06:06 volumio systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Oct 03 16:06:06 volumio ntpd[861]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 16:06:06 volumio ntpd[861]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 03 16:06:06 volumio ntpd[861]: DNS: dns_check: DNS error: -11, System error Oct 03 16:06:06 volumio ntpd[861]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Oct 03 16:06:06 volumio sh[1034]: wlan0=wlan0 Oct 03 16:06:07 volumio nmbd[1036]: [2026/10/03 16:06:07.627504, 0] ../../source3/nmbd/nmbd.c:901(main) Oct 03 16:06:07 volumio nmbd[1036]: nmbd version 4.17.8-Raspbian started. Oct 03 16:06:07 volumio nmbd[1036]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 03 16:06:07 volumio nmbd[1036]: [2026/10/03 16:06:07.634565, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 03 16:06:07 volumio nmbd[1036]: started asyncdns process 1044 Oct 03 16:06:07 volumio nmbd[1036]: [2026/10/03 16:06:07.637045, 0] ../../lib/util/become_daemon.c:150(daemon_status) Oct 03 16:06:07 volumio nmbd[1036]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 03 16:06:07 volumio nmbd[1036]: [2026/10/03 16:06:07.637242, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Oct 03 16:06:07 volumio nmbd[1036]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 03 16:06:07 volumio ntpd[861]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 16:06:07 volumio ntpd[861]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 03 16:06:07 volumio ntpd[861]: DNS: dns_check: DNS error: -11, System error Oct 03 16:06:07 volumio ntpd[861]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Oct 03 16:06:08 volumio volumio-remote-updater[680]: [2026-10-03 16:06:08] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 03 16:06:08 volumio volumio-remote-updater[680]: [2026-10-03 16:06:08] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 03 16:06:08 volumio volumio-remote-updater[680]: [2026-10-03 16:06:08] [error] handle_connect error: Connection refused Oct 03 16:06:08 volumio wireless.js[681]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Oct 03 16:06:08 volumio ntpd[861]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 16:06:08 volumio ntpd[861]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 03 16:06:08 volumio ntpd[861]: DNS: dns_check: DNS error: -11, System error Oct 03 16:06:08 volumio ntpd[861]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Oct 03 16:06:08 volumio wireless.js[681]: WIRELESS.JS - INFO: === SNM TRANSITION === Oct 03 16:06:09 volumio wireless.js[681]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Oct 03 16:06:09 volumio wireless.js[681]: WIRELESS.JS - INFO: New ethernet state: connected Oct 03 16:06:09 volumio wireless.js[681]: WIRELESS.JS - INFO: Single Network Mode: enabled Oct 03 16:06:09 volumio wireless.js[681]: WIRELESS.JS - INFO: First start: yes Oct 03 16:06:09 volumio wireless.js[681]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Oct 03 16:06:09 volumio wireless.js[681]: WIRELESS.JS - INFO: === END TRANSITION === Oct 03 16:06:09 volumio wireless.js[681]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Oct 03 16:06:09 volumio bash[828]: setdatetime-helper: all HTTPS Date fallbacks failed Oct 03 16:06:09 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 03 16:06:09 volumio sudo[1066]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 03 16:06:09 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 03 16:06:09 volumio sudo[1066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 16:06:09 volumio sudo[1066]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:09 volumio sudo[1069]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 03 16:06:09 volumio sudo[1069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 16:06:10 volumio dhcpcd[652]: eth0: DHCP lease expired Oct 03 16:06:10 volumio sh[642]: eth0: DHCP lease expired Oct 03 16:06:10 volumio sh[642]: eth0: soliciting a DHCP lease Oct 03 16:06:10 volumio dhcpcd[652]: eth0: soliciting a DHCP lease Oct 03 16:06:10 volumio sudo[1069]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:10 volumio wireless.js[681]: WIRELESS.JS - INFO: Cleaning previous... Oct 03 16:06:10 volumio sudo[1080]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 03 16:06:10 volumio sudo[1080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 16:06:10 volumio sudo[1080]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:10 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 03 16:06:10 volumio wireless.js[681]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Oct 03 16:06:10 volumio wireless.js[681]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 10ms Oct 03 16:06:10 volumio wireless.js[681]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 88:a2:9e:4d:43:76) Oct 03 16:06:10 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. Oct 03 16:06:10 volumio sudo[1087]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 03 16:06:10 volumio sudo[1087]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:10 volumio sudo[1087]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:10 volumio sudo[1095]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 03 16:06:10 volumio sudo[1095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:11 volumio sudo[1095]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:11 volumio wireless.js[681]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: JP Oct 03 16:06:11 volumio sudo[1101]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Oct 03 16:06:11 volumio sudo[1101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:11 volumio sudo[1101]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:11 volumio wireless.js[681]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: JP Oct 03 16:06:11 volumio wireless.js[681]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Oct 03 16:06:11 volumio wireless.js[681]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Oct 03 16:06:11 volumio wireless.js[681]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Oct 03 16:06:11 volumio wireless.js[681]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Oct 03 16:06:11 volumio dhcpcd[781]: eth0: DHCP lease expired Oct 03 16:06:11 volumio dhcpcd[781]: eth0: soliciting a DHCP lease Oct 03 16:06:11 volumio sudo[1120]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 03 16:06:11 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 16:06:11 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:11 volumio sudo[1123]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 03 16:06:11 volumio sudo[1123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 16:06:11 volumio sudo[1123]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:11 volumio wpa_supplicant[1126]: Successfully initialized wpa_supplicant Oct 03 16:06:12 volumio wpa_supplicant[1126]: nl80211: kernel reports: Registration to specific type not supported Oct 03 16:06:12 volumio wireless.js[681]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 3281ms Oct 03 16:06:12 volumio wireless.js[681]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Oct 03 16:06:12 volumio wireless.js[681]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Oct 03 16:06:12 volumio wireless.js[681]: WIRELESS.JS - INFO: Notified systemd about wireless ready Oct 03 16:06:12 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 03 16:06:12 volumio systemd[1]: Started wireless.service - Wireless Services. Oct 03 16:06:12 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Oct 03 16:06:12 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Oct 03 16:06:12 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Oct 03 16:06:12 volumio systemd[1]: Received SIGRTMIN+21 from PID 292 (plymouthd). Oct 03 16:06:12 volumio systemd[1]: Received SIGRTMIN+21 from PID 292 (plymouthd). Oct 03 16:06:12 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Oct 03 16:06:12 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Oct 03 16:06:12 volumio volumio-cpu-tweak[1148]: Setting RT Priority for mpd Oct 03 16:06:12 volumio volumio-cpu-tweak[1163]: pid 35's current scheduling policy: SCHED_OTHER Oct 03 16:06:12 volumio volumio-cpu-tweak[1163]: pid 35's current scheduling priority: 0 Oct 03 16:06:12 volumio volumio-cpu-tweak[1148]: Setting MPD Affinity Oct 03 16:06:12 volumio volumio-cpu-tweak[1164]: pid 3's current affinity mask: f Oct 03 16:06:12 volumio volumio-cpu-tweak[1148]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Oct 03 16:06:12 volumio volumio-cpu-tweak[1148]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Oct 03 16:06:12 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 03 16:06:12 volumio systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 03 16:06:12 volumio systemd[1]: Reached target getty.target - Login Prompts. Oct 03 16:06:12 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Oct 03 16:06:15 volumio volumio-remote-updater[680]: [2026-10-03 16:06:15] [info] asio async_connect error: asio.system:111 (Connection refused) Oct 03 16:06:15 volumio volumio-remote-updater[680]: [2026-10-03 16:06:15] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 03 16:06:15 volumio volumio-remote-updater[680]: [2026-10-03 16:06:15] [error] handle_connect error: Connection refused Oct 03 16:06:18 volumio volumio[1146]: info: ------------------------------------------- Oct 03 16:06:18 volumio volumio[1146]: info: ----- Volumio3 ---- Oct 03 16:06:18 volumio volumio[1146]: info: ------------------------------------------- Oct 03 16:06:18 volumio volumio[1146]: info: ----- System startup ---- Oct 03 16:06:18 volumio volumio[1146]: info: ------------------------------------------- Oct 03 16:06:18 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Oct 03 16:06:18 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 16:06:18 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 16:06:19 volumio upmpdcli[1175]: Could not open config: /tmp/upmpdcli.conf Oct 03 16:06:19 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 16:06:19 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 16:06:20 volumio volumio[1146]: info: MYVOLUMIO Environment detected Oct 03 16:06:20 volumio volumio[1146]: info: Plugin folders cleanup Oct 03 16:06:20 volumio volumio[1146]: info: Scanning into folder /volumio/app/plugins/ Oct 03 16:06:20 volumio volumio[1146]: info: Scanning category audio_interface Oct 03 16:06:20 volumio volumio[1146]: info: Scanning category miscellanea Oct 03 16:06:20 volumio volumio[1146]: info: Scanning category music_service Oct 03 16:06:20 volumio volumio[1146]: info: Scanning category plugins.json Oct 03 16:06:20 volumio volumio[1146]: info: Scanning category system_controller Oct 03 16:06:20 volumio volumio[1146]: info: Scanning category user_interface Oct 03 16:06:20 volumio volumio[1146]: info: Scanning into folder /data/plugins/ Oct 03 16:06:20 volumio volumio[1146]: info: Scanning category audio_interface Oct 03 16:06:20 volumio volumio[1146]: info: Scanning category music_service Oct 03 16:06:20 volumio volumio[1146]: info: Plugin folders cleanup completed Oct 03 16:06:20 volumio volumio[1146]: info: ------------------------------------------- Oct 03 16:06:20 volumio volumio[1146]: info: ----- Core plugins startup ---- Oct 03 16:06:20 volumio volumio[1146]: info: ------------------------------------------- Oct 03 16:06:20 volumio volumio[1146]: info: Loading plugins from folder /volumio/app/plugins/ Oct 03 16:06:20 volumio volumio[1146]: info: Adding plugin upnp to MyMusic Plugins Oct 03 16:06:20 volumio volumio[1146]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 03 16:06:20 volumio volumio[1146]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 03 16:06:20 volumio volumio[1146]: info: Loading plugins from folder /data/plugins/ Oct 03 16:06:20 volumio volumio[1146]: info: Loading plugin "system"... Oct 03 16:06:20 volumio volumio[1146]: info: Loading plugin "appearance"... Oct 03 16:06:23 volumio volumio[1146]: info: Loading plugin "network"... Oct 03 16:06:23 volumio volumio[1146]: info: Refreshing Cached IP Addresses Oct 03 16:06:23 volumio volumio[1146]: info: Loading plugin "services"... Oct 03 16:06:23 volumio sudo[1183]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 16:06:23 volumio volumio[1146]: info: Loading plugin "volumio5onboarding"... Oct 03 16:06:23 volumio sudo[1183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:23 volumio sudo[1183]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:23 volumio sudo[1196]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 03 16:06:23 volumio sudo[1196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:23 volumio volumio[1146]: info: Loading plugin "alsa_controller"... Oct 03 16:06:23 volumio sudo[1185]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 16:06:23 volumio sudo[1185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:23 volumio sudo[1185]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:23 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 16:06:23 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:23 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:23 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 03 16:06:23 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 03 16:06:23 volumio volumio[1146]: info: Loading plugin "wizard"... Oct 03 16:06:23 volumio volumio[1146]: info: Loading plugin "networkfs"... Oct 03 16:06:23 volumio volumio[1146]: info: Starting Udev Watcher for removable devices Oct 03 16:06:24 volumio volumio[1146]: info: Ignoring mount for partition: boot Oct 03 16:06:24 volumio volumio[1146]: info: Ignoring mount for partition: volumio Oct 03 16:06:24 volumio volumio[1146]: info: Ignoring mount for partition: volumio_data Oct 03 16:06:24 volumio sudo[1196]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:24 volumio volumio[1146]: info: Mounting Device C2F9-CAA6 Oct 03 16:06:24 volumio sudo[1222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/C2F9-CAA6 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 03 16:06:24 volumio sudo[1222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:24 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 03 16:06:24 volumio sudo[1222]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:24 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 03 16:06:24 volumio volumio[1146]: info: Mounting Device 10AC-160D Oct 03 16:06:24 volumio sudo[1232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/10AC-160D -o noatime Oct 03 16:06:24 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:24 volumio kernel: exFAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 03 16:06:25 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:25 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 03 16:06:25 volumio volumio[1146]: info: Loading plugin "volumio_command_line_client"... Oct 03 16:06:25 volumio volumio[1146]: info: Loading plugin "upnp"... Oct 03 16:06:25 volumio volumio[1146]: info: [1791011185434] Starting Upmpd Daemon Oct 03 16:06:25 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 03 16:06:25 volumio volumio[1146]: info: Loading plugin "my_music"... Oct 03 16:06:25 volumio volumio[1146]: info: Loading plugin "mpd"... Oct 03 16:06:25 volumio volumio-remote-updater[680]: [2026-10-03 16:06:25] [connect] Successful connection Oct 03 16:06:26 volumio volumio[1146]: info: Loading plugin "upnp_browser"... Oct 03 16:06:29 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 03 16:06:30 volumio volumio[1146]: info: Starting UPNP Browser Oct 03 16:06:30 volumio volumio[1146]: info: Loading plugin "alarm-clock"... Oct 03 16:06:30 volumio volumio[1146]: info: Loading plugin "airplay_emulation"... Oct 03 16:06:30 volumio volumio[1146]: info: Starting Shairport Sync Oct 03 16:06:30 volumio volumio[1146]: info: Loading plugin "last_100"... Oct 03 16:06:30 volumio volumio[1146]: info: Loading plugin "webradio"... Oct 03 16:06:30 volumio volumio[1146]: info: Loading plugin "i2s_dacs"... Oct 03 16:06:30 volumio volumio[1146]: info: Loading plugin "volumiodiscovery"... Oct 03 16:06:30 volumio volumio[1146]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 03 16:06:30 volumio volumio[1146]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 16:06:30 volumio volumio[1146]: *** WARNING *** For more information see Oct 03 16:06:30 volumio volumio[1146]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 03 16:06:30 volumio volumio[1146]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 16:06:30 volumio volumio[1146]: *** WARNING *** For more information see Oct 03 16:06:30 volumio node[1146]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 03 16:06:30 volumio node[1146]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 16:06:30 volumio node[1146]: *** WARNING *** For more information see Oct 03 16:06:30 volumio node[1146]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 03 16:06:30 volumio node[1146]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 03 16:06:30 volumio node[1146]: *** WARNING *** For more information see Oct 03 16:06:30 volumio volumio[1146]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 03 16:06:30 volumio volumio[1146]: info: Discovery: Started advertising with name: Volumio Oct 03 16:06:30 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 03 16:06:30 volumio volumio[1146]: info: Loading plugin "outputs"... Oct 03 16:06:30 volumio volumio[1146]: info: Loading plugin "albumart"... Oct 03 16:06:31 volumio volumio[1146]: info: Plugin example_plugin is not enabled Oct 03 16:06:31 volumio volumio[1146]: info: Loading plugin "inputs"... Oct 03 16:06:31 volumio volumio[1146]: info: Loading plugin "updater_comm"... Oct 03 16:06:31 volumio volumio[1146]: info: Plugin mpdemulation is not enabled Oct 03 16:06:31 volumio volumio[1146]: info: Loading plugin "rest_api"... Oct 03 16:06:31 volumio volumio[1146]: info: Loading plugin "websocket"... Oct 03 16:06:31 volumio dhcpcd[652]: timed out Oct 03 16:06:31 volumio sh[642]: timed out Oct 03 16:06:31 volumio volumio[1146]: info: Starting Socket.io Server version 1.7.4 Oct 03 16:06:31 volumio sh[594]: ifup: failed to bring up eth0 Oct 03 16:06:31 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 03 16:06:31 volumio volumio[1146]: info: Loading plugin "fusiondsp"... Oct 03 16:06:31 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 03 16:06:31 volumio dhcpcd[781]: control command: dhcpcd eth0 Oct 03 16:06:31 volumio ifplugd(eth0)[880]: client: sending commands to dhcpcd process Oct 03 16:06:31 volumio dhcpcd[781]: control_free: No such file or directory Oct 03 16:06:32 volumio ifplugd(eth0)[880]: Program executed successfully. Oct 03 16:06:33 volumio volumio[1237]: Forking 3 albumart workers Oct 03 16:06:34 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 03 16:06:34 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 16:06:34 volumio volumio[1146]: info: Applying required configuration parameters for plugin fusiondsp Oct 03 16:06:34 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 16:06:34 volumio upmpdcli[1337]: Could not open config: /tmp/upmpdcli.conf Oct 03 16:06:34 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 16:06:34 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 16:06:36 volumio volumio[1146]: info: Loading plugin "jp_radio"... Oct 03 16:06:39 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 03 16:06:40 volumio volumio-remote-updater[680]: [2026-10-03 16:06:40] [connect] Successful connection Oct 03 16:06:45 volumio volumio[1297]: Starting albumart workers Oct 03 16:06:47 volumio volumio[1296]: Starting albumart workers Oct 03 16:06:48 volumio volumio[1298]: Starting albumart workers Oct 03 16:06:48 volumio bash[1342]: setdatetime-helper: all HTTPS Date fallbacks failed Oct 03 16:06:48 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 03 16:06:48 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 03 16:06:48 volumio systemd[1]: setdatetime-helper.service: Consumed 1.721s CPU time. Oct 03 16:06:49 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 03 16:06:49 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 16:06:49 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 16:06:49 volumio upmpdcli[1404]: Could not open config: /tmp/upmpdcli.conf Oct 03 16:06:49 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 16:06:49 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 16:06:53 volumio volumio[1146]: info: [2026-10-03T07:06:53.975Z] [JP_Radio] [INFO] [IDX_I036] getConfigurationFiles Oct 03 16:06:54 volumio volumio[1146]: info: [2026-10-03T07:06:54.014Z] [JP_Radio] [INFO] [IDX_I035] onVolumioStart Oct 03 16:06:54 volumio volumio[1146]: info: Loading i18n strings for locale ja Oct 03 16:06:54 volumio volumio[1146]: Updating browse sources language Oct 03 16:06:54 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 16:06:55 volumio volumio-remote-updater[680]: [2026-10-03 16:06:55] [connect] Successful connection Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::initPlayerControls Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 16:06:56 volumio volumio[1146]: Express server listening on port 3000 Oct 03 16:06:56 volumio volumio[1146]: [Metrics] WebUI: 39s 612.13ms Oct 03 16:06:56 volumio volumio[1146]: info: CoreStateMachine::resetVolumioState Oct 03 16:06:56 volumio volumio[1146]: info: CoreStateMachine::getcurrentVolume Oct 03 16:06:56 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 16:06:56 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:56 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:56 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 03 16:06:56 volumio sudo[1442]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 16:06:56 volumio sudo[1442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:57 volumio sudo[1442]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:57 volumio sudo[1444]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 16:06:57 volumio sudo[1444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:57 volumio sudo[1444]: pam_unix(sudo:session): session closed for user root Oct 03 16:06:57 volumio volumio[1146]: info: Volumio Network Manager: Network status updated: 0 Oct 03 16:06:57 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:57 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:57 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 03 16:06:58 volumio volumio[1146]: info: VolumeController:: Volume=100 Mute =true Oct 03 16:06:58 volumio volumio[1146]: info: CoreStateMachine::pushState Oct 03 16:06:58 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Oct 03 16:06:58 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 16:06:58 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Oct 03 16:06:58 volumio volumio[1146]: info: CoreStateMachine::updateTrackBlock Oct 03 16:06:58 volumio volumio[1146]: info: CorePlayQueue::getTrackBlock Oct 03 16:06:58 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 16:06:58 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:58 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:58 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 03 16:06:58 volumio volumio[1146]: info: Reloading queue from file Oct 03 16:06:58 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 16:06:58 volumio volumio-remote-updater[680]: [2026-10-03 16:06:58] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1791011215 101 Oct 03 16:06:58 volumio volumio[1146]: 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: 1 Oct 03 16:06:58 volumio volumio[1146]: info: CoreStateMachine::setRepeat true single undefined Oct 03 16:06:58 volumio volumio[1146]: info: CoreStateMachine::pushState Oct 03 16:06:58 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Oct 03 16:06:58 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 16:06:58 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Oct 03 16:06:58 volumio volumio[1146]: info: CoreStateMachine::setRandom null Oct 03 16:06:58 volumio volumio[1146]: info: CoreStateMachine::pushState Oct 03 16:06:58 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Oct 03 16:06:58 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Oct 03 16:06:59 volumio volumio[1146]: info: Setting Device type: Raspberry PI Oct 03 16:06:59 volumio volumio[1146]: info: Completed loading Core Plugins Oct 03 16:06:59 volumio volumio[1146]: info: Preparing to generate the ALSA configuration file Oct 03 16:06:59 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:59 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 03 16:06:59 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Oct 03 16:06:59 volumio volumio[1146]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 03 16:06:59 volumio volumio[1146]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Oct 03 16:06:59 volumio volumio[1146]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 03 16:06:59 volumio volumio[1146]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 03 16:06:59 volumio volumio[1146]: info: Reading ALSA contributions from plugins. Oct 03 16:06:59 volumio sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 03 16:06:59 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:06:59 volumio volumio[1146]: info: Discovery: adding 36478e77-76b0-4833-b060-240676ef0cc1 Oct 03 16:06:59 volumio volumio[1146]: info: Discovery: Found device Volumio Oct 03 16:06:59 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Oct 03 16:06:59 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Oct 03 16:06:59 volumio volumio[1146]: info: VolumeController:: Volume=100 Mute =true Oct 03 16:06:59 volumio volumio[1146]: info: CoreStateMachine::pushState Oct 03 16:06:59 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Oct 03 16:06:59 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Oct 03 16:06:59 volumio volumio[1146]: info: Asound.conf file unchanged, so no further update is needed Oct 03 16:06:59 volumio volumio[1146]: info: Output device has changed, restarting MPD Oct 03 16:07:00 volumio volumio[1146]: info: Output device has changed, restarting Shairport Sync Oct 03 16:07:00 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:07:00 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 16:07:00 volumio sudo[1464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 16:07:00 volumio sudo[1464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:07:00 volumio sudo[1464]: pam_unix(sudo:session): session closed for user root Oct 03 16:07:00 volumio sudo[1466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 16:07:00 volumio sudo[1466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:07:00 volumio volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 16:07:00 volumio volumio[1146]: info: ___________ START PLUGINS ___________ Oct 03 16:07:00 volumio volumio[1146]: info: ControllerMpd::onStart: Initializing MPD Oct 03 16:07:00 volumio volumio[1146]: info: Creating MPD Configuration file Oct 03 16:07:00 volumio sudo[1474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 03 16:07:00 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:07:00 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 16:07:00 volumio volumio[1146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 16:07:00 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 16:07:00 volumio volumio[1146]: info: [1791011220683] CoreMusicLibrary::Adding element DLNAサーバー Oct 03 16:07:00 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 16:07:00 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 16:07:00 volumio volumio[1146]: info: UPNP Browser: Client initialized successfully Oct 03 16:07:00 volumio sudo[1477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 16:07:00 volumio sudo[1477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:07:00 volumio sudo[1479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 16:07:00 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:07:00 volumio sudo[1477]: pam_unix(sudo:session): session closed for user root Oct 03 16:07:00 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:07:00 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 16:07:01 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 16:07:01 volumio sudo[1481]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 16:07:01 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 16:07:01 volumio sudo[1487]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 16:07:01 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Oct 03 16:07:01 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Oct 03 16:07:01 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 03 16:07:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 16:07:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 03 16:07:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 16:07:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 16:07:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 16:07:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 16:07:01 volumio volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 16:07:01 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:07:01 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 16:07:01 volumio volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 16:07:01 volumio sudo[1497]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 16:07:01 volumio sudo[1497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 16:07:01 volumio volumio[1146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 16:07:01 volumio volumio[1146]: info: [1791011221824] CoreMusicLibrary::Adding element Last_100 Oct 03 16:07:01 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 16:07:01 volumio volumio[1146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 16:07:01 volumio volumio[1146]: info: [1791011221830] CoreMusicLibrary::Adding element Webradio Oct 03 16:07:01 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 16:07:01 volumio sudo[1502]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 03 16:07:01 volumio sudo[1497]: pam_unix(sudo:session): session closed for user root Oct 03 16:07:01 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 16:07:01 volumio volumio[1146]: info: Initializing BBC Radios Oct 03 16:07:02 volumio volumio5-onboarding[1485]: time=2026-10-03T16:07:02.066+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 03 16:07:02 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 16:07:02 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 16:07:02 volumio volumio[1146]: info: FusionDsp - CamillaDSP binary found. Oct 03 16:07:02 volumio volumio[1146]: info: Loading i18n strings for locale ja Oct 03 16:07:03 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 03 16:07:03 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:07:03 volumio volumio[1146]: info: FusionDsp - mixtype--------------------- Hardware Oct 03 16:07:03 volumio volumio[1146]: info: [2026-10-03T07:07:03.098Z] [JP_Radio] [INFO] [IDX_I001] onStart: START Oct 03 16:07:03 volumio volumio[1146]: info: [2026-10-03T07:07:03.531Z] [JP_Radio] [INFO] [RCT_I001] setupRoutes Oct 03 16:07:03 volumio volumio[1146]: info: [2026-10-03T07:07:03.597Z] [JP_Radio] [INFO] [RCT_I008] start Oct 03 16:07:03 volumio volumio[1146]: info: [2026-10-03T07:07:03.770Z] [JP_Radio] [INFO] [RCT_I011] init Oct 03 16:07:03 volumio volumio[1146]: info: [2026-10-03T07:07:03.805Z] [JP_Radio] [INFO] [RDK_I001] Attempting login Oct 03 16:07:03 volumio volumio[1146]: info: [2026-10-03T07:07:03.807Z] [JP_Radio] [INFO] [RDK_I003] checkLogin Oct 03 16:07:04 volumio volumio[1146]: info: [2026-10-03T07:07:04.198Z] [JP_Radio] [INFO] [IDX_I003] onStart: EXIT Oct 03 16:07:04 volumio volumio[1146]: info: Volumio Calling Home Oct 03 16:07:04 volumio volumio[1146]: info: Preparing to generate the ALSA configuration file Oct 03 16:07:05 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Oct 03 16:07:05 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 16:07:05 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 16:07:05 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root Oct 03 16:07:05 volumio volumio[1146]: info: [2026-10-03T07:07:05.747Z] [JP_Radio] [INFO] [RCT_I010] Listening on port 9000 Oct 03 16:07:05 volumio volumio[1146]: info: CoreCommandRouter::servicePushState Oct 03 16:07:05 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Oct 03 16:07:05 volumio volumio[1146]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received undefined Oct 03 16:07:12 volumio volumio[1146]: info: [2026-10-03T07:07:12.016Z] [JP_Radio] [INFO] [IDX_I006] addToBrowseSources: pluginName=jp_radio Oct 03 16:07:12 volumio volumio[1146]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 03 16:07:12 volumio volumio[1146]: info: [1791011232018] CoreMusicLibrary::Adding element RADIKO Oct 03 16:07:12 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 16:07:12 volumio volumio[1146]: Cannot find translation for source RADIKO Oct 03 16:07:12 volumio volumio5-onboarding[1485]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:48300->127.0.0.1:3000: i/o timeout Oct 03 16:07:12 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 03 16:07:12 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 03 16:07:12 volumio volumio[1146]: info: [2026-10-03T07:07:12.151Z] [JP_Radio] [INFO] [IDX_I002] onStart: COMPLETE Oct 03 16:07:12 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 03 16:07:12 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 16:07:12 volumio dhcpcd[781]: eth0: offered 192.168.0.20 from 192.168.0.1 Oct 03 16:07:12 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 16:07:12 volumio volumio[1146]: Unhandled rejection Error: No sockets available, cannot start. Oct 03 16:07:12 volumio volumio[1146]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Oct 03 16:07:12 volumio volumio[1146]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Oct 03 16:07:12 volumio volumio[1146]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Oct 03 16:07:12 volumio volumio[1146]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Oct 03 16:07:12 volumio volumio[1146]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Oct 03 16:07:12 volumio volumio[1146]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Oct 03 16:07:12 volumio volumio[1146]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Oct 03 16:07:12 volumio volumio[1146]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Oct 03 16:07:12 volumio volumio[1146]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Oct 03 16:07:12 volumio volumio[1146]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Oct 03 16:07:12 volumio volumio[1146]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Oct 03 16:07:12 volumio volumio[1146]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 03 16:07:12 volumio volumio[1146]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 03 16:07:12 volumio volumio[1146]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Oct 03 16:07:12 volumio volumio[1146]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Oct 03 16:07:12 volumio volumio[1146]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 03 16:07:12 volumio volumio5-onboarding[1545]: time=2026-10-03T16:07:12.732+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 03 16:07:13 volumio dhcpcd[781]: eth0: probing address 192.168.0.20/24 Oct 03 16:07:13 volumio volumio[1146]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 03 16:07:13 volumio mpd[1503]: 2026-10-03T16:07:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 03 16:07:14 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 03 16:07:14 volumio volumio[1146]: info: camilladsp service started and running in background, instance 1 Oct 03 16:07:14 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 16:07:14 volumio sudo[1466]: pam_unix(sudo:session): session closed for user root Oct 03 16:07:14 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root Oct 03 16:07:14 volumio volumio[1146]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Oct 03 16:07:14 volumio volumio[1146]: info: FusionDsp loaded Oct 03 16:07:14 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 16:07:14 volumio volumio[1146]: info: FusionDsp - --[object Object] Oct 03 16:07:14 volumio volumio[1146]: error: FusionDsp - Socket connection not established Oct 03 16:07:14 volumio volumio[1146]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 03 16:07:14 volumio volumio[1146]: info: Adding Signal Path Element [object Object] Oct 03 16:07:14 volumio volumio[1146]: info: Adding fusiondspeq DSP Signal Path Element Oct 03 16:07:14 volumio volumio[1146]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Oct 03 16:07:14 volumio volumio[1146]: info: FusionDsp - PEQ graph server listening on port 10015 Oct 03 16:07:14 volumio volumio[1146]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 03 16:07:14 volumio volumio[1146]: info: Reading ALSA contributions from plugins. Oct 03 16:07:15 volumio sudo[1570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 03 16:07:15 volumio sudo[1570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 16:07:15 volumio volumio[1146]: error: [2026-10-03T07:07:15.252Z] [JP_Radio] [ERROR] [RDK_E002] premium account login check error: TypeError: fetch failed Oct 03 16:07:15 volumio volumio[1146]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Oct 03 16:07:15 volumio volumio[1146]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 03 16:07:15 volumio volumio[1146]: at async request (/data/plugins/music_service/jp_radio/dist/lib/utils/http-client.js:54:21) Oct 03 16:07:15 volumio volumio[1146]: at async Radiko.checkLogin (/data/plugins/music_service/jp_radio/dist/lib/services/radiko-service.js:134:30) Oct 03 16:07:15 volumio volumio[1146]: at async Radiko.init (/data/plugins/music_service/jp_radio/dist/lib/services/radiko-service.js:79:27) Oct 03 16:07:15 volumio volumio[1146]: at async #init (/data/plugins/music_service/jp_radio/dist/lib/controllers/radio-controller.js:1074:13) Oct 03 16:07:15 volumio volumio[1146]: info: [2026-10-03T07:07:15.254Z] [JP_Radio] [INFO] [RDK_I002] login Oct 03 16:07:15 volumio systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 03 16:07:15 volumio volumio[1146]: info: MPD Permissions set Oct 03 16:07:15 volumio volumio[1146]: info: Completed starting Core Plugins Oct 03 16:07:15 volumio volumio[1146]: info: ------------------------------------------- Oct 03 16:07:15 volumio volumio[1146]: info: ----- MyVolumio plugins startup ---- Oct 03 16:07:15 volumio volumio[1146]: info: ------------------------------------------- Oct 03 16:07:15 volumio volumio[1146]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 03 16:07:16 volumio volumio[1146]: info: MPD Permissions set Oct 03 16:07:16 volumio volumio[1146]: info: Upmpdcli Daemon Started Oct 03 16:07:16 volumio volumio[1146]: error: FusionDsp - Reload WebSocket error: [object Object] Oct 03 16:07:16 volumio volumio[1146]: error: [2026-10-03T07:07:16.293Z] [JP_Radio] [ERROR] [RDK_E001] Login failed: TypeError: fetch failed Oct 03 16:07:16 volumio volumio[1146]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Oct 03 16:07:16 volumio volumio[1146]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 03 16:07:16 volumio volumio[1146]: at async request (/data/plugins/music_service/jp_radio/dist/lib/utils/http-client.js:54:21) Oct 03 16:07:16 volumio volumio[1146]: at async Radiko.login (/data/plugins/music_service/jp_radio/dist/lib/services/radiko-service.js:114:13) Oct 03 16:07:16 volumio volumio[1146]: at async Radiko.init (/data/plugins/music_service/jp_radio/dist/lib/services/radiko-service.js:81:34) Oct 03 16:07:16 volumio volumio[1146]: at async #init (/data/plugins/music_service/jp_radio/dist/lib/controllers/radio-controller.js:1074:13) Oct 03 16:07:16 volumio volumio[1146]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 16:07:16 volumio volumio[1146]: TypeError: fetch failed Oct 03 16:07:16 volumio volumio[1146]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Oct 03 16:07:16 volumio volumio[1146]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 03 16:07:16 volumio volumio[1146]: at async request (/data/plugins/music_service/jp_radio/dist/lib/utils/http-client.js:54:21) Oct 03 16:07:16 volumio volumio[1146]: at async Radiko.login (/data/plugins/music_service/jp_radio/dist/lib/services/radiko-service.js:114:13) Oct 03 16:07:16 volumio volumio[1146]: at async Radiko.init (/data/plugins/music_service/jp_radio/dist/lib/services/radiko-service.js:81:34) Oct 03 16:07:16 volumio volumio[1146]: at async #init (/data/plugins/music_service/jp_radio/dist/lib/controllers/radio-controller.js:1074:13) { Oct 03 16:07:16 volumio volumio[1146]: cause: Error: getaddrinfo EBUSY radiko.jp Oct 03 16:07:16 volumio volumio[1146]: at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:118:26) { Oct 03 16:07:16 volumio volumio[1146]: errno: -16, Oct 03 16:07:16 volumio volumio[1146]: code: 'EBUSY', Oct 03 16:07:16 volumio volumio[1146]: syscall: 'getaddrinfo', Oct 03 16:07:16 volumio volumio[1146]: hostname: 'radiko.jp' Oct 03 16:07:16 volumio volumio[1146]: } Oct 03 16:07:16 volumio volumio[1146]: } Oct 03 16:07:16 volumio volumio[1146]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 16:07:18 volumio dhcpcd[781]: eth0: leased 192.168.0.20 for 86400 seconds Oct 03 16:07:18 volumio dhcpcd[781]: eth0: adding route to 192.168.0.0/24 Oct 03 16:07:18 volumio dhcpcd[781]: eth0: adding default route via 192.168.0.1 Oct 03 16:07:18 volumio avahi-daemon[665]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.20. Oct 03 16:07:18 volumio avahi-daemon[665]: New relevant interface eth0.IPv4 for mDNS. Oct 03 16:07:18 volumio avahi-daemon[665]: Registering new address record for 192.168.0.20 on eth0.IPv4. Oct 03 16:07:18 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 03 16:07:18 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 03 16:07:18 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 03 16:07:18 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 03 16:07:18 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. Oct 03 16:07:18 volumio welcome[1613]: Resolved ip:[1] 192.168.0.20 Oct 03 16:07:18 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... Oct 03 16:07:18 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 03 16:07:18 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 03 16:07:19 volumio ntpd[861]: IO: Listen normally on 3 eth0 192.168.0.20:123 Oct 03 16:07:19 volumio ntpd[861]: IO: new interface(s) found: waking up resolver Oct 03 16:07:19 volumio ntpd[861]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 16:07:20 volumio winbindd[1629]: [2026/10/03 16:07:20.503746, 0] ../../source3/winbindd/winbindd.c:1440(main) Oct 03 16:07:20 volumio winbindd[1629]: winbindd version 4.17.8-Raspbian started. Oct 03 16:07:20 volumio winbindd[1629]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 03 16:07:20 volumio winbindd[1629]: [2026/10/03 16:07:20.574078, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Oct 03 16:07:20 volumio winbindd[1629]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 03 16:07:20 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 03 16:07:20 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. Oct 03 16:07:20 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... Oct 03 16:07:20 volumio sudo[1570]: pam_unix(sudo:session): session closed for user root Oct 03 16:07:22 volumio smbd[1639]: [2026/10/03 16:07:22.180904, 0] ../../source3/smbd/server.c:1741(main) Oct 03 16:07:22 volumio smbd[1639]: smbd version 4.17.8-Raspbian started. Oct 03 16:07:22 volumio smbd[1639]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 03 16:07:23 volumio volumio5-onboarding[1545]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:46516->127.0.0.1:3000: i/o timeout Oct 03 16:07:23 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 03 16:07:23 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 03 16:07:23 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Oct 03 16:07:23 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 16:07:23 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 03 16:07:24 volumio volumio5-onboarding[1656]: time=2026-10-03T16:07:24.171+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 03 16:07:25 volumio ntpd[861]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 03 16:07:25 volumio ntpd[861]: DNS: Pool taking: 203.141.133.38 Oct 03 16:07:25 volumio ntpd[861]: DNS: Pool taking: 142.91.108.61 Oct 03 16:07:25 volumio ntpd[861]: DNS: Pool taking: 129.250.35.250 Oct 03 16:07:25 volumio ntpd[861]: DNS: Pool taking: 152.70.97.72 Oct 03 16:07:25 volumio ntpd[861]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 03 16:07:25 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 03 16:07:25 volumio systemd[1]: Reached target multi-user.target - Multi-User System. Oct 03 16:07:25 volumio systemd[1]: Reached target graphical.target - Graphical Interface. Oct 03 16:07:25 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 03 16:07:25 volumio ntpd[861]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 16:07:26 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 03 16:07:26 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 03 16:07:26 volumio systemd[1]: Startup finished in 19.495s (kernel) + 1min 31.348s (userspace) = 1min 50.843s. Oct 15 04:58:17 volumio ntpd[861]: CLOCK: time stepped by 996646.717521 Oct 15 04:58:17 volumio ntpd[861]: CLOCK: time changed from 2026-10-03 to 2026-10-15 Oct 15 04:58:17 volumio ntpd[861]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 15 04:58:17 volumio sudo[1687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 16:06' Oct 15 04:58:17 volumio ntpd[861]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 15 04:58:17 volumio ntpd[861]: DNS: Pool taking: 140.245.90.15 Oct 15 04:58:17 volumio ntpd[861]: DNS: Pool taking: 43.229.16.158 Oct 15 04:58:17 volumio ntpd[861]: DNS: Pool taking: 162.159.200.123 Oct 15 04:58:17 volumio ntpd[861]: DNS: Pool taking: 8.209.206.137 Oct 15 04:58:17 volumio ntpd[861]: DNS: Pool taking: 240b:4009:23a:c064:fe41:d658:e951:c18f Oct 15 04:58:17 volumio ntpd[861]: DNS: Pool taking: 2406:8c00:0:3471:133:18:147:168 Oct 15 04:58:17 volumio ntpd[861]: DNS: Pool taking: 2606:4700:f1::123 Oct 15 04:58:17 volumio ntpd[861]: DNS: Pool taking: 2a06:a880:3:4915::123 Oct 15 04:58:17 volumio ntpd[861]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 15 04:58:17 volumio sudo[1687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 04:58:17 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 15 04:58:17 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Oct 15 04:58:17 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 15 04:58:17 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Oct 15 04:58:17 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Oct 15 04:58:17 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 15 04:58:18 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 15 04:58:18 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 15 04:58:18 volumio ntpd[861]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"