2月 25 00:00:00 volumio systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. 2月 25 00:00:00 volumio systemd[1]: Expecting device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0... 2月 25 00:00:00 volumio systemd[1]: Reached target rpc_pipefs.target. 2月 25 00:00: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). 2月 25 00:00:00 volumio systemd[1]: Reached target nfs-client.target - NFS client services. 2月 25 00:00:00 volumio systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). 2月 25 00:00:00 volumio systemd[1]: Reached target sysinit.target - System Initialization. 2月 25 00:00:00 volumio systemd[1]: Started apt-daily.timer - Daily apt download activities. 2月 25 00:00:00 volumio systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. 2月 25 00:00:00 volumio systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. 2月 25 00:00:00 volumio systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. 2月 25 00:00:00 volumio systemd[1]: Started exim4-base.timer - Daily exim4-base housekeeping. 2月 25 00:00:00 volumio systemd[1]: Started fstrim.timer - Discard unused blocks once a week. 2月 25 00:00:00 volumio systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. 2月 25 00:00:00 volumio systemd[1]: Started phpsessionclean.timer - Clean PHP session files every 30 mins. 2月 25 00:00:00 volumio systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. 2月 25 00:00:00 volumio systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. 2月 25 00:00:00 volumio systemd[1]: Reached target timers.target - Timer Units. 2月 25 00:00:00 volumio systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. 2月 25 00:00:00 volumio systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. 2月 25 00:00:00 volumio systemd[1]: Listening on pcscd.socket - PC/SC Smart Card Daemon Activation Socket. 2月 25 00:00:00 volumio systemd[1]: Reached target sockets.target - Socket Units. 2月 25 00:00: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). 2月 25 00:00:00 volumio systemd[1]: Reached target basic.target - Basic System. 2月 25 00:00: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). 2月 25 00:00:00 volumio systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... 2月 25 00:00:00 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... 2月 25 00:00:00 volumio systemd[1]: Starting bluetooth.service - Bluetooth service... 2月 25 00:00:00 volumio systemd[1]: Starting dbus.service - D-Bus System Message Bus... 2月 25 00:00:00 volumio haveged[707]: haveged: command socket is listening at fd 3 2月 25 00:00:00 volumio systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... 2月 25 00:00: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). 2月 25 00:00:00 volumio systemd[1]: Started iptables.service - Volumio Iptables Module. 2月 25 00:00:00 volumio systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... 2月 25 00:00:00 volumio systemd[1]: Starting systemd-logind.service - User Login Management... 2月 25 00:00:00 volumio systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. 2月 25 00:00:00 volumio systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... 2月 25 00:00:00 volumio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... 2月 25 00:00:00 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. 2月 25 00:00:00 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. 2月 25 00:00:00 volumio systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). 2月 25 00:00:00 volumio systemd[1]: Starting wireless.service - Wireless Services... 2月 25 00:00:00 volumio systemd[1]: Started rpcbind.service - RPC bind portmap service. 2月 25 00:00:00 volumio systemd[1]: Found device sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0. 2月 25 00:00:00 volumio thd[729]: Unable to parse trigger line: 2月 25 00:00:00 volumio thd[729]: Unable to parse trigger line: 2月 25 00:00:00 volumio avahi-daemon[719]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). 2月 25 00:00:00 volumio avahi-daemon[719]: Successfully dropped root privileges. 2月 25 00:00:00 volumio systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. 2月 25 00:00:00 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. 2月 25 00:00:00 volumio systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. 2月 25 00:00:00 volumio systemd[1]: Reached target remote-fs.target - Remote File Systems. 2月 25 00:00:00 volumio systemd[1]: Reached target rpcbind.target - RPC Port Mapper. 2月 25 00:00:00 volumio avahi-daemon[719]: avahi-daemon 0.8 starting up. 2月 25 00:00:00 volumio systemd[1]: Starting atd.service - Deferred execution scheduler... 2月 25 00:00:00 volumio systemd[1]: Started cron.service - Regular background program processing daemon. 2月 25 00:00:00 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... 2月 25 00:00: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). 2月 25 00:00:00 volumio cron[741]: (CRON) INFO (pidfile fd = 3) 2月 25 00:00:00 volumio systemd[1]: Started ifup@wlan0.service - ifup for wlan0. 2月 25 00:00:00 volumio systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... 2月 25 00:00:00 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). 2月 25 00:00:00 volumio systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... 2月 25 00:00:00 volumio systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... 2月 25 00:00:00 volumio rpi-eeprom-update[724]: Device does not a have a Raspberry Pi bootloader EEPROM (e.g. Pi 4 or Pi 5). Skipping bootloader update. 2月 25 00:00:00 volumio systemd[1]: Starting exim4-base.service - exim4-base housekeeping... 2月 25 00:00:00 volumio systemd[1]: e2scrub_reap.service: Deactivated successfully. 2月 25 00:00:00 volumio systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. 2月 25 00:00:00 volumio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 2月 25 00:00:00 volumio systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. 2月 25 00:00:00 volumio th-udev-rebind.sh[748]: Rebinding /dev/input/event0 to thd using --add... 2月 25 00:00:00 volumio volumio_rfkill_unblock.sh[751]: Volumio WiFi Soft Blocked check script 2月 25 00:00:00 volumio volumio_rfkill_unblock.sh[751]: Check if rfkill is available 2月 25 00:00:00 volumio systemd[1]: Started atd.service - Deferred execution scheduler. 2月 25 00:00:00 volumio cron[741]: (CRON) INFO (Running @reboot jobs) 2月 25 00:00:00 volumio volumio_rfkill_unblock.sh[751]: Check if rfkill listed devices are already unblocked 2月 25 00:00:00 volumio alsactl[763]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 2月 25 00:00:00 volumio alsactl[763]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 2月 25 00:00:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled 2月 25 00:00:00 volumio systemd[1]: th-udev-rebind.service: Deactivated successfully. 2月 25 00:00:00 volumio systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. 2月 25 00:00:00 volumio systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. 2月 25 00:00:00 volumio systemd[1]: Reached target sound.target - Sound Card. 2月 25 00:00:00 volumio sh[784]: eth0=eth0 2月 25 00:00:00 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_lookup:1815: inode #615104: comm atd: deleted inode referenced: 616545 2月 25 00:00:00 volumio systemd-logind[727]: New seat seat0. 2月 25 00:00:01 volumio volumio_rfkill_unblock.sh[751]: Wi-Fi is already unblocked. 2月 25 00:00:01 volumio systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. 2月 25 00:00:01 volumio systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... 2月 25 00:00:01 volumio sh[811]: wlan0=wlan0 2月 25 00:00:01 volumio volumio-remote-updater[731]: Volumio OTA Client: 1.7.0 2月 25 00:00:01 volumio volumio-remote-updater[731]: Error: No active session 2月 25 00:00:01 volumio volumio-remote-updater[731]: [2026-02-25 00:00:01] [info] asio async_connect error: asio.system:111 (Connection refused) 2月 25 00:00:01 volumio volumio-remote-updater[731]: [2026-02-25 00:00:01] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) 2月 25 00:00:01 volumio volumio-remote-updater[731]: [2026-02-25 00:00:01] [error] handle_connect error: Connection refused 2月 25 00:00:01 volumio haveged[707]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K 2月 25 00:00:01 volumio haveged[707]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14748/63356 2月 25 00:00:01 volumio haveged[707]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0019 2月 25 00:00:01 volumio haveged[707]: haveged: fills: 0, generated: 0 2月 25 00:00:01 volumio dhcpcd[807]: dhcpcd-9.4.1 starting 2月 25 00:00:01 volumio systemd[1]: exim4-base.service: Deactivated successfully. 2月 25 00:00:01 volumio systemd[1]: Finished exim4-base.service - exim4-base housekeeping. 2月 25 00:00:01 volumio dhcpcd[824]: dev: loaded udev 2月 25 00:00:01 volumio (udev-worker)[464]: pcmC5D0p: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. 2月 25 00:00:01 volumio dhcpcd[824]: DUID 00:01:00:01:30:c6:00:29:b8:27:eb:00:e7:96 2月 25 00:00:01 volumio bluetoothd[720]: Bluetooth daemon 5.83 2月 25 00:00:01 volumio systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. 2月 25 00:00:01 volumio upmpdcli[730]: Could not open config: /tmp/upmpdcli.conf 2月 25 00:00:01 volumio systemd[1]: Starting networking.service - Raise network interfaces... 2月 25 00:00:01 volumio systemd[1]: Started dbus.service - D-Bus System Message Bus. 2月 25 00:00:01 volumio avahi-daemon[719]: Successfully called chroot(). 2月 25 00:00:01 volumio avahi-daemon[719]: Successfully dropped remaining capabilities. 2月 25 00:00:01 volumio bluetoothd[720]: Starting SDP server 2月 25 00:00:01 volumio bluetoothd[720]: Excluding (cli) sap 2月 25 00:00:01 volumio bluetoothd[720]: Excluding (cli) midi 2月 25 00:00:01 volumio bluetoothd[720]: Excluding (cli) battery 2月 25 00:00:01 volumio avahi-daemon[719]: No service file found in /etc/avahi/services. 2月 25 00:00:01 volumio avahi-daemon[719]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. 2月 25 00:00:01 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 2月 25 00:00:01 volumio avahi-daemon[719]: New relevant interface lo.IPv4 for mDNS. 2月 25 00:00:01 volumio avahi-daemon[719]: Network interface enumeration completed. 2月 25 00:00:01 volumio avahi-daemon[719]: Registering new address record for 127.0.0.1 on lo.IPv4. 2月 25 00:00:01 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE 2月 25 00:00:01 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. 2月 25 00:00:02 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. 2月 25 00:00:02 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. 2月 25 00:00:02 volumio systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. 2月 25 00:00:02 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 2月 25 00:00:02 volumio kernel: Bluetooth: BNEP filters: protocol multicast 2月 25 00:00:02 volumio kernel: Bluetooth: BNEP socket layer initialized 2月 25 00:00:02 volumio systemd[1]: iptables.service: Deactivated successfully. 2月 25 00:00:02 volumio systemd[1]: Starting wpa_supplicant.service - WPA supplicant... 2月 25 00:00:02 volumio dbus-daemon[721]: [system] Successfully activated service 'org.freedesktop.systemd1' 2月 25 00:00:02 volumio systemd[1]: Started systemd-logind.service - User Login Management. 2月 25 00:00:02 volumio systemd[1]: Started bluetooth.service - Bluetooth service. 2月 25 00:00:02 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. 2月 25 00:00:02 volumio loadcpufreq[745]: Loading cpufreq kernel modules...done (none). 2月 25 00:00:02 volumio systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. 2月 25 00:00:02 volumio dbus-daemon[721]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=0 pid=720 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") 2月 25 00:00:02 volumio bluetoothd[720]: Bluetooth management interface 1.23 initialized 2月 25 00:00:02 volumio bluetoothd[720]: Battery Provider Manager created 2月 25 00:00:02 volumio kernel: Bluetooth: MGMT ver 1.23 2月 25 00:00:02 volumio systemd[1]: Starting bluealsa.service - BlueALSA service... 2月 25 00:00:02 volumio systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... 2月 25 00:00:02 volumio kernel: NET: Registered PF_ALG protocol family 2月 25 00:00:02 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... 2月 25 00:00:02 volumio kernel: cryptd: max_cpu_qlen set to 1000 2月 25 00:00:02 volumio systemd[1]: Started wpa_supplicant.service - WPA supplicant. 2月 25 00:00:02 volumio wpa_supplicant[870]: Successfully initialized wpa_supplicant 2月 25 00:00:02 volumio dhcpcd[824]: eth0: waiting for carrier 2月 25 00:00:02 volumio dhcpcd[824]: wlan0: waiting for carrier 2月 25 00:00:02 volumio cpufrequtils[876]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. 2月 25 00:00:02 volumio systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. 2月 25 00:00:02 volumio systemd[1]: Finished networking.service - Raise network interfaces. 2月 25 00:00:02 volumio dbus-daemon[721]: [system] Successfully activated service 'org.freedesktop.hostname1' 2月 25 00:00:02 volumio systemd[1]: Reached target network.target - Network. 2月 25 00:00:02 volumio systemd[1]: Reached target network-online.target - Network is Online. 2月 25 00:00:02 volumio systemd[1]: Starting apache2.service - The Apache HTTP Server... 2月 25 00:00:02 volumio systemd[1]: Starting exim4.service - LSB: exim Mail Transport Agent... 2月 25 00:00:02 volumio avahi-daemon[719]: Server startup complete. Host name is volumio.local. Local service cookie is 2681890224. 2月 25 00:00:02 volumio systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... 2月 25 00:00:02 volumio systemd[1]: Starting lighttpd.service - Lighttpd Daemon... 2月 25 00:00:02 volumio systemd[1]: Starting nmbd.service - Samba NMB Daemon... 2月 25 00:00:02 volumio systemd[1]: Starting ntpsec.service - Network Time Service... 2月 25 00:00:02 volumio systemd[1]: Starting openvpn.service - OpenVPN service... 2月 25 00:00:02 volumio systemd[1]: Starting openvpn@myvpn.service - OpenVPN connection to myvpn... 2月 25 00:00:02 volumio systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... 2月 25 00:00:02 volumio systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... 2月 25 00:00:02 volumio systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... 2月 25 00:00:02 volumio bluetoothd[720]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled 2月 25 00:00:02 volumio bluetoothd[720]: bap: Operation not supported (95) 2月 25 00:00:02 volumio bluetoothd[720]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 2月 25 00:00:02 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... 2月 25 00:00:02 volumio sm-notify[982]: Version 2.6.2 starting 2月 25 00:00:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 2月 25 00:00:03 volumio ntpd[997]: INIT: ntpd ntpsec-1.2.2: Starting 2月 25 00:00:03 volumio ntp-systemd-wrapper[997]: 2026-02-25T00:00:03 ntpd[997]: INIT: ntpd ntpsec-1.2.2: Starting 2月 25 00:00:03 volumio ntp-systemd-wrapper[997]: 2026-02-25T00:00:03 ntpd[997]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec 2月 25 00:00:03 volumio ntpd[997]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec 2月 25 00:00:03 volumio ntpd[1012]: INIT: precision = 0.833 usec (-20) 2月 25 00:00:03 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... 2月 25 00:00:03 volumio ntpd[1012]: INIT: successfully locked into RAM 2月 25 00:00:03 volumio systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. 2月 25 00:00:03 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... 2月 25 00:00:03 volumio systemd[1]: Started ntpsec.service - Network Time Service. 2月 25 00:00:03 volumio systemd[1]: Finished openvpn.service - OpenVPN service. 2月 25 00:00:03 volumio systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. 2月 25 00:00:03 volumio systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. 2月 25 00:00:03 volumio ntpd[1012]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf 2月 25 00:00:03 volumio ntpd[1012]: CONFIG: restrict nopeer ignored 2月 25 00:00:03 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. 2月 25 00:00:03 volumio ovpn-myvpn[978]: DEPRECATED OPTION: --cipher set to 'AES-128-CBC' but missing in --data-ciphers (AES-256-GCM:AES-128-GCM:CHACHA20-POLY1305). OpenVPN ignores --cipher for cipher negotiations. 2月 25 00:00:03 volumio ntpd[1012]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature 2月 25 00:00:03 volumio ntpd[1012]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 2月 25 00:00:03 volumio ntpd[1012]: INIT: Using SO_TIMESTAMPNS(ns) 2月 25 00:00:03 volumio ntpd[1012]: IO: Listen and drop on 0 v6wildcard [::]:123 2月 25 00:00:03 volumio ntpd[1012]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 2月 25 00:00:03 volumio ntpd[1012]: IO: Listen normally on 2 lo 127.0.0.1:123 2月 25 00:00:03 volumio ntpd[1012]: IO: Listening on routing socket on fd #19 for interface updates 2月 25 00:00:03 volumio ovpn-myvpn[978]: Note: Kernel support for ovpn-dco missing, disabling data channel offload. 2月 25 00:00:03 volumio ovpn-myvpn[978]: OpenVPN 2.6.3 arm-unknown-linux-gnueabihf [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] [DCO] 2月 25 00:00:03 volumio ovpn-myvpn[978]: library versions: OpenSSL 3.0.18 30 Sep 2025, LZO 2.10 2月 25 00:00:03 volumio ovpn-myvpn[978]: DCO version: N/A 2月 25 00:00:03 volumio ovpn-myvpn[978]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 2月 25 00:00:03 volumio systemd[1]: Started openvpn@myvpn.service - OpenVPN connection to myvpn. 2月 25 00:00:03 volumio welcome[1015]: Resolved ip:[0] 2月 25 00:00:03 volumio ifplugd(eth0)[1046]: ifplugd 0.28 initializing. 2月 25 00:00:03 volumio ifplugd(eth0)[1046]: Using interface eth0/B8:27:EB:00:E7:96 with driver (version: 6.12.47-v7+) 2月 25 00:00:03 volumio ifplugd(eth0)[1046]: Using detection mode: SIOCETHTOOL 2月 25 00:00:03 volumio ifplugd(eth0)[1046]: Initialization complete, link beat not detected. 2月 25 00:00:03 volumio systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... 2月 25 00:00:03 volumio ntpd[1012]: INIT: This system has a 32-bit time_t. 2月 25 00:00:03 volumio ntpd[1012]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. 2月 25 00:00:03 volumio ntpd[1012]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes 2月 25 00:00:03 volumio ntpd[1012]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 2月 25 00:00:03 volumio ntpd[1012]: INIT: Running with OpenSSL 3.0.18 30 Sep 2025, 30000120 2月 25 00:00:03 volumio ntpd[1012]: NTSc: Using system default root certificates. 2月 25 00:00:03 volumio ifplugd[970]: Network Interface Plugging Daemon...start eth0...done. 2月 25 00:00:03 volumio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. 2月 25 00:00:03 volumio ovpn-myvpn[978]: RESOLVE: Cannot resolve host address: public-vpn-108.opengw.net:443 (System error) 2月 25 00:00:03 volumio ovpn-myvpn[978]: RESOLVE: Cannot resolve host address: public-vpn-108.opengw.net:443 (System error) 2月 25 00:00:03 volumio ovpn-myvpn[978]: Could not determine IPv4/IPv6 protocol 2月 25 00:00:03 volumio ovpn-myvpn[978]: SIGUSR1[soft,Could not determine IPv4/IPv6 protocol] received, process restarting 2月 25 00:00:03 volumio ovpn-myvpn[978]: Restart pause, 10 second(s) 2月 25 00:00:03 volumio systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. 2月 25 00:00:04 volumio ntpd[1012]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory 2月 25 00:00:04 volumio systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... 2月 25 00:00:04 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. 2月 25 00:00:04 volumio sshd[1088]: Server listening on 0.0.0.0 port 22. 2月 25 00:00:04 volumio sshd[1088]: Server listening on :: port 22. 2月 25 00:00:04 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. 2月 25 00:00:04 volumio exim4[969]: Starting MTA: 2月 25 00:00:04 volumio exim4[1304]: 2026-02-25 00:00:04 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=117 2月 25 00:00:04 volumio exim4[1304]: 2026-02-25 00:00:04 Warning: No server certificate defined; will use a selfsigned one. 2月 25 00:00:04 volumio exim4[1304]: Suggested action: either install a certificate or change tls_advertise_hosts option 2月 25 00:00:04 volumio exim4[1304]: 2026-02-25 00:00:04 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=117 2月 25 00:00:04 volumio exim4[1304]: exim: could not open panic log - aborting: see message(s) above 2月 25 00:00:04 volumio exim[1304]: [1\2] 2026-02-25 00:00:04 Warning: No server certificate defined; will use a selfsigned one. 2月 25 00:00:04 volumio exim[1304]: [2/2] Suggested action: either install a certificate or change tls_advertise_hosts option 2月 25 00:00:04 volumio exim[1304]: 2026-02-25 00:00:04 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=117 2月 25 00:00:04 volumio exim[1304]: exim: could not open panic log - aborting: see message(s) above 2月 25 00:00:04 volumio systemd[1]: exim4.service: Control process exited, code=exited, status=1/FAILURE 2月 25 00:00:04 volumio systemd[1]: exim4.service: Failed with result 'exit-code'. 2月 25 00:00:04 volumio systemd[1]: Failed to start exim4.service - LSB: exim Mail Transport Agent. 2月 25 00:00:05 volumio ntpd[1012]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 2月 25 00:00:05 volumio ntpd[1012]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 2月 25 00:00:05 volumio ntpd[1012]: DNS: dns_check: DNS error: -11, System error 2月 25 00:00:05 volumio ntpd[1012]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 2月 25 00:00:05 volumio apachectl[1031]: AH00558: apache2: Could not reliably determine the server's fully qualified domain name, using 127.0.1.1. Set the 'ServerName' directive globally to suppress this message 2月 25 00:00:05 volumio apachectl[1031]: (2)No such file or directory: AH02291: Cannot access directory '/var/log/apache2/' for main error log 2月 25 00:00:05 volumio apachectl[1031]: (2)No such file or directory: AH02291: Cannot access directory '/var/log/apache2/' for error log of vhost defined at /etc/apache2/sites-enabled/000-default.conf:1 2月 25 00:00:05 volumio apachectl[1031]: AH00014: Configuration check failed 2月 25 00:00:05 volumio apachectl[968]: Action 'start' failed. 2月 25 00:00:05 volumio apachectl[968]: The Apache error log may have more information. 2月 25 00:00:05 volumio systemd[1]: apache2.service: Control process exited, code=exited, status=1/FAILURE 2月 25 00:00:05 volumio systemd[1]: apache2.service: Failed with result 'exit-code'. 2月 25 00:00:05 volumio systemd[1]: Failed to start apache2.service - The Apache HTTP Server. 2月 25 00:00:05 volumio bluealsa[875]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa 2月 25 00:00:05 volumio bluealsa[875]: main.c:663: Starting main dispatching loop 2月 25 00:00:05 volumio systemd[1]: Started bluealsa.service - BlueALSA service. 2月 25 00:00:05 volumio systemd[1]: Reached target bluetooth.target - Bluetooth Support. 2月 25 00:00:05 volumio bluealsa[875]: main.c:118: Acquired D-Bus service name: org.bluealsa 2月 25 00:00:05 volumio bluealsa[875]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa 2月 25 00:00:05 volumio bluealsa[875]: bluez.c:153: Registering media application: /org/bluez/hci0 2月 25 00:00:05 volumio bluealsa[875]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 2月 25 00:00:05 volumio bluealsa[875]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 2月 25 00:00:05 volumio bluealsa[875]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 2月 25 00:00:05 volumio bluealsa[875]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 2月 25 00:00:05 volumio dbus-daemon[721]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service' requested by ':1.6' (uid=0 pid=875 comm="/usr/bin/bluealsa -S -p a2dp-source -p a2dp-sink") 2月 25 00:00:05 volumio systemd[1]: Starting upower.service - Daemon for power management... 2月 25 00:00:05 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. 2月 25 00:00:06 volumio ntpd[1012]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 2月 25 00:00:06 volumio ntpd[1012]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 2月 25 00:00:06 volumio ntpd[1012]: DNS: dns_check: DNS error: -11, System error 2月 25 00:00:06 volumio ntpd[1012]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 2月 25 00:00:06 volumio dbus-daemon[721]: [system] Successfully activated service 'org.freedesktop.UPower' 2月 25 00:00:06 volumio systemd[1]: Started upower.service - Daemon for power management. 2月 25 00:00:06 volumio volumio-remote-updater[731]: [2026-02-25 00:00:06] [info] asio async_connect error: asio.system:111 (Connection refused) 2月 25 00:00:06 volumio volumio-remote-updater[731]: [2026-02-25 00:00:06] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) 2月 25 00:00:06 volumio volumio-remote-updater[731]: [2026-02-25 00:00:06] [error] handle_connect error: Connection refused 2月 25 00:00:06 volumio bluetoothd[720]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 2月 25 00:00:06 volumio bluetoothd[720]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 2月 25 00:00:06 volumio bluetoothd[720]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 2月 25 00:00:06 volumio bluetoothd[720]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 2月 25 00:00:06 volumio systemd[1]: Started lighttpd.service - Lighttpd Daemon. 2月 25 00:00:06 volumio systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. 2月 25 00:00:06 volumio systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. 2月 25 00:00:07 volumio ntpd[1012]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 2月 25 00:00:07 volumio ntpd[1012]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 2月 25 00:00:07 volumio ntpd[1012]: DNS: dns_check: DNS error: -11, System error 2月 25 00:00:07 volumio ntpd[1012]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 2月 25 00:00:07 volumio nmbd[1345]: [2026/02/25 00:00:07.416204, 0] ../../source3/nmbd/nmbd.c:901(main) 2月 25 00:00:07 volumio nmbd[1345]: nmbd version 4.17.8-Raspbian started. 2月 25 00:00:07 volumio nmbd[1345]: Copyright Andrew Tridgell and the Samba Team 1992-2022 2月 25 00:00:07 volumio nmbd[1345]: [2026/02/25 00:00:07.440441, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) 2月 25 00:00:07 volumio nmbd[1345]: started asyncdns process 1347 2月 25 00:00:07 volumio nmbd[1345]: [2026/02/25 00:00:07.443044, 0] ../../lib/util/become_daemon.c:150(daemon_status) 2月 25 00:00:07 volumio nmbd[1345]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... 2月 25 00:00:07 volumio nmbd[1345]: [2026/02/25 00:00:07.443256, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) 2月 25 00:00:07 volumio nmbd[1345]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). 2月 25 00:00:08 volumio ntpd[1012]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 2月 25 00:00:08 volumio ntpd[1012]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 2月 25 00:00:08 volumio ntpd[1012]: DNS: dns_check: DNS error: -11, System error 2月 25 00:00:08 volumio ntpd[1012]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 2月 25 00:00:08 volumio wireless.js[732]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless 2月 25 00:00:08 volumio wireless.js[732]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow 2月 25 00:00:09 volumio sudo[1379]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 2月 25 00:00:09 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:09 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:09 volumio sudo[1381]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down 2月 25 00:00:09 volumio sudo[1381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:10 volumio sudo[1381]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:10 volumio wireless.js[732]: WIRELESS.JS - INFO: Cleaning previous... 2月 25 00:00:10 volumio sudo[1390]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up 2月 25 00:00:10 volumio sudo[1390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:10 volumio sudo[1390]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:10 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled 2月 25 00:00:10 volumio wireless.js[732]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations 2月 25 00:00:10 volumio wireless.js[732]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 3ms 2月 25 00:00:10 volumio wireless.js[732]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:55:b2:c3) 2月 25 00:00:10 volumio sudo[1397]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get 2月 25 00:00:10 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:10 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:10 volumio sudo[1405]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan 2月 25 00:00:10 volumio sudo[1405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:10 volumio sudo[1405]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:10 volumio wireless.js[732]: WIRELESS.JS - INFO: Start wireless flow 2月 25 00:00:10 volumio wireless.js[732]: WIRELESS.JS - INFO: Stopped hotspot (if there).. 2月 25 00:00:10 volumio sudo[1419]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 2月 25 00:00:10 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:10 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:10 volumio sudo[1421]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down 2月 25 00:00:10 volumio sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:11 volumio sudo[1421]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:11 volumio wireless.js[732]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations 2月 25 00:00:11 volumio wireless.js[732]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: b8:27:eb:55:b2:c3, USB: false) 2月 25 00:00:11 volumio wpa_supplicant[1427]: Successfully initialized wpa_supplicant 2月 25 00:00:11 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled 2月 25 00:00:11 volumio sudo[1439]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up 2月 25 00:00:11 volumio sudo[1439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:11 volumio sudo[1439]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:12 volumio wpa_supplicant[1436]: wlan0: Trying to associate with d8:07:b6:58:5f:79 (SSID='stranger_C163_2' freq=2462 MHz) 2月 25 00:00:12 volumio wpa_supplicant[1436]: wlan0: Associated with d8:07:b6:58:5f:79 2月 25 00:00:12 volumio wpa_supplicant[1436]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 2月 25 00:00:12 volumio wpa_supplicant[1436]: wlan0: WPA: Key negotiation completed with d8:07:b6:58:5f:79 [PTK=CCMP GTK=CCMP] 2月 25 00:00:12 volumio wpa_supplicant[1436]: wlan0: CTRL-EVENT-CONNECTED - Connection to d8:07:b6:58:5f:79 completed [id=0 id_str=] 2月 25 00:00:12 volumio dhcpcd[824]: wlan0: carrier acquired 2月 25 00:00:12 volumio dhcpcd[824]: wlan0: connected to Access Point: stranger_C163_2 2月 25 00:00:12 volumio dhcpcd[824]: wlan0: IAID eb:55:b2:c3 2月 25 00:00:12 volumio dhcpcd[824]: wlan0: adding address fe80::ebf2:e8fc:6c4c:87f 2月 25 00:00:12 volumio dhcpcd[824]: ipv6_addaddr1: Permission denied 2月 25 00:00:12 volumio bash[994]: setdatetime-helper: all HTTPS Date fallbacks failed 2月 25 00:00:12 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. 2月 25 00:00:12 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. 2月 25 00:00:12 volumio systemd[1]: setdatetime-helper.service: Consumed 1.364s CPU time. 2月 25 00:00:12 volumio wpa_supplicant[1436]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d8:07:b6:58:5f:79 reason=3 locally_generated=1 2月 25 00:00:12 volumio wpa_supplicant[1436]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all 2月 25 00:00:12 volumio dhcpcd[824]: wlan0: carrier lost - roaming 2月 25 00:00:12 volumio wpa_supplicant[1436]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD 2月 25 00:00:12 volumio wireless.js[732]: WIRELESS.JS - INFO: DHCP IP fallback 2月 25 00:00:12 volumio wireless.js[732]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor 2月 25 00:00:12 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 2月 25 00:00:13 volumio dhcpcd[824]: wlan0: soliciting an IPv6 router 2月 25 00:00:13 volumio wpa_supplicant[1436]: wlan0: Trying to associate with d8:07:b6:58:5f:79 (SSID='stranger_C163_2' freq=2462 MHz) 2月 25 00:00:13 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> ASSOCIATING (duration: 0ms) 2月 25 00:00:13 volumio ovpn-myvpn[978]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 2月 25 00:00:13 volumio ovpn-myvpn[978]: RESOLVE: Cannot resolve host address: public-vpn-108.opengw.net:443 (System error) 2月 25 00:00:13 volumio ovpn-myvpn[978]: RESOLVE: Cannot resolve host address: public-vpn-108.opengw.net:443 (System error) 2月 25 00:00:13 volumio ovpn-myvpn[978]: Could not determine IPv4/IPv6 protocol 2月 25 00:00:13 volumio ovpn-myvpn[978]: SIGUSR1[soft,Could not determine IPv4/IPv6 protocol] received, process restarting 2月 25 00:00:13 volumio ovpn-myvpn[978]: Restart pause, 10 second(s) 2月 25 00:00:13 volumio volumio-remote-updater[731]: [2026-02-25 00:00:13] [info] asio async_connect error: asio.system:111 (Connection refused) 2月 25 00:00:13 volumio volumio-remote-updater[731]: [2026-02-25 00:00:13] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) 2月 25 00:00:13 volumio volumio-remote-updater[731]: [2026-02-25 00:00:13] [error] handle_connect error: Connection refused 2月 25 00:00:13 volumio wpa_supplicant[1436]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=d8:07:b6:58:5f:79 status_code=16 2月 25 00:00:14 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: State transition: ASSOCIATING -> SCANNING (duration: 518ms) 2月 25 00:00:14 volumio wpa_supplicant[1436]: wlan0: Trying to associate with d8:07:b6:58:5f:79 (SSID='stranger_C163_2' freq=2462 MHz) 2月 25 00:00:14 volumio wpa_supplicant[1436]: wlan0: Associated with d8:07:b6:58:5f:79 2月 25 00:00:14 volumio wpa_supplicant[1436]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 2月 25 00:00:14 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> ASSOCIATING (duration: 519ms) 2月 25 00:00:14 volumio wpa_supplicant[1436]: wlan0: WPA: Key negotiation completed with d8:07:b6:58:5f:79 [PTK=CCMP GTK=CCMP] 2月 25 00:00:14 volumio wpa_supplicant[1436]: wlan0: CTRL-EVENT-CONNECTED - Connection to d8:07:b6:58:5f:79 completed [id=0 id_str=] 2月 25 00:00:14 volumio dhcpcd[824]: wlan0: carrier acquired 2月 25 00:00:14 volumio dhcpcd[824]: wlan0: IAID eb:55:b2:c3 2月 25 00:00:14 volumio dhcpcd[824]: wlan0: soliciting an IPv6 router 2月 25 00:00:15 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: State transition: ASSOCIATING -> COMPLETED (duration: 511ms) 2月 25 00:00:15 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful 2月 25 00:00:15 volumio wireless.js[732]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to d8:07:b6:58:5f:79 2月 25 00:00:15 volumio wireless.js[732]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow 2月 25 00:00:16 volumio sudo[1473]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 2月 25 00:00:16 volumio sudo[1473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:16 volumio sudo[1473]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:16 volumio dhcpcd[824]: control command: /sbin/dhcpcd wlan0 2月 25 00:00:16 volumio dhcpcd[824]: control_free: No such file or directory 2月 25 00:00:16 volumio dhcpcd[824]: wlan0: rebinding lease of 192.168.1.108 2月 25 00:00:16 volumio dhcpcd[824]: wlan0: probing address 192.168.1.108/24 2月 25 00:00:17 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. 2月 25 00:00:17 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. 2月 25 00:00:17 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. 2月 25 00:00:17 volumio upmpdcli[1477]: Could not open config: /tmp/upmpdcli.conf 2月 25 00:00:17 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE 2月 25 00:00:17 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. 2月 25 00:00:18 volumio wireless.js[732]: WIRELESS.JS - INFO: Start ap 2月 25 00:00:18 volumio wireless.js[732]: WIRELESS.JS - INFO: Notified systemd about wireless ready 2月 25 00:00:18 volumio systemd[1]: Started wireless.service - Wireless Services. 2月 25 00:00:18 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled 2月 25 00:00:18 volumio systemd[1]: Started volumio.service - Volumio Backend Module. 2月 25 00:00:18 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... 2月 25 00:00:18 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. 2月 25 00:00:18 volumio systemd[1]: Received SIGRTMIN+21 from PID 300 (plymouthd). 2月 25 00:00:18 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. 2月 25 00:00:18 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. 2月 25 00:00:18 volumio systemd[1]: Received SIGRTMIN+21 from PID 300 (n/a). 2月 25 00:00:18 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. 2月 25 00:00:18 volumio systemd[1]: Reached target getty.target - Login Prompts. 2月 25 00:00:18 volumio volumio-cpu-tweak[1486]: Setting RT Priority for mpd 2月 25 00:00:18 volumio volumio-cpu-tweak[1501]: pid 35's current scheduling policy: SCHED_OTHER 2月 25 00:00:18 volumio volumio-cpu-tweak[1501]: pid 35's current scheduling priority: 0 2月 25 00:00:18 volumio volumio-cpu-tweak[1486]: Setting MPD Affinity 2月 25 00:00:18 volumio volumio-cpu-tweak[1502]: pid 3's current affinity mask: f 2月 25 00:00:18 volumio volumio-cpu-tweak[1486]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input 2月 25 00:00:18 volumio volumio-cpu-tweak[1486]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance 2月 25 00:00:18 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. 2月 25 00:00:19 volumio wireless.js[732]: WIRELESS.JS - INFO: trying... 2月 25 00:00:19 volumio sudo[1516]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r 2月 25 00:00:19 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:19 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:19 volumio sudo[1519]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 2月 25 00:00:19 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:19 volumio sudo[1519]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:19 volumio wireless.js[732]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined 2月 25 00:00:20 volumio wireless.js[732]: WIRELESS.JS - INFO: trying... 2月 25 00:00:20 volumio sudo[1530]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r 2月 25 00:00:20 volumio sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:20 volumio sudo[1530]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:20 volumio sudo[1533]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 2月 25 00:00:20 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:20 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:20 volumio wireless.js[732]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined 2月 25 00:00:21 volumio wireless.js[732]: WIRELESS.JS - INFO: WARNING: dhcpcd running but no IP assigned yet 2月 25 00:00:21 volumio wireless.js[732]: WIRELESS.JS - INFO: trying... 2月 25 00:00:21 volumio sudo[1551]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r 2月 25 00:00:21 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:21 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:21 volumio sudo[1555]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 2月 25 00:00:21 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:21 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:21 volumio wireless.js[732]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined 2月 25 00:00:21 volumio dhcpcd[824]: wlan0: leased 192.168.1.108 for 7200 seconds 2月 25 00:00:21 volumio avahi-daemon[719]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.108. 2月 25 00:00:21 volumio avahi-daemon[719]: New relevant interface wlan0.IPv4 for mDNS. 2月 25 00:00:21 volumio avahi-daemon[719]: Registering new address record for 192.168.1.108 on wlan0.IPv4. 2月 25 00:00:21 volumio dhcpcd[824]: wlan0: adding route to 192.168.1.0/24 2月 25 00:00:21 volumio dhcpcd[824]: wlan0: adding default route via 192.168.1.254 2月 25 00:00:21 volumio systemd[1]: welcome.service: Deactivated successfully. 2月 25 00:00:21 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. 2月 25 00:00:21 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... 2月 25 00:00:21 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... 2月 25 00:00:21 volumio welcome[1569]: Resolved ip:[1] 192.168.1.108 2月 25 00:00:21 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. 2月 25 00:00:21 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. 2月 25 00:00:21 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. 2月 25 00:00:21 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... 2月 25 00:00:21 volumio nmbd[1345]: [2026/02/25 00:00:21.807817, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) 2月 25 00:00:21 volumio nmbd[1345]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.108 for name WORKGROUP<1d>. 2月 25 00:00:21 volumio nmbd[1345]: This response was from IP 192.168.1.129, reporting an IP address of 192.168.1.129. 2月 25 00:00:22 volumio wireless.js[732]: WIRELESS.JS - INFO: trying... 2月 25 00:00:22 volumio winbindd[1584]: [2026/02/25 00:00:22.554047, 0] ../../source3/winbindd/winbindd.c:1440(main) 2月 25 00:00:22 volumio winbindd[1584]: winbindd version 4.17.8-Raspbian started. 2月 25 00:00:22 volumio winbindd[1584]: Copyright Andrew Tridgell and the Samba Team 1992-2022 2月 25 00:00:22 volumio sudo[1594]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r 2月 25 00:00:22 volumio sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:22 volumio sudo[1594]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:22 volumio winbindd[1584]: [2026/02/25 00:00:22.591384, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) 2月 25 00:00:22 volumio winbindd[1584]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 2月 25 00:00:22 volumio sudo[1598]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 2月 25 00:00:22 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:22 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:22 volumio wireless.js[732]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.108, ipV6 is undefined 2月 25 00:00:22 volumio wireless.js[732]: WIRELESS.JS - INFO: Connected to SSID: stranger_C163_2 2月 25 00:00:22 volumio wireless.js[732]: WIRELESS.JS - INFO: It's done! AP 2月 25 00:00:22 volumio wireless.js[732]: WIRELESS.JS - INFO: Restarting avahi-daemon... 2月 25 00:00:22 volumio sudo[1603]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon 2月 25 00:00:22 volumio sudo[1603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:00:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... 2月 25 00:00:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. 2月 25 00:00:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 2月 25 00:00:22 volumio systemd[1]: shairport-sync.service: Consumed 1.353s CPU time. 2月 25 00:00:22 volumio avahi-daemon[719]: Got SIGTERM, quitting. 2月 25 00:00:22 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... 2月 25 00:00:22 volumio avahi-daemon[719]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.108. 2月 25 00:00:22 volumio avahi-daemon[719]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. 2月 25 00:00:22 volumio avahi-daemon[719]: avahi-daemon 0.8 exiting. 2月 25 00:00:22 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. 2月 25 00:00:22 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. 2月 25 00:00:23 volumio ntpd[1012]: IO: Listen normally on 3 wlan0 192.168.1.108:123 2月 25 00:00:23 volumio ntpd[1012]: IO: new interface(s) found: waking up resolver 2月 25 00:00:23 volumio ntpd[1012]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 2月 25 00:00:23 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... 2月 25 00:00:23 volumio avahi-daemon[1605]: Process 719 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) 2月 25 00:00:23 volumio avahi-daemon[1605]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). 2月 25 00:00:23 volumio avahi-daemon[1605]: Successfully dropped root privileges. 2月 25 00:00:23 volumio avahi-daemon[1605]: avahi-daemon 0.8 starting up. 2月 25 00:00:23 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. 2月 25 00:00:23 volumio avahi-daemon[1605]: Successfully called chroot(). 2月 25 00:00:23 volumio avahi-daemon[1605]: Successfully dropped remaining capabilities. 2月 25 00:00:23 volumio avahi-daemon[1605]: No service file found in /etc/avahi/services. 2月 25 00:00:23 volumio avahi-daemon[1605]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.108. 2月 25 00:00:23 volumio avahi-daemon[1605]: New relevant interface wlan0.IPv4 for mDNS. 2月 25 00:00:23 volumio avahi-daemon[1605]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. 2月 25 00:00:23 volumio avahi-daemon[1605]: New relevant interface lo.IPv4 for mDNS. 2月 25 00:00:23 volumio avahi-daemon[1605]: Network interface enumeration completed. 2月 25 00:00:23 volumio avahi-daemon[1605]: Registering new address record for 192.168.1.108 on wlan0.IPv4. 2月 25 00:00:23 volumio avahi-daemon[1605]: Registering new address record for 127.0.0.1 on lo.IPv4. 2月 25 00:00:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 2月 25 00:00:23 volumio sudo[1603]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:23 volumio ovpn-myvpn[978]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 2月 25 00:00:23 volumio volumio-remote-updater[731]: [2026-02-25 00:00:23] [connect] Successful connection 2月 25 00:00:23 volumio ovpn-myvpn[978]: TCP/UDP: Preserving recently used remote address: [AF_INET]219.100.37.98:443 2月 25 00:00:23 volumio ovpn-myvpn[978]: Socket Buffers: R=[131072->131072] S=[16384->16384] 2月 25 00:00:23 volumio ovpn-myvpn[978]: Attempting to establish TCP connection with [AF_INET]219.100.37.98:443 2月 25 00:00:23 volumio avahi-daemon[1605]: Server startup complete. Host name is volumio.local. Local service cookie is 2330586067. 2月 25 00:00:23 volumio ovpn-myvpn[978]: TCP connection established with [AF_INET]219.100.37.98:443 2月 25 00:00:23 volumio ovpn-myvpn[978]: TCPv4_CLIENT link local: (not bound) 2月 25 00:00:23 volumio ovpn-myvpn[978]: TCPv4_CLIENT link remote: [AF_INET]219.100.37.98:443 2月 25 00:00:23 volumio ovpn-myvpn[978]: TLS: Initial packet from [AF_INET]219.100.37.98:443, sid=a7cf4d30 9610462f 2月 25 00:00:23 volumio ovpn-myvpn[978]: WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this 2月 25 00:00:24 volumio ovpn-myvpn[978]: VERIFY OK: depth=2, C=US, O=Internet Security Research Group, CN=ISRG Root X1 2月 25 00:00:24 volumio ovpn-myvpn[978]: VERIFY OK: depth=1, C=US, O=Let's Encrypt, CN=R12 2月 25 00:00:24 volumio ovpn-myvpn[978]: VERIFY OK: depth=0, CN=opengw.net 2月 25 00:00:24 volumio ovpn-myvpn[978]: Control Channel: TLSv1.3, cipher TLSv1.3 TLS_AES_256_GCM_SHA384, peer certificate: 2048 bit RSA, signature: RSA-SHA256 2月 25 00:00:24 volumio ovpn-myvpn[978]: [opengw.net] Peer Connection Initiated with [AF_INET]219.100.37.98:443 2月 25 00:00:24 volumio ovpn-myvpn[978]: TLS: move_session: dest=TM_ACTIVE src=TM_INITIAL reinit_src=1 2月 25 00:00:24 volumio ovpn-myvpn[978]: TLS: tls_multi_process: initial untrusted session promoted to trusted 2月 25 00:00:24 volumio wireless.js[732]: WIRELESS.JS - INFO: Notified systemd about wireless ready 2月 25 00:00:24 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. 2月 25 00:00:24 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... 2月 25 00:00:25 volumio ovpn-myvpn[978]: SENT CONTROL [opengw.net]: 'PUSH_REQUEST' (status=1) 2月 25 00:00:25 volumio smbd[1642]: [2026/02/25 00:00:25.752551, 0] ../../source3/smbd/server.c:1741(main) 2月 25 00:00:25 volumio smbd[1642]: smbd version 4.17.8-Raspbian started. 2月 25 00:00:25 volumio smbd[1642]: Copyright Andrew Tridgell and the Samba Team 1992-2022 2月 25 00:00:26 volumio volumio[1484]: info: ------------------------------------------- 2月 25 00:00:26 volumio volumio[1484]: info: ----- Volumio3 ---- 2月 25 00:00:26 volumio volumio[1484]: info: ------------------------------------------- 2月 25 00:00:26 volumio volumio[1484]: info: ----- System startup ---- 2月 25 00:00:26 volumio volumio[1484]: info: ------------------------------------------- 2月 25 00:00:27 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. 2月 25 00:00:27 volumio systemd[1]: Reached target multi-user.target - Multi-User System. 2月 25 00:00:27 volumio systemd[1]: Reached target graphical.target - Graphical Interface. 2月 25 00:00:27 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... 2月 25 00:00:27 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. 2月 25 00:00:27 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. 2月 25 00:00:27 volumio systemd[1]: Startup finished in 16.350s (kernel) + 33.308s (userspace) = 49.659s. 2月 25 00:00:27 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. 2月 25 00:00:27 volumio ovpn-myvpn[978]: PUSH: Received control message: 'PUSH_REPLY,ping 3,ping-restart 10,ifconfig 10.239.1.201 10.239.1.202,dhcp-option DNS 10.239.254.254,dhcp-option DNS 8.8.8.8,route-gateway 10.239.1.202,redirect-gateway def1' 2月 25 00:00:27 volumio ovpn-myvpn[978]: OPTIONS IMPORT: --ifconfig/up options modified 2月 25 00:00:27 volumio ovpn-myvpn[978]: OPTIONS IMPORT: route options modified 2月 25 00:00:27 volumio ovpn-myvpn[978]: OPTIONS IMPORT: route-related options modified 2月 25 00:00:27 volumio ovpn-myvpn[978]: OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified 2月 25 00:00:27 volumio ovpn-myvpn[978]: OPTIONS ERROR: failed to negotiate cipher with server. Add the server's cipher ('AES-128-CBC') to --data-ciphers (currently 'AES-256-GCM:AES-128-GCM:CHACHA20-POLY1305') if you want to connect to this server. 2月 25 00:00:27 volumio ovpn-myvpn[978]: ERROR: Failed to apply push options 2月 25 00:00:27 volumio ovpn-myvpn[978]: Failed to open tun/tap interface 2月 25 00:00:27 volumio ovpn-myvpn[978]: SIGUSR1[soft,process-push-msg-failed] received, process restarting 2月 25 00:00:27 volumio ovpn-myvpn[978]: Restart pause, 10 second(s) 2月 25 00:00:28 volumio ntpd[1012]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 2月 25 00:00:28 volumio ntpd[1012]: DNS: Pool taking: 162.159.200.1 2月 25 00:00:28 volumio ntpd[1012]: DNS: Pool taking: 129.250.35.250 2月 25 00:00:28 volumio ntpd[1012]: DNS: Pool taking: 110.4.129.114 2月 25 00:00:28 volumio ntpd[1012]: DNS: Pool taking: 138.3.209.153 2月 25 00:00:28 volumio ntpd[1012]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 2月 25 00:00:28 volumio volumio[1484]: info: MYVOLUMIO Environment detected 2月 25 00:00:28 volumio volumio[1484]: info: Plugin folders cleanup 2月 25 00:00:28 volumio volumio[1484]: info: Scanning into folder /volumio/app/plugins/ 2月 25 00:00:28 volumio volumio[1484]: info: Scanning category audio_interface 2月 25 00:00:28 volumio volumio[1484]: info: Scanning category miscellanea 2月 25 00:00:28 volumio volumio[1484]: info: Scanning category music_service 2月 25 00:00:28 volumio volumio[1484]: info: Scanning category plugins.json 2月 25 00:00:28 volumio volumio[1484]: info: Scanning category system_controller 2月 25 00:00:28 volumio volumio[1484]: info: Scanning category user_interface 2月 25 00:00:28 volumio volumio[1484]: info: Scanning into folder /data/plugins/ 2月 25 00:00:28 volumio volumio[1484]: info: Scanning category music_service 2月 25 00:00:28 volumio volumio[1484]: info: Plugin folders cleanup completed 2月 25 00:00:28 volumio volumio[1484]: info: ------------------------------------------- 2月 25 00:00:28 volumio volumio[1484]: info: ----- Core plugins startup ---- 2月 25 00:00:28 volumio volumio[1484]: info: ------------------------------------------- 2月 25 00:00:28 volumio volumio[1484]: info: Loading plugins from folder /volumio/app/plugins/ 2月 25 00:00:28 volumio volumio[1484]: info: Adding plugin upnp to MyMusic Plugins 2月 25 00:00:28 volumio volumio[1484]: info: Adding plugin airplay_emulation to MyMusic Plugins 2月 25 00:00:28 volumio volumio[1484]: info: Adding plugin upnp_browser to MyMusic Plugins 2月 25 00:00:28 volumio volumio[1484]: info: Loading plugins from folder /data/plugins/ 2月 25 00:00:28 volumio volumio[1484]: info: Loading plugin "system"... 2月 25 00:00:28 volumio volumio[1484]: info: Loading plugin "appearance"... 2月 25 00:00:29 volumio ntpd[1012]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 2月 25 00:00:29 volumio ntpd[1012]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 2月 25 00:00:29 volumio ntpd[1012]: DNS: Pool taking: 108.160.132.224 2月 25 00:00:29 volumio ntpd[1012]: DNS: Pool taking: 165.140.142.8 2月 25 00:00:29 volumio ntpd[1012]: DNS: Pool skipping: 138.3.209.153 2月 25 00:00:29 volumio ntpd[1012]: DNS: Pool taking: 172.237.20.5 2月 25 00:00:29 volumio ntpd[1012]: DNS: Pool taking: 2406:da14:10c4:9aa0:123:: 2月 25 00:00:29 volumio ntpd[1012]: DNS: Pool taking: 2400:8902::f03c:91ff:fe50:f5f0 2月 25 00:00:29 volumio ntpd[1012]: DNS: Pool taking: 240b:4009:272:9c06:def8:1b59:32ba:d60 2月 25 00:00:29 volumio ntpd[1012]: DNS: Pool taking: 2400:8902::2000:52ff:fee4:69d9 2月 25 00:00:29 volumio ntpd[1012]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 2月 25 00:00:30 volumio ntpd[1012]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 2月 25 00:00:30 volumio ntpd[1012]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 2月 25 00:00:30 volumio ntpd[1012]: DNS: Pool skipping: 110.4.129.114 2月 25 00:00:30 volumio ntpd[1012]: DNS: Pool taking: 85.121.50.82 2月 25 00:00:30 volumio ntpd[1012]: DNS: Pool taking: 163.44.119.85 2月 25 00:00:30 volumio ntpd[1012]: DNS: Pool taking: 139.162.81.45 2月 25 00:00:30 volumio ntpd[1012]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 2月 25 00:00:31 volumio ntpd[1012]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 2月 25 00:00:31 volumio volumio[1484]: info: Loading plugin "network"... 2月 25 00:00:31 volumio volumio[1484]: info: Refreshing Cached IP Addresses 2月 25 00:00:31 volumio volumio[1484]: info: Loading plugin "services"... 2月 25 00:00:31 volumio sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 2月 25 00:00:31 volumio sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:31 volumio volumio[1484]: info: Loading plugin "alsa_controller"... 2月 25 00:00:31 volumio sudo[1669]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:31 volumio sudo[1681]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan 2月 25 00:00:31 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:31 volumio sudo[1670]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 2月 25 00:00:31 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:31 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:31 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 2月 25 00:00:31 volumio volumio[1484]: info: Loading plugin "wizard"... 2月 25 00:00:31 volumio volumio[1484]: info: Loading plugin "networkfs"... 2月 25 00:00:31 volumio volumio[1484]: info: Starting Udev Watcher for removable devices 2月 25 00:00:31 volumio volumio[1484]: info: Ignoring mount for partition: boot 2月 25 00:00:31 volumio volumio[1484]: info: Ignoring mount for partition: volumio 2月 25 00:00:31 volumio volumio[1484]: info: Ignoring mount for partition: volumio_data 2月 25 00:00:31 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback 2月 25 00:00:31 volumio volumio[1484]: info: Loading plugin "volumio_command_line_client"... 2月 25 00:00:31 volumio sudo[1699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ktk,password=meescjqk,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.1 //192.168.1.129/ssd1_Music /mnt/NAS/ssd1_Music-1 2月 25 00:00:31 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:00:31 volumio volumio[1484]: info: Loading plugin "upnp"... 2月 25 00:00:31 volumio volumio[1484]: info: [1771945231454] Starting Upmpd Daemon 2月 25 00:00:31 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback 2月 25 00:00:31 volumio volumio[1484]: info: Loading plugin "my_music"... 2月 25 00:00:31 volumio volumio[1484]: info: Loading plugin "mpd"... 2月 25 00:00:31 volumio kernel: netfs: FS-Cache loaded 2月 25 00:00:31 volumio kernel: Key type cifs.spnego registered 2月 25 00:00:31 volumio kernel: Key type cifs.idmap registered 2月 25 00:00:31 volumio kernel: CIFS: Attempting to mount //192.168.1.129/ssd1_Music 2月 25 00:00:31 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:32 volumio volumio[1484]: info: Loading plugin "upnp_browser"... 2月 25 00:00:32 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. 2月 25 00:00:32 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. 2月 25 00:00:32 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root 2月 25 00:00:32 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. 2月 25 00:00:32 volumio upmpdcli[1727]: Could not open config: /tmp/upmpdcli.conf 2月 25 00:00:32 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE 2月 25 00:00:32 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. 2月 25 00:00:33 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. 2月 25 00:01:00 volumio ntpd[1012]: CLOCK: time stepped by 24.024653 2月 25 00:01:00 volumio ntpd[1012]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes 2月 25 00:01:00 volumio ntpd[1012]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 2月 25 00:01:00 volumio ntpd[1012]: DNS: Pool taking: 43.167.237.61 2月 25 00:01:00 volumio ntpd[1012]: DNS: Pool taking: 142.91.108.61 2月 25 00:01:00 volumio ntpd[1012]: DNS: Pool taking: 162.159.200.123 2月 25 00:01:00 volumio ntpd[1012]: DNS: Pool taking: 129.250.35.251 2月 25 00:01:00 volumio ntpd[1012]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 2月 25 00:01:00 volumio volumio[1484]: info: Starting UPNP Browser 2月 25 00:01:00 volumio volumio[1484]: info: Loading plugin "alarm-clock"... 2月 25 00:01:00 volumio volumio[1484]: info: Loading plugin "airplay_emulation"... 2月 25 00:01:00 volumio volumio[1484]: info: Starting Shairport Sync 2月 25 00:01:00 volumio volumio[1484]: info: Loading plugin "last_100"... 2月 25 00:01:00 volumio volumio[1484]: info: Loading plugin "webradio"... 2月 25 00:01:01 volumio volumio[1484]: info: Loading plugin "i2s_dacs"... 2月 25 00:01:01 volumio volumio[1484]: info: I2S DAC not set, start Auto-detection 2月 25 00:01:01 volumio volumio[1484]: info: Loading plugin "volumiodiscovery"... 2月 25 00:01:01 volumio volumio[1484]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. 2月 25 00:01:01 volumio volumio[1484]: *** WARNING *** Please fix your application to use the native API of Avahi! 2月 25 00:01:01 volumio volumio[1484]: *** WARNING *** For more information see 2月 25 00:01:01 volumio volumio[1484]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. 2月 25 00:01:01 volumio volumio[1484]: *** WARNING *** Please fix your application to use the native API of Avahi! 2月 25 00:01:01 volumio volumio[1484]: *** WARNING *** For more information see 2月 25 00:01:01 volumio node[1484]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. 2月 25 00:01:01 volumio node[1484]: *** WARNING *** Please fix your application to use the native API of Avahi! 2月 25 00:01:01 volumio node[1484]: *** WARNING *** For more information see 2月 25 00:01:01 volumio node[1484]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. 2月 25 00:01:01 volumio node[1484]: *** WARNING *** Please fix your application to use the native API of Avahi! 2月 25 00:01:01 volumio node[1484]: *** WARNING *** For more information see 2月 25 00:01:01 volumio volumio[1484]: info: Applying required configuration parameters for plugin volumiodiscovery 2月 25 00:01:01 volumio volumio[1484]: info: Discovery: Started advertising with name: Volumio 2月 25 00:01:01 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback 2月 25 00:01:01 volumio volumio[1484]: info: Loading plugin "spop"... 2月 25 00:01:01 volumio ovpn-myvpn[978]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 2月 25 00:01:01 volumio ovpn-myvpn[978]: TCP/UDP: Preserving recently used remote address: [AF_INET]219.100.37.98:443 2月 25 00:01:01 volumio ovpn-myvpn[978]: Socket Buffers: R=[131072->131072] S=[16384->16384] 2月 25 00:01:01 volumio ovpn-myvpn[978]: Attempting to establish TCP connection with [AF_INET]219.100.37.98:443 2月 25 00:01:02 volumio ovpn-myvpn[978]: TCP connection established with [AF_INET]219.100.37.98:443 2月 25 00:01:02 volumio ovpn-myvpn[978]: TCPv4_CLIENT link local: (not bound) 2月 25 00:01:02 volumio ovpn-myvpn[978]: TCPv4_CLIENT link remote: [AF_INET]219.100.37.98:443 2月 25 00:01:02 volumio ovpn-myvpn[978]: TLS: Initial packet from [AF_INET]219.100.37.98:443, sid=a73f98e8 1aaa7a2f 2月 25 00:01:02 volumio ovpn-myvpn[978]: VERIFY OK: depth=2, C=US, O=Internet Security Research Group, CN=ISRG Root X1 2月 25 00:01:02 volumio ovpn-myvpn[978]: VERIFY OK: depth=1, C=US, O=Let's Encrypt, CN=R12 2月 25 00:01:02 volumio ovpn-myvpn[978]: VERIFY OK: depth=0, CN=opengw.net 2月 25 00:01:02 volumio ovpn-myvpn[978]: Control Channel: TLSv1.3, cipher TLSv1.3 TLS_AES_256_GCM_SHA384, peer certificate: 2048 bit RSA, signature: RSA-SHA256 2月 25 00:01:02 volumio ovpn-myvpn[978]: [opengw.net] Peer Connection Initiated with [AF_INET]219.100.37.98:443 2月 25 00:01:02 volumio ovpn-myvpn[978]: TLS: move_session: dest=TM_ACTIVE src=TM_INITIAL reinit_src=1 2月 25 00:01:02 volumio ovpn-myvpn[978]: TLS: tls_multi_process: initial untrusted session promoted to trusted 2月 25 00:01:02 volumio volumio-remote-updater[731]: [2026-02-25 00:01:02] [connect] Successful connection 2月 25 00:01:03 volumio ovpn-myvpn[978]: SENT CONTROL [opengw.net]: 'PUSH_REQUEST' (status=1) 2月 25 00:01:04 volumio volumio[1484]: info: Loading plugin "youtube2"... 2月 25 00:01:05 volumio ovpn-myvpn[978]: PUSH: Received control message: 'PUSH_REPLY,ping 3,ping-restart 10,ifconfig 10.239.14.213 10.239.14.214,dhcp-option DNS 10.239.254.254,dhcp-option DNS 8.8.8.8,route-gateway 10.239.14.214,redirect-gateway def1' 2月 25 00:01:05 volumio ovpn-myvpn[978]: OPTIONS IMPORT: --ifconfig/up options modified 2月 25 00:01:05 volumio ovpn-myvpn[978]: OPTIONS IMPORT: route options modified 2月 25 00:01:05 volumio ovpn-myvpn[978]: OPTIONS IMPORT: route-related options modified 2月 25 00:01:05 volumio ovpn-myvpn[978]: OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified 2月 25 00:01:05 volumio ovpn-myvpn[978]: OPTIONS ERROR: failed to negotiate cipher with server. Add the server's cipher ('AES-128-CBC') to --data-ciphers (currently 'AES-256-GCM:AES-128-GCM:CHACHA20-POLY1305') if you want to connect to this server. 2月 25 00:01:05 volumio ovpn-myvpn[978]: ERROR: Failed to apply push options 2月 25 00:01:05 volumio ovpn-myvpn[978]: Failed to open tun/tap interface 2月 25 00:01:05 volumio ovpn-myvpn[978]: SIGUSR1[soft,process-push-msg-failed] received, process restarting 2月 25 00:01:05 volumio ovpn-myvpn[978]: Restart pause, 10 second(s) 2月 25 00:01:05 volumio volumio[1484]: info: Plugin ytcr is not enabled 2月 25 00:01:05 volumio volumio[1484]: info: Loading plugin "outputs"... 2月 25 00:01:05 volumio volumio[1484]: info: Loading plugin "albumart"... 2月 25 00:01:05 volumio volumio[1484]: info: Plugin example_plugin is not enabled 2月 25 00:01:05 volumio volumio[1484]: info: Loading plugin "inputs"... 2月 25 00:01:05 volumio volumio[1484]: info: Loading plugin "updater_comm"... 2月 25 00:01:06 volumio volumio[1484]: info: Plugin mpdemulation is not enabled 2月 25 00:01:06 volumio volumio[1484]: info: Loading plugin "rest_api"... 2月 25 00:01:06 volumio volumio[1484]: info: Loading plugin "websocket"... 2月 25 00:01:06 volumio volumio[1484]: info: Starting Socket.io Server version 1.7.4 2月 25 00:01:06 volumio volumio[1484]: info: Loading i18n strings for locale ja 2月 25 00:01:06 volumio volumio[1484]: Updating browse sources language 2月 25 00:01:06 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::initPlayerControls 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 2月 25 00:01:07 volumio volumio[1484]: Express server listening on port 3000 2月 25 00:01:07 volumio volumio[1484]: [Metrics] WebUI: 20s 570.48ms 2月 25 00:01:07 volumio volumio[1484]: info: CoreStateMachine::resetVolumioState 2月 25 00:01:07 volumio volumio[1484]: info: CoreStateMachine::getcurrentVolume 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::volumioRetrievevolume 2月 25 00:01:07 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2月 25 00:01:07 volumio sudo[1766]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 2月 25 00:01:07 volumio sudo[1768]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 2月 25 00:01:07 volumio sudo[1768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:07 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:07 volumio sudo[1768]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:07 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:07 volumio volumio[1746]: Forking 3 albumart workers 2月 25 00:01:07 volumio volumio[1484]: info: Volumio Network Manager: Network status updated: 2 2月 25 00:01:08 volumio volumio[1484]: info: CoreStateMachine::pushState 2月 25 00:01:08 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:08 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 2月 25 00:01:08 volumio volumio[1484]: info: CoreCommandRouter::volumioPushState 2月 25 00:01:08 volumio volumio[1484]: info: CoreStateMachine::updateTrackBlock 2月 25 00:01:08 volumio volumio[1484]: info: CorePlayQueue::getTrackBlock 2月 25 00:01:08 volumio volumio[1484]: info: CoreCommandRouter::volumioRetrievevolume 2月 25 00:01:08 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 2月 25 00:01:09 volumio volumio[1484]: info: Reloading queue from file 2月 25 00:01:09 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... 2月 25 00:01:09 volumio volumio[1484]: info: CoreStateMachine::pushState 2月 25 00:01:09 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:09 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 2月 25 00:01:09 volumio volumio[1484]: info: CoreCommandRouter::volumioPushState 2月 25 00:01:09 volumio volumio[1484]: info: CoreStateMachine::setRepeat false single undefined 2月 25 00:01:09 volumio volumio[1484]: info: CoreStateMachine::pushState 2月 25 00:01:09 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:09 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 2月 25 00:01:09 volumio volumio[1484]: info: CoreCommandRouter::volumioPushState 2月 25 00:01:09 volumio volumio[1484]: info: CoreStateMachine::setRandom null 2月 25 00:01:09 volumio volumio[1484]: info: CoreStateMachine::pushState 2月 25 00:01:09 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:09 volumio volumio[1484]: info: CoreCommandRouter::volumioPushState 2月 25 00:01:09 volumio volumio[1484]: info: Setting Device type: Raspberry PI 2月 25 00:01:09 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 2月 25 00:01:10 volumio volumio[1484]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb 2月 25 00:01:10 volumio volumio[1484]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 2月 25 00:01:10 volumio volumio[1484]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI 2月 25 00:01:10 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 2月 25 00:01:10 volumio sudo[1833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service 2月 25 00:01:10 volumio sudo[1833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:10 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 2月 25 00:01:10 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 2月 25 00:01:10 volumio volumio[1484]: info: Discovery: adding 8bcbac9c-eed5-4c38-bccc-98f0741bf9f5 2月 25 00:01:10 volumio volumio[1484]: info: Discovery: Found device Volumio 2月 25 00:01:10 volumio volumio[1484]: info: CoreCommandRouter::volumioGetState 2月 25 00:01:10 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:10 volumio volumio[1484]: info: Discovery: this is already registered, 8bcbac9c-eed5-4c38-bccc-98f0741bf9f5 2月 25 00:01:10 volumio volumio[1484]: info: Discovery: Found device Volumio 2月 25 00:01:10 volumio volumio[1484]: info: CoreCommandRouter::volumioGetState 2月 25 00:01:10 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:10 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 2月 25 00:01:11 volumio volumio[1484]: info: Completed loading Core Plugins 2月 25 00:01:11 volumio volumio[1484]: info: Preparing to generate the ALSA configuration file 2月 25 00:01:11 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 2月 25 00:01:11 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 2月 25 00:01:11 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 2月 25 00:01:11 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 2月 25 00:01:11 volumio volumio[1484]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf 2月 25 00:01:11 volumio volumio[1484]: info: Reading ALSA contributions from plugins. 2月 25 00:01:11 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. 2月 25 00:01:11 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. 2月 25 00:01:11 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 2月 25 00:01:11 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. 2月 25 00:01:11 volumio sudo[1833]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:12 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 2月 25 00:01:12 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 2月 25 00:01:12 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 2月 25 00:01:12 volumio volumio[1484]: info: Upmpdcli Daemon Started 2月 25 00:01:12 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 2月 25 00:01:12 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 2月 25 00:01:12 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 2月 25 00:01:12 volumio volumio[1484]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 2月 25 00:01:14 volumio volumio[1484]: info: Asound.conf file unchanged, so no further update is needed 2月 25 00:01:14 volumio volumio[1484]: info: Output device has changed, restarting MPD 2月 25 00:01:14 volumio volumio[1484]: info: Output device has changed, restarting Shairport Sync 2月 25 00:01:14 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:14 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2月 25 00:01:14 volumio sudo[1852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf 2月 25 00:01:14 volumio sudo[1852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:14 volumio sudo[1854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service 2月 25 00:01:14 volumio sudo[1854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:14 volumio sudo[1852]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:14 volumio volumio[1484]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 2月 25 00:01:14 volumio volumio[1484]: info: ___________ START PLUGINS ___________ 2月 25 00:01:14 volumio volumio[1484]: info: ControllerMpd::onStart: Initializing MPD 2月 25 00:01:14 volumio volumio[1484]: info: Creating MPD Configuration file 2月 25 00:01:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. 2月 25 00:01:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... 2月 25 00:01:15 volumio sudo[1862]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log 2月 25 00:01:15 volumio sudo[1862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:01:15 volumio sudo[1866]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory 2月 25 00:01:15 volumio sudo[1862]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:15 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 2月 25 00:01:15 volumio volumio[1484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 2月 25 00:01:15 volumio volumio[1484]: info: [1771945275214] CoreMusicLibrary::Adding element DLNAサーバー 2月 25 00:01:15 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 2月 25 00:01:15 volumio sudo[1864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf 2月 25 00:01:15 volumio volumio[1484]: info: UPNP Browser: Client initialized successfully 2月 25 00:01:15 volumio ovpn-myvpn[978]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 2月 25 00:01:15 volumio sudo[1864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:15 volumio sudo[1868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service 2月 25 00:01:15 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:15 volumio ovpn-myvpn[978]: TCP/UDP: Preserving recently used remote address: [AF_INET]219.100.37.98:443 2月 25 00:01:15 volumio ovpn-myvpn[978]: Socket Buffers: R=[131072->131072] S=[16384->16384] 2月 25 00:01:15 volumio ovpn-myvpn[978]: Attempting to establish TCP connection with [AF_INET]219.100.37.98:443 2月 25 00:01:15 volumio sudo[1864]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:15 volumio ovpn-myvpn[978]: TCP connection established with [AF_INET]219.100.37.98:443 2月 25 00:01:15 volumio ovpn-myvpn[978]: TCPv4_CLIENT link local: (not bound) 2月 25 00:01:15 volumio ovpn-myvpn[978]: TCPv4_CLIENT link remote: [AF_INET]219.100.37.98:443 2月 25 00:01:15 volumio ovpn-myvpn[978]: TLS: Initial packet from [AF_INET]219.100.37.98:443, sid=ed81f201 85107239 2月 25 00:01:15 volumio systemd[1]: mpd.service: Deactivated successfully. 2月 25 00:01:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. 2月 25 00:01:15 volumio systemd[1]: mpd.socket: Deactivated successfully. 2月 25 00:01:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. 2月 25 00:01:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... 2月 25 00:01:15 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:15 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2月 25 00:01:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. 2月 25 00:01:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... 2月 25 00:01:15 volumio volumio[1484]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 2月 25 00:01:15 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:15 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2月 25 00:01:16 volumio sudo[1882]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log 2月 25 00:01:16 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 2月 25 00:01:16 volumio sudo[1891]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory 2月 25 00:01:16 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:16 volumio volumio[1484]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 2月 25 00:01:16 volumio volumio[1484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 2月 25 00:01:16 volumio volumio[1484]: info: [1771945276213] CoreMusicLibrary::Adding element Last_100 2月 25 00:01:16 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 2月 25 00:01:16 volumio volumio[1484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 2月 25 00:01:16 volumio volumio[1484]: info: [1771945276219] CoreMusicLibrary::Adding element Webradio 2月 25 00:01:16 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 2月 25 00:01:16 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 2月 25 00:01:16 volumio volumio[1484]: info: Initializing BBC Radios 2月 25 00:01:16 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. 2月 25 00:01:16 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. 2月 25 00:01:16 volumio systemd[1]: setdatetime-helper.service: Consumed 1.031s CPU time. 2月 25 00:01:17 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 2月 25 00:01:17 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2月 25 00:01:17 volumio volumio[1484]: info: Creating Spotify config file 2月 25 00:01:17 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:17 volumio volumio[1771]: Starting albumart workers 2月 25 00:01:17 volumio volumio-remote-updater[731]: [2026-02-25 00:01:17] [connect] Successful connection 2月 25 00:01:17 volumio ovpn-myvpn[978]: VERIFY OK: depth=2, C=US, O=Internet Security Research Group, CN=ISRG Root X1 2月 25 00:01:17 volumio ovpn-myvpn[978]: VERIFY OK: depth=1, C=US, O=Let's Encrypt, CN=R12 2月 25 00:01:17 volumio ovpn-myvpn[978]: VERIFY OK: depth=0, CN=opengw.net 2月 25 00:01:18 volumio volumio[1772]: Starting albumart workers 2月 25 00:01:19 volumio volumio[1773]: Starting albumart workers 2月 25 00:01:21 volumio volumio[1484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 2月 25 00:01:21 volumio volumio[1484]: info: [1771945281291] CoreMusicLibrary::Adding element YouTube2 2月 25 00:01:21 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 2月 25 00:01:21 volumio volumio[1484]: Cannot find translation for source YouTube2 2月 25 00:01:21 volumio volumio[1484]: info: Volumio Calling Home 2月 25 00:01:23 volumio volumio[1484]: info: MPD Permissions set 2月 25 00:01:23 volumio volumio[1484]: info: MPD Permissions set 2月 25 00:01:23 volumio mpd[1892]: 2026-02-25T00:01:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg 2月 25 00:01:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. 2月 25 00:01:23 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:23 volumio sudo[1854]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:23 volumio volumio[1484]: info: Spotify config file written 2月 25 00:01:23 volumio sudo[1937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service 2月 25 00:01:23 volumio sudo[1937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:24 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 2月 25 00:01:24 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 2月 25 00:01:24 volumio volumio[1484]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 2月 25 00:01:24 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. 2月 25 00:01:24 volumio go-librespot[1939]: go-librespot daemon starting... 2月 25 00:01:24 volumio sudo[1937]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:24 volumio volumio[1484]: info: Completed starting Core Plugins 2月 25 00:01:24 volumio volumio[1484]: info: ------------------------------------------- 2月 25 00:01:24 volumio volumio[1484]: info: ----- MyVolumio plugins startup ---- 2月 25 00:01:24 volumio volumio[1484]: info: ------------------------------------------- 2月 25 00:01:24 volumio volumio[1484]: info: [MyVolumio PluginManager] Fetching plans data.... 2月 25 00:01:24 volumio volumio[1484]: info: Volumio called home 2月 25 00:01:24 volumio volumio[1484]: info: No need to fix Spotify hosts 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2月 25 00:01:24 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2月 25 00:01:24 volumio go-librespot[1940]: time="2026-02-25T00:01:24+09:00" level=info msg="running go-librespot 0.4.0" 2月 25 00:01:24 volumio go-librespot[1940]: time="2026-02-25T00:01:24+09:00" level=debug msg="app state loaded" 2月 25 00:01:24 volumio go-librespot[1940]: time="2026-02-25T00:01:24+09:00" level=info msg="api server listening on 127.0.0.1:9879" 2月 25 00:01:25 volumio ovpn-myvpn[978]: Control Channel: TLSv1.3, cipher TLSv1.3 TLS_AES_256_GCM_SHA384, peer certificate: 2048 bit RSA, signature: RSA-SHA256 2月 25 00:01:25 volumio ovpn-myvpn[978]: [opengw.net] Peer Connection Initiated with [AF_INET]219.100.37.98:443 2月 25 00:01:25 volumio ovpn-myvpn[978]: TLS: move_session: dest=TM_ACTIVE src=TM_INITIAL reinit_src=1 2月 25 00:01:25 volumio ovpn-myvpn[978]: TLS: tls_multi_process: initial untrusted session promoted to trusted 2月 25 00:01:25 volumio go-librespot[1940]: time="2026-02-25T00:01:25+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" 2月 25 00:01:25 volumio go-librespot[1940]: time="2026-02-25T00:01:25+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" 2月 25 00:01:25 volumio go-librespot[1940]: time="2026-02-25T00:01:25+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" 2月 25 00:01:25 volumio go-librespot[1940]: time="2026-02-25T00:01:25+09:00" level=info msg="zeroconf server listening on port 33269" 2月 25 00:01:26 volumio go-librespot[1940]: time="2026-02-25T00:01:26+09:00" level=debug msg="obtained new client token: AADu8fpeafTkuSvFbeAp9JltLJzy37gH94x20uPjw6LHq9BcnP2reLjx/Kz8PTOe+A6A+JLsnBdHONE8ePXmfct4YOx/v2/GWSWRrz4mR5TN6v4WgsQAaWLJT+6obA0wx6/Qn4xQ9jqsEPoUob3MRaeLRkp+a4hqR6BwpIU/oHFsIC38AlFJvpe7AU8FPwdA8jjbkOVAh9NGnxywolQj+sqiCnH109GcnN+VnMkZdzcRk4VJEqUJios=" 2月 25 00:01:26 volumio volumio[1484]: error: MPD error: The expression evaluated to a falsy value: 2月 25 00:01:26 volumio volumio[1484]: assert.ok(self.idling) 2月 25 00:01:26 volumio volumio[1484]: error: The expression evaluated to a falsy value: 2月 25 00:01:26 volumio volumio[1484]: assert.ok(self.idling) 2月 25 00:01:26 volumio go-librespot[1940]: time="2026-02-25T00:01:26+09:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" 2月 25 00:01:26 volumio ovpn-myvpn[978]: SENT CONTROL [opengw.net]: 'PUSH_REQUEST' (status=1) 2月 25 00:01:26 volumio go-librespot[1940]: time="2026-02-25T00:01:26+09:00" level=debug msg="connected to ap-gae2.spotify.com:443" 2月 25 00:01:26 volumio go-librespot[1940]: time="2026-02-25T00:01:26+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" 2月 25 00:01:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE 2月 25 00:01:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. 2月 25 00:01:26 volumio volumio[1484]: info: MPD running with PID1892 2月 25 00:01:26 volumio volumio[1484]: ,establishing connection 2月 25 00:01:26 volumio volumio[1484]: error: updateQueue error: null 2月 25 00:01:26 volumio volumio[1484]: info: Starting Shairport Sync 2月 25 00:01:26 volumio volumio[1484]: info: Starting Shairport Sync 2月 25 00:01:26 volumio volumio[1484]: info: Starting Shairport Sync 2月 25 00:01:26 volumio sudo[1983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync 2月 25 00:01:26 volumio sudo[1985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync 2月 25 00:01:26 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:26 volumio sudo[1981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync 2月 25 00:01:26 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:27 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:27 volumio volumio[1484]: error: updateQueue error: null 2月 25 00:01:27 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... 2月 25 00:01:27 volumio systemd[1]: shairport-sync.service: Deactivated successfully. 2月 25 00:01:27 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 2月 25 00:01:27 volumio systemd[1]: shairport-sync.service: Consumed 1.902s CPU time. 2月 25 00:01:27 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 2月 25 00:01:27 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:27 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... 2月 25 00:01:27 volumio systemd[1]: shairport-sync.service: Deactivated successfully. 2月 25 00:01:27 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 2月 25 00:01:27 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 2月 25 00:01:27 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:27 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:27 volumio volumio[1484]: info: Shairport-Sync Started 2月 25 00:01:27 volumio volumio[1484]: Error adding Membership: Error: addMembership EINVAL 2月 25 00:01:27 volumio volumio[1484]: info: Shairport-Sync Started 2月 25 00:01:27 volumio volumio[1484]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ 2月 25 00:01:27 volumio volumio[1484]: SPOTIFY: BQBRMNtg-IXMmHVQCDKs1F6AYZMV9-FaNiBLwRwJn7nnYym-DB7D3C16wmeSM5eYwkM49o_hsgzt8TL-2dAeKYOZDaR_qVBtBfAoF3KZhbcvDXJInNeOPCJcZf1QdYi8qPObXr5e7gf2bg3aW-AOkLgNyx0q-X1pFFqOp3BHOBgGE2y18b5pzJF7nL2FcPwJDgMA5QOHdpge0QILSUHD5AoGkMW1d7ivn2rXsr_Cli639OdWSwNu6zKTj1K9KxcqNtiYO4YnXbCWVwn3R-sAEVbWHMmKI93vh7VZKAp9iNIjrl7kWpTg_WOTaP89 2月 25 00:01:27 volumio volumio[1484]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ 2月 25 00:01:27 volumio volumio[1484]: info: New Spotify access token = BQBRMNtg-IXMmHVQCDKs1F6AYZMV9-FaNiBLwRwJn7nnYym-DB7D3C16wmeSM5eYwkM49o_hsgzt8TL-2dAeKYOZDaR_qVBtBfAoF3KZhbcvDXJInNeOPCJcZf1QdYi8qPObXr5e7gf2bg3aW-AOkLgNyx0q-X1pFFqOp3BHOBgGE2y18b5pzJF7nL2FcPwJDgMA5QOHdpge0QILSUHD5AoGkMW1d7ivn2rXsr_Cli639OdWSwNu6zKTj1K9KxcqNtiYO4YnXbCWVwn3R-sAEVbWHMmKI93vh7VZKAp9iNIjrl7kWpTg_WOTaP89 2月 25 00:01:27 volumio volumio[1484]: info: Spotify credentials grant success - running version from March 24, 2019 2月 25 00:01:27 volumio volumio[1484]: info: Shairport-Sync Started 2月 25 00:01:28 volumio volumio[1484]: info: go-librespot daemon successfully initialized 2月 25 00:01:28 volumio sudo[2012]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 2月 25 00:01:28 volumio sudo[2010]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 2月 25 00:01:28 volumio sudo[2010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:28 volumio sudo[2010]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:28 volumio sudo[2012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:28 volumio sudo[2012]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:29 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 2月 25 00:01:29 volumio sudo[2016]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 2月 25 00:01:29 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:29 volumio sudo[2018]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 2月 25 00:01:29 volumio sudo[2018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:29 volumio sudo[2018]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:29 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root 2月 25 00:01:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. 2月 25 00:01:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. 2月 25 00:01:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. 2月 25 00:01:29 volumio go-librespot[2022]: go-librespot daemon starting... 2月 25 00:01:29 volumio go-librespot[2023]: time="2026-02-25T00:01:29+09:00" level=info msg="running go-librespot 0.4.0" 2月 25 00:01:29 volumio go-librespot[2023]: time="2026-02-25T00:01:29+09:00" level=debug msg="app state loaded" 2月 25 00:01:29 volumio go-librespot[2023]: time="2026-02-25T00:01:29+09:00" level=info msg="api server listening on 127.0.0.1:9879" 2月 25 00:01:29 volumio volumio[1484]: info: CoreCommandRouter::volumioGetState 2月 25 00:01:29 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:30 volumio ovpn-myvpn[978]: PUSH: Received control message: 'PUSH_REPLY,ping 3,ping-restart 10,ifconfig 10.239.197.65 10.239.197.66,dhcp-option DNS 10.239.254.254,dhcp-option DNS 8.8.8.8,route-gateway 10.239.197.66,redirect-gateway def1' 2月 25 00:01:30 volumio ovpn-myvpn[978]: OPTIONS IMPORT: --ifconfig/up options modified 2月 25 00:01:30 volumio ovpn-myvpn[978]: OPTIONS IMPORT: route options modified 2月 25 00:01:30 volumio ovpn-myvpn[978]: OPTIONS IMPORT: route-related options modified 2月 25 00:01:30 volumio ovpn-myvpn[978]: OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified 2月 25 00:01:30 volumio ovpn-myvpn[978]: OPTIONS ERROR: failed to negotiate cipher with server. Add the server's cipher ('AES-128-CBC') to --data-ciphers (currently 'AES-256-GCM:AES-128-GCM:CHACHA20-POLY1305') if you want to connect to this server. 2月 25 00:01:30 volumio ovpn-myvpn[978]: ERROR: Failed to apply push options 2月 25 00:01:30 volumio ovpn-myvpn[978]: Failed to open tun/tap interface 2月 25 00:01:30 volumio ovpn-myvpn[978]: SIGUSR1[soft,process-push-msg-failed] received, process restarting 2月 25 00:01:30 volumio ovpn-myvpn[978]: Restart pause, 20 second(s) 2月 25 00:01:30 volumio volumio[1484]: verbose: New Socket.io Connection to 192.168.1.108 from 192.168.1.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 2月 25 00:01:30 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled 2月 25 00:01:31 volumio volumio[1484]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory 2月 25 00:01:31 volumio volumio[1484]: SPOTIFY: User informations: {"country":"JP","display_name":"Ken.north2","email":"ken1.north2@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31yhzttx3ny6duauhx42xcj6wjhm"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31yhzttx3ny6duauhx42xcj6wjhm","id":"31yhzttx3ny6duauhx42xcj6wjhm","images":[],"product":"premium","type":"user","uri":"spotify:user:31yhzttx3ny6duauhx42xcj6wjhm"} 2月 25 00:01:31 volumio volumio[1484]: info: Spotify Successfully logged in 2月 25 00:01:31 volumio volumio[1484]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 2月 25 00:01:31 volumio volumio[1484]: info: [1771945291135] CoreMusicLibrary::Adding element Spotify 2月 25 00:01:31 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 2月 25 00:01:31 volumio volumio[1484]: Cannot find translation for source YouTube2 2月 25 00:01:31 volumio volumio[1484]: Cannot find translation for source Spotify 2月 25 00:01:31 volumio volumio[1484]: info: Initializing connection to go-librespot Websocket 2月 25 00:01:32 volumio go-librespot[2023]: time="2026-02-25T00:01:32+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" 2月 25 00:01:32 volumio go-librespot[2023]: time="2026-02-25T00:01:32+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" 2月 25 00:01:32 volumio go-librespot[2023]: time="2026-02-25T00:01:32+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" 2月 25 00:01:32 volumio go-librespot[2023]: time="2026-02-25T00:01:32+09:00" level=info msg="zeroconf server listening on port 43637" 2月 25 00:01:32 volumio volumio-remote-updater[731]: [2026-02-25 00:01:32] [connect] Successful connection 2月 25 00:01:33 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled 2月 25 00:01:34 volumio go-librespot[2023]: time="2026-02-25T00:01:34+09:00" level=debug msg="new websocket client" 2月 25 00:01:35 volumio volumio[1484]: info: Connection to go-librespot Websocket established 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::volumioGetVisibleSources 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::volumioGetState 2月 25 00:01:35 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom 2月 25 00:01:35 volumio volumio[1484]: info: Received Get System Info 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice 2月 25 00:01:35 volumio volumio[1484]: info: Discovery: Getting this device information 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::volumioGetState 2月 25 00:01:35 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::volumioGetState 2月 25 00:01:35 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:35 volumio volumio[1484]: info: Listing playlists 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus 2月 25 00:01:35 volumio volumio[1484]: info: Received Get System Info 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice 2月 25 00:01:35 volumio volumio[1484]: info: Discovery: Getting this device information 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::volumioGetState 2月 25 00:01:35 volumio volumio[1484]: info: CorePlayQueue::getTrack 0 2月 25 00:01:35 volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses 2月 25 00:01:35 volumio go-librespot[2023]: time="2026-02-25T00:01:35+09:00" level=debug msg="obtained new client token: AAAKkmO6/SfQNr0fvwqsBscRZPEgTqYiGkmCsH7qBrE+xqVzXt1Efw1Y2uraSN1lsXfvUS8++p9mZ4h9zVo5IxMhogj/r2fWHuM3b/eoGmaVhDkcv8P61zX7FOSFNfSBWRpEFYhnHjafPGeZjVNoKb1D3eVeIWeAon3+wD/+dKDHiVnQiuRrIohcxV7eiOCIsrngOHBDoDAZZUlP+blpR7O+C7jqmC8ZqbOlWYLs7LoIkweRBrsJaAj77w==" 2月 25 00:01:35 volumio volumio-remote-updater[731]: [2026-02-25 00:01:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771945292 101 2月 25 00:01:35 volumio go-librespot[2023]: time="2026-02-25T00:01:35+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" 2月 25 00:01:35 volumio volumio[1484]: 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: 21 2月 25 00:01:35 volumio go-librespot[2023]: time="2026-02-25T00:01:35+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" 2月 25 00:01:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE 2月 25 00:01:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. 2月 25 00:01:37 volumio volumio[1484]: info: Connection to go-librespot Websocket closed 2月 25 00:01:38 volumio volumio[1484]: info: Getting Spotify volume 2月 25 00:01:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. 2月 25 00:01:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. 2月 25 00:01:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. 2月 25 00:01:38 volumio go-librespot[2045]: go-librespot daemon starting... 2月 25 00:01:38 volumio go-librespot[2046]: time="2026-02-25T00:01:38+09:00" level=info msg="running go-librespot 0.4.0" 2月 25 00:01:38 volumio go-librespot[2046]: time="2026-02-25T00:01:38+09:00" level=debug msg="app state loaded" 2月 25 00:01:38 volumio go-librespot[2046]: time="2026-02-25T00:01:38+09:00" level=info msg="api server listening on 127.0.0.1:9879" 2月 25 00:01:39 volumio volumio[1484]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| 2月 25 00:01:39 volumio volumio[1484]: Error: connect ECONNREFUSED 127.0.0.1:9879 2月 25 00:01:39 volumio volumio[1484]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { 2月 25 00:01:39 volumio volumio[1484]: errno: -111, 2月 25 00:01:39 volumio volumio[1484]: code: 'ECONNREFUSED', 2月 25 00:01:39 volumio volumio[1484]: syscall: 'connect', 2月 25 00:01:39 volumio volumio[1484]: address: '127.0.0.1', 2月 25 00:01:39 volumio volumio[1484]: port: 9879, 2月 25 00:01:39 volumio volumio[1484]: response: undefined 2月 25 00:01:39 volumio volumio[1484]: } 2月 25 00:01:39 volumio volumio[1484]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| 2月 25 00:01:41 volumio go-librespot[2046]: time="2026-02-25T00:01:41+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" 2月 25 00:01:41 volumio go-librespot[2046]: time="2026-02-25T00:01:41+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" 2月 25 00:01:41 volumio go-librespot[2046]: time="2026-02-25T00:01:41+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" 2月 25 00:01:41 volumio go-librespot[2046]: time="2026-02-25T00:01:41+09:00" level=info msg="zeroconf server listening on port 35969" 2月 25 00:01:41 volumio go-librespot[2046]: time="2026-02-25T00:01:41+09:00" level=debug msg="obtained new client token: AADenu7hlb2CF+IVo9slRDG4loRs/0AI5A/EzBCgUaSbnVu4EhWIbD+0ELDXbJJ0qIRCvUt+7txLKPnvymR1+kheyGl4Dm+NqsQw/RCXALBydU8SlGln6O+GYM9NPa71JN5oGhpWIEdW6kwQoiJpm2nbo3R8gz4iVcq3xSgHSCC14A5ZTEYuv8QiwescDOrbyjKnoXZdY/5mYqgHLCB2eC3p+aKMAFIPkj8tpn3+we2F00aDiezhKD/ffw==" 2月 25 00:01:42 volumio go-librespot[2046]: time="2026-02-25T00:01:42+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" 2月 25 00:01:42 volumio go-librespot[2046]: time="2026-02-25T00:01:42+09:00" level=debug msg="completed keyexchange" 2月 25 00:01:42 volumio go-librespot[2046]: time="2026-02-25T00:01:42+09:00" level=debug msg="completed challenge" 2月 25 00:01:43 volumio go-librespot[2046]: time="2026-02-25T00:01:43+09:00" level=info msg="authenticated AP" username="31************************hm" 2月 25 00:01:43 volumio go-librespot[2046]: time="2026-02-25T00:01:43+09:00" level=info msg="authenticated Login5" username="31************************hm" 2月 25 00:01:43 volumio go-librespot[2046]: time="2026-02-25T00:01:43+09:00" level=debug msg="initializing zeroconf session" username="31************************hm" 2月 25 00:01:43 volumio sudo[2068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 00:00' 2月 25 00:01:43 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=debug msg="dealer connection opened" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=trace msg="starting accesspoint recv loop" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=trace msg="starting dealer recv loop" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=trace msg="received accesspoint ping" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=debug msg="received connection id: MzRkY2E0NzgtYzQ4...MDZBNjQ1QUY3Rg==" 2月 25 00:01:44 volumio go-librespot[2046]: time="2026-02-25T00:01:44+09:00" level=trace msg="received accesspoint pong ack" 2月 25 00:01:45 volumio go-librespot[2046]: time="2026-02-25T00:01:45+09:00" level=debug msg="put connect state because NEW_DEVICE" 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"