3月 29 13:06:00 volumio cpufrequtils[844]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. 3月 29 13:06:00 volumio systemd[1]: Starting systemd-hostnamed.service - Hostname Service... 3月 29 13:06:00 volumio systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. 3月 29 13:06:00 volumio systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. 3月 29 13:06:00 volumio systemd[1]: Starting networking.service - Raise network interfaces... 3月 29 13:06:00 volumio kernel: cryptd: max_cpu_qlen set to 1000 3月 29 13:06:00 volumio wpa_supplicant[850]: Successfully initialized wpa_supplicant 3月 29 13:06:00 volumio systemd[1]: Started wpa_supplicant.service - WPA supplicant. 3月 29 13:06:00 volumio dhcpcd[799]: eth0: waiting for carrier 3月 29 13:06:00 volumio dhcpcd[799]: wlan0: waiting for carrier 3月 29 13:06:00 volumio avahi-daemon[719]: Server startup complete. Host name is volumio.local. Local service cookie is 4164246289. 3月 29 13:06:00 volumio systemd[1]: Finished networking.service - Raise network interfaces. 3月 29 13:06:00 volumio systemd[1]: Reached target network.target - Network. 3月 29 13:06:00 volumio systemd[1]: Reached target network-online.target - Network is Online. 3月 29 13:06:01 volumio systemd[1]: Starting apache2.service - The Apache HTTP Server... 3月 29 13:06:01 volumio systemd[1]: Starting exim4.service - LSB: exim Mail Transport Agent... 3月 29 13:06:01 volumio systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... 3月 29 13:06:01 volumio systemd[1]: Starting lighttpd.service - Lighttpd Daemon... 3月 29 13:06:01 volumio dbus-daemon[721]: [system] Successfully activated service 'org.freedesktop.hostname1' 3月 29 13:06:01 volumio systemd[1]: Starting nmbd.service - Samba NMB Daemon... 3月 29 13:06:01 volumio systemd[1]: Starting ntpsec.service - Network Time Service... 3月 29 13:06:01 volumio systemd[1]: Starting openvpn.service - OpenVPN service... 3月 29 13:06:01 volumio systemd[1]: Starting openvpn@myvpn.service - OpenVPN connection to myvpn... 3月 29 13:06:01 volumio systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... 3月 29 13:06:01 volumio systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... 3月 29 13:06:01 volumio systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... 3月 29 13:06:01 volumio bluetoothd[720]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled 3月 29 13:06:01 volumio bluetoothd[720]: bap: Operation not supported (95) 3月 29 13:06:01 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... 3月 29 13:06:01 volumio sm-notify[973]: Version 2.6.2 starting 3月 29 13:06:01 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 3月 29 13:06:01 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 3月 29 13:06:01 volumio systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... 3月 29 13:06:01 volumio systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. 3月 29 13:06:01 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... 3月 29 13:06:01 volumio systemd[1]: Finished openvpn.service - OpenVPN service. 3月 29 13:06:01 volumio systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. 3月 29 13:06:01 volumio systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. 3月 29 13:06:01 volumio systemd[1]: Started systemd-hostnamed.service - Hostname Service. 3月 29 13:06:01 volumio welcome[992]: Resolved ip:[0] 3月 29 13:06:01 volumio ntpd[987]: INIT: ntpd ntpsec-1.2.2: Starting 3月 29 13:06:01 volumio ntp-systemd-wrapper[987]: 2026-03-29T13:06:01 ntpd[987]: INIT: ntpd ntpsec-1.2.2: Starting 3月 29 13:06:01 volumio ntp-systemd-wrapper[987]: 2026-03-29T13:06:01 ntpd[987]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec 3月 29 13:06:01 volumio ntpd[987]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec 3月 29 13:06:01 volumio ifplugd(eth0)[1026]: ifplugd 0.28 initializing. 3月 29 13:06:01 volumio ifplugd(eth0)[1026]: Using interface eth0/B8:27:EB:00:E7:96 with driver (version: 6.12.47-v7+) 3月 29 13:06:01 volumio ovpn-myvpn[969]: 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. 3月 29 13:06:01 volumio ifplugd(eth0)[1026]: Using detection mode: SIOCETHTOOL 3月 29 13:06:01 volumio systemd[1]: Started ntpsec.service - Network Time Service. 3月 29 13:06:01 volumio ifplugd(eth0)[1026]: Initialization complete, link beat not detected. 3月 29 13:06:01 volumio ntpd[1022]: INIT: precision = 0.885 usec (-20) 3月 29 13:06:01 volumio ntpd[1022]: INIT: successfully locked into RAM 3月 29 13:06:02 volumio ovpn-myvpn[969]: Note: Kernel support for ovpn-dco missing, disabling data channel offload. 3月 29 13:06:02 volumio ovpn-myvpn[969]: OpenVPN 2.6.3 arm-unknown-linux-gnueabihf [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] [DCO] 3月 29 13:06:02 volumio ovpn-myvpn[969]: library versions: OpenSSL 3.0.18 30 Sep 2025, LZO 2.10 3月 29 13:06:02 volumio ovpn-myvpn[969]: DCO version: N/A 3月 29 13:06:02 volumio ntpd[1022]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf 3月 29 13:06:02 volumio ntpd[1022]: CONFIG: restrict nopeer ignored 3月 29 13:06:02 volumio ovpn-myvpn[969]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 3月 29 13:06:02 volumio systemd[1]: Started openvpn@myvpn.service - OpenVPN connection to myvpn. 3月 29 13:06:02 volumio ifplugd[959]: Network Interface Plugging Daemon...start eth0...done. 3月 29 13:06:02 volumio systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... 3月 29 13:06:02 volumio systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. 3月 29 13:06:02 volumio ntpd[1022]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature 3月 29 13:06:02 volumio ntpd[1022]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 3月 29 13:06:02 volumio ntpd[1022]: INIT: Using SO_TIMESTAMPNS(ns) 3月 29 13:06:02 volumio ntpd[1022]: IO: Listen and drop on 0 v6wildcard [::]:123 3月 29 13:06:02 volumio ntpd[1022]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 3月 29 13:06:02 volumio ntpd[1022]: IO: Listen normally on 2 lo 127.0.0.1:123 3月 29 13:06:02 volumio ntpd[1022]: IO: Listening on routing socket on fd #19 for interface updates 3月 29 13:06:02 volumio ovpn-myvpn[969]: RESOLVE: Cannot resolve host address: public-vpn-108.opengw.net:443 (System error) 3月 29 13:06:02 volumio ovpn-myvpn[969]: RESOLVE: Cannot resolve host address: public-vpn-108.opengw.net:443 (System error) 3月 29 13:06:02 volumio ovpn-myvpn[969]: Could not determine IPv4/IPv6 protocol 3月 29 13:06:02 volumio ovpn-myvpn[969]: SIGUSR1[soft,Could not determine IPv4/IPv6 protocol] received, process restarting 3月 29 13:06:02 volumio ovpn-myvpn[969]: Restart pause, 10 second(s) 3月 29 13:06:02 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. 3月 29 13:06:02 volumio systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. 3月 29 13:06:02 volumio systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... 3月 29 13:06:02 volumio sshd[1076]: Server listening on 0.0.0.0 port 22. 3月 29 13:06:02 volumio sshd[1076]: Server listening on :: port 22. 3月 29 13:06:02 volumio systemd[1]: Started ssh.service - OpenBSD Secure Shell server. 3月 29 13:06:02 volumio ntpd[1022]: INIT: This system has a 32-bit time_t. 3月 29 13:06:02 volumio ntpd[1022]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. 3月 29 13:06:02 volumio ntpd[1022]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes 3月 29 13:06:02 volumio ntpd[1022]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 3月 29 13:06:02 volumio ntpd[1022]: INIT: Running with OpenSSL 3.0.18 30 Sep 2025, 30000120 3月 29 13:06:02 volumio ntpd[1022]: NTSc: Using system default root certificates. 3月 29 13:06:02 volumio ntpd[1022]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory 3月 29 13:06:03 volumio systemd[1]: systemd-rfkill.service: Deactivated successfully. 3月 29 13:06:03 volumio exim4[958]: Starting MTA: 3月 29 13:06:03 volumio exim4[1301]: 2026-03-29 13:06:03 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=117 3月 29 13:06:03 volumio exim4[1301]: 2026-03-29 13:06:03 Warning: No server certificate defined; will use a selfsigned one. 3月 29 13:06:03 volumio exim4[1301]: Suggested action: either install a certificate or change tls_advertise_hosts option 3月 29 13:06:03 volumio exim4[1301]: 2026-03-29 13:06:03 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=117 3月 29 13:06:03 volumio exim4[1301]: exim: could not open panic log - aborting: see message(s) above 3月 29 13:06:03 volumio exim[1301]: [1\2] 2026-03-29 13:06:03 Warning: No server certificate defined; will use a selfsigned one. 3月 29 13:06:03 volumio exim[1301]: [2/2] Suggested action: either install a certificate or change tls_advertise_hosts option 3月 29 13:06:03 volumio systemd[1]: exim4.service: Control process exited, code=exited, status=1/FAILURE 3月 29 13:06:03 volumio exim[1301]: 2026-03-29 13:06:03 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=117 3月 29 13:06:03 volumio systemd[1]: exim4.service: Failed with result 'exit-code'. 3月 29 13:06:03 volumio exim[1301]: exim: could not open panic log - aborting: see message(s) above 3月 29 13:06:03 volumio systemd[1]: Failed to start exim4.service - LSB: exim Mail Transport Agent. 3月 29 13:06:03 volumio ntpd[1022]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 3月 29 13:06:03 volumio ntpd[1022]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 3月 29 13:06:03 volumio ntpd[1022]: DNS: dns_check: DNS error: -11, System error 3月 29 13:06:03 volumio ntpd[1022]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 3月 29 13:06:03 volumio bluealsa[858]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa 3月 29 13:06:03 volumio bluealsa[858]: main.c:663: Starting main dispatching loop 3月 29 13:06:03 volumio bluealsa[858]: main.c:118: Acquired D-Bus service name: org.bluealsa 3月 29 13:06:03 volumio bluealsa[858]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa 3月 29 13:06:03 volumio systemd[1]: Started bluealsa.service - BlueALSA service. 3月 29 13:06:03 volumio systemd[1]: Reached target bluetooth.target - Bluetooth Support. 3月 29 13:06:03 volumio bluealsa[858]: bluez.c:153: Registering media application: /org/bluez/hci0 3月 29 13:06:03 volumio bluealsa[858]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 3月 29 13:06:03 volumio bluealsa[858]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 3月 29 13:06:03 volumio bluealsa[858]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 3月 29 13:06:03 volumio bluealsa[858]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 3月 29 13:06:03 volumio dbus-daemon[721]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service' requested by ':1.6' (uid=0 pid=858 comm="/usr/bin/bluealsa -S -p a2dp-source -p a2dp-sink") 3月 29 13:06:03 volumio apachectl[1054]: 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 3月 29 13:06:03 volumio apachectl[1054]: (2)No such file or directory: AH02291: Cannot access directory '/var/log/apache2/' for main error log 3月 29 13:06:03 volumio apachectl[1054]: (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 3月 29 13:06:03 volumio apachectl[1054]: AH00014: Configuration check failed 3月 29 13:06:03 volumio apachectl[957]: Action 'start' failed. 3月 29 13:06:03 volumio apachectl[957]: The Apache error log may have more information. 3月 29 13:06:03 volumio systemd[1]: Starting upower.service - Daemon for power management... 3月 29 13:06:03 volumio systemd[1]: apache2.service: Control process exited, code=exited, status=1/FAILURE 3月 29 13:06:03 volumio systemd[1]: apache2.service: Failed with result 'exit-code'. 3月 29 13:06:03 volumio systemd[1]: Failed to start apache2.service - The Apache HTTP Server. 3月 29 13:06:04 volumio dbus-daemon[721]: [system] Successfully activated service 'org.freedesktop.UPower' 3月 29 13:06:04 volumio systemd[1]: Started upower.service - Daemon for power management. 3月 29 13:06:04 volumio bluetoothd[720]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 3月 29 13:06:04 volumio bluetoothd[720]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 3月 29 13:06:04 volumio bluetoothd[720]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 3月 29 13:06:04 volumio bluetoothd[720]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 3月 29 13:06:04 volumio systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. 3月 29 13:06:04 volumio systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. 3月 29 13:06:04 volumio volumio-remote-updater[731]: [2026-03-29 13:06:04] [info] asio async_connect error: asio.system:111 (Connection refused) 3月 29 13:06:04 volumio volumio-remote-updater[731]: [2026-03-29 13:06:04] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) 3月 29 13:06:04 volumio volumio-remote-updater[731]: [2026-03-29 13:06:04] [error] handle_connect error: Connection refused 3月 29 13:06:04 volumio ntpd[1022]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 3月 29 13:06:04 volumio ntpd[1022]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 3月 29 13:06:04 volumio ntpd[1022]: DNS: dns_check: DNS error: -11, System error 3月 29 13:06:04 volumio ntpd[1022]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 3月 29 13:06:04 volumio systemd[1]: Started lighttpd.service - Lighttpd Daemon. 3月 29 13:06:05 volumio nmbd[1342]: [2026/03/29 13:06:05.196449, 0] ../../source3/nmbd/nmbd.c:901(main) 3月 29 13:06:05 volumio nmbd[1342]: nmbd version 4.17.8-Raspbian started. 3月 29 13:06:05 volumio nmbd[1342]: Copyright Andrew Tridgell and the Samba Team 1992-2022 3月 29 13:06:05 volumio nmbd[1342]: [2026/03/29 13:06:05.205657, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) 3月 29 13:06:05 volumio nmbd[1342]: started asyncdns process 1348 3月 29 13:06:05 volumio nmbd[1342]: [2026/03/29 13:06:05.210140, 0] ../../lib/util/become_daemon.c:150(daemon_status) 3月 29 13:06:05 volumio nmbd[1342]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... 3月 29 13:06:05 volumio nmbd[1342]: [2026/03/29 13:06:05.211123, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) 3月 29 13:06:05 volumio nmbd[1342]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). 3月 29 13:06:05 volumio ntpd[1022]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 3月 29 13:06:05 volumio ntpd[1022]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 3月 29 13:06:05 volumio ntpd[1022]: DNS: dns_check: DNS error: -11, System error 3月 29 13:06:05 volumio ntpd[1022]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 3月 29 13:06:06 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 3月 29 13:06:06 volumio wireless.js[732]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow 3月 29 13:06:06 volumio ntpd[1022]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 3月 29 13:06:06 volumio ntpd[1022]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 3月 29 13:06:06 volumio ntpd[1022]: DNS: dns_check: DNS error: -11, System error 3月 29 13:06:06 volumio ntpd[1022]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 3月 29 13:06:06 volumio sudo[1375]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 3月 29 13:06:06 volumio sudo[1375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:06 volumio sudo[1375]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:06 volumio sudo[1379]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down 3月 29 13:06:06 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:07 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:07 volumio wireless.js[732]: WIRELESS.JS - INFO: Cleaning previous... 3月 29 13:06:07 volumio sudo[1386]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up 3月 29 13:06:07 volumio sudo[1386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:07 volumio sudo[1386]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:07 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled 3月 29 13:06:07 volumio wireless.js[732]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations 3月 29 13:06:07 volumio wireless.js[732]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 5ms 3月 29 13:06:07 volumio wireless.js[732]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:55:b2:c3) 3月 29 13:06:07 volumio sudo[1393]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get 3月 29 13:06:07 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:07 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:07 volumio sudo[1401]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan 3月 29 13:06:07 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:07 volumio bash[983]: setdatetime-helper: all HTTPS Date fallbacks failed 3月 29 13:06:07 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. 3月 29 13:06:07 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. 3月 29 13:06:08 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:08 volumio wireless.js[732]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: JP 3月 29 13:06:08 volumio sudo[1408]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP 3月 29 13:06:08 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:08 volumio sudo[1408]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:08 volumio wireless.js[732]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: JP 3月 29 13:06:08 volumio wireless.js[732]: WIRELESS.JS - INFO: Start wireless flow 3月 29 13:06:08 volumio wireless.js[732]: WIRELESS.JS - INFO: Stopped hotspot (if there).. 3月 29 13:06:08 volumio sudo[1417]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 3月 29 13:06:08 volumio sudo[1417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:08 volumio sudo[1417]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:08 volumio sudo[1419]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down 3月 29 13:06:08 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:08 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:08 volumio wireless.js[732]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations 3月 29 13:06:08 volumio wireless.js[732]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: b8:27:eb:55:b2:c3, USB: false) 3月 29 13:06:08 volumio wpa_supplicant[1425]: Successfully initialized wpa_supplicant 3月 29 13:06:08 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled 3月 29 13:06:09 volumio sudo[1431]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up 3月 29 13:06:09 volumio sudo[1431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:09 volumio sudo[1431]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:10 volumio wpa_supplicant[1428]: wlan0: Trying to associate with d8:07:b6:58:5f:79 (SSID='stranger_C163_2' freq=2412 MHz) 3月 29 13:06:10 volumio wpa_supplicant[1428]: wlan0: Associated with d8:07:b6:58:5f:79 3月 29 13:06:10 volumio wpa_supplicant[1428]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 3月 29 13:06:10 volumio wpa_supplicant[1428]: wlan0: WPA: Key negotiation completed with d8:07:b6:58:5f:79 [PTK=CCMP GTK=CCMP] 3月 29 13:06:10 volumio wpa_supplicant[1428]: wlan0: CTRL-EVENT-CONNECTED - Connection to d8:07:b6:58:5f:79 completed [id=0 id_str=] 3月 29 13:06:10 volumio dhcpcd[799]: wlan0: carrier acquired 3月 29 13:06:10 volumio dhcpcd[799]: wlan0: connected to Access Point: stranger_C163_2 3月 29 13:06:10 volumio dhcpcd[799]: wlan0: IAID eb:55:b2:c3 3月 29 13:06:10 volumio dhcpcd[799]: wlan0: adding address fe80::ebf2:e8fc:6c4c:87f 3月 29 13:06:10 volumio dhcpcd[799]: ipv6_addaddr1: Permission denied 3月 29 13:06:10 volumio dhcpcd[799]: wlan0: probing address 192.168.0.161/24 3月 29 13:06:10 volumio dhcpcd[799]: wlan0: soliciting an IPv6 router 3月 29 13:06:10 volumio wpa_supplicant[1428]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d8:07:b6:58:5f:79 reason=3 locally_generated=1 3月 29 13:06:10 volumio dhcpcd[799]: wlan0: carrier lost - roaming 3月 29 13:06:10 volumio wpa_supplicant[1428]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all 3月 29 13:06:10 volumio wpa_supplicant[1428]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD 3月 29 13:06:10 volumio wpa_supplicant[1428]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=JP 3月 29 13:06:10 volumio wireless.js[732]: WIRELESS.JS - INFO: DHCP IP fallback 3月 29 13:06:10 volumio wireless.js[732]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor 3月 29 13:06:10 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 3月 29 13:06:11 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms) 3月 29 13:06:11 volumio wpa_supplicant[1428]: wlan0: Trying to associate with d8:07:b6:58:5f:79 (SSID='stranger_C163_2' freq=2412 MHz) 3月 29 13:06:11 volumio wpa_supplicant[1428]: wlan0: Associated with d8:07:b6:58:5f:79 3月 29 13:06:11 volumio wpa_supplicant[1428]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 3月 29 13:06:11 volumio wpa_supplicant[1428]: wlan0: WPA: Key negotiation completed with d8:07:b6:58:5f:79 [PTK=CCMP GTK=CCMP] 3月 29 13:06:11 volumio wpa_supplicant[1428]: wlan0: CTRL-EVENT-CONNECTED - Connection to d8:07:b6:58:5f:79 completed [id=0 id_str=] 3月 29 13:06:11 volumio dhcpcd[799]: wlan0: carrier acquired 3月 29 13:06:11 volumio dhcpcd[799]: wlan0: IAID eb:55:b2:c3 3月 29 13:06:11 volumio dhcpcd[799]: wlan0: probing address 192.168.0.161/24 3月 29 13:06:11 volumio dhcpcd[799]: wlan0: soliciting an IPv6 router 3月 29 13:06:11 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> COMPLETED (duration: 512ms) 3月 29 13:06:11 volumio wireless.js[732]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful 3月 29 13:06:11 volumio wireless.js[732]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to d8:07:b6:58:5f:79 3月 29 13:06:11 volumio wireless.js[732]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow 3月 29 13:06:12 volumio ovpn-myvpn[969]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 3月 29 13:06:12 volumio ovpn-myvpn[969]: RESOLVE: Cannot resolve host address: public-vpn-108.opengw.net:443 (System error) 3月 29 13:06:12 volumio ovpn-myvpn[969]: RESOLVE: Cannot resolve host address: public-vpn-108.opengw.net:443 (System error) 3月 29 13:06:12 volumio ovpn-myvpn[969]: Could not determine IPv4/IPv6 protocol 3月 29 13:06:12 volumio ovpn-myvpn[969]: SIGUSR1[soft,Could not determine IPv4/IPv6 protocol] received, process restarting 3月 29 13:06:12 volumio ovpn-myvpn[969]: Restart pause, 10 second(s) 3月 29 13:06:12 volumio volumio-remote-updater[731]: [2026-03-29 13:06:12] [info] asio async_connect error: asio.system:111 (Connection refused) 3月 29 13:06:12 volumio volumio-remote-updater[731]: [2026-03-29 13:06:12] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) 3月 29 13:06:12 volumio volumio-remote-updater[731]: [2026-03-29 13:06:12] [error] handle_connect error: Connection refused 3月 29 13:06:12 volumio sudo[1460]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 3月 29 13:06:12 volumio sudo[1460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:12 volumio dhcpcd[799]: control command: /sbin/dhcpcd wlan0 3月 29 13:06:12 volumio sudo[1460]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:12 volumio dhcpcd[799]: control_free: No such file or directory 3月 29 13:06:14 volumio wireless.js[732]: WIRELESS.JS - INFO: Start ap 3月 29 13:06:15 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. 3月 29 13:06:15 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. 3月 29 13:06:15 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. 3月 29 13:06:15 volumio wireless.js[732]: WIRELESS.JS - INFO: Notified systemd about wireless ready 3月 29 13:06:15 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled 3月 29 13:06:15 volumio upmpdcli[1467]: Could not open config: /tmp/upmpdcli.conf 3月 29 13:06:15 volumio systemd[1]: Started wireless.service - Wireless Services. 3月 29 13:06:15 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE 3月 29 13:06:15 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. 3月 29 13:06:15 volumio systemd[1]: Started volumio.service - Volumio Backend Module. 3月 29 13:06:15 volumio systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... 3月 29 13:06:15 volumio systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. 3月 29 13:06:15 volumio systemd[1]: Received SIGRTMIN+21 from PID 300 (plymouthd). 3月 29 13:06:15 volumio systemd[1]: Received SIGRTMIN+21 from PID 300 (plymouthd). 3月 29 13:06:15 volumio systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. 3月 29 13:06:15 volumio systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. 3月 29 13:06:15 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. 3月 29 13:06:15 volumio systemd[1]: Reached target getty.target - Login Prompts. 3月 29 13:06:15 volumio volumio-cpu-tweak[1472]: Setting RT Priority for mpd 3月 29 13:06:15 volumio volumio-cpu-tweak[1487]: pid 35's current scheduling policy: SCHED_OTHER 3月 29 13:06:15 volumio volumio-cpu-tweak[1487]: pid 35's current scheduling priority: 0 3月 29 13:06:15 volumio volumio-cpu-tweak[1472]: Setting MPD Affinity 3月 29 13:06:15 volumio volumio-cpu-tweak[1488]: pid 3's current affinity mask: f 3月 29 13:06:15 volumio volumio-cpu-tweak[1472]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input 3月 29 13:06:15 volumio volumio-cpu-tweak[1472]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance 3月 29 13:06:15 volumio systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. 3月 29 13:06:15 volumio wireless.js[732]: WIRELESS.JS - INFO: trying... 3月 29 13:06:16 volumio sudo[1501]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r 3月 29 13:06:16 volumio sudo[1501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:16 volumio sudo[1501]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:16 volumio sudo[1504]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 3月 29 13:06:16 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:16 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:16 volumio wireless.js[732]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined 3月 29 13:06:16 volumio avahi-daemon[719]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.161. 3月 29 13:06:16 volumio dhcpcd[799]: wlan0: using static address 192.168.0.161/24 3月 29 13:06:16 volumio dhcpcd[799]: wlan0: adding route to 192.168.0.0/24 3月 29 13:06:16 volumio dhcpcd[799]: wlan0: adding default route via 192.168.0.254 3月 29 13:06:16 volumio avahi-daemon[719]: New relevant interface wlan0.IPv4 for mDNS. 3月 29 13:06:16 volumio avahi-daemon[719]: Registering new address record for 192.168.0.161 on wlan0.IPv4. 3月 29 13:06:16 volumio systemd[1]: Started nmbd.service - Samba NMB Daemon. 3月 29 13:06:16 volumio systemd[1]: Starting winbind.service - Samba Winbind Daemon... 3月 29 13:06:16 volumio systemd[1]: welcome.service: Deactivated successfully. 3月 29 13:06:16 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. 3月 29 13:06:16 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... 3月 29 13:06:16 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... 3月 29 13:06:16 volumio welcome[1528]: Resolved ip:[1] 192.168.0.161 3月 29 13:06:16 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. 3月 29 13:06:16 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. 3月 29 13:06:16 volumio wireless.js[732]: WIRELESS.JS - INFO: trying... 3月 29 13:06:17 volumio sudo[1543]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r 3月 29 13:06:17 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:17 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:17 volumio sudo[1546]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 3月 29 13:06:17 volumio sudo[1546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:17 volumio sudo[1546]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:17 volumio wireless.js[732]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.0.161, ipV6 is undefined 3月 29 13:06:17 volumio wireless.js[732]: WIRELESS.JS - INFO: Connected to SSID: stranger_C163_2 3月 29 13:06:17 volumio wireless.js[732]: WIRELESS.JS - INFO: It's done! AP 3月 29 13:06:17 volumio wireless.js[732]: WIRELESS.JS - INFO: Restarting avahi-daemon... 3月 29 13:06:17 volumio winbindd[1533]: [2026/03/29 13:06:17.434097, 0] ../../source3/winbindd/winbindd.c:1440(main) 3月 29 13:06:17 volumio winbindd[1533]: winbindd version 4.17.8-Raspbian started. 3月 29 13:06:17 volumio winbindd[1533]: Copyright Andrew Tridgell and the Samba Team 1992-2022 3月 29 13:06:17 volumio sudo[1551]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon 3月 29 13:06:17 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:06:17 volumio winbindd[1533]: [2026/03/29 13:06:17.458450, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) 3月 29 13:06:17 volumio winbindd[1533]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 3月 29 13:06:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... 3月 29 13:06:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. 3月 29 13:06:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 3月 29 13:06:17 volumio systemd[1]: shairport-sync.service: Consumed 1.460s CPU time. 3月 29 13:06:17 volumio avahi-daemon[719]: Got SIGTERM, quitting. 3月 29 13:06:17 volumio avahi-daemon[719]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.161. 3月 29 13:06:17 volumio avahi-daemon[719]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. 3月 29 13:06:17 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... 3月 29 13:06:17 volumio avahi-daemon[719]: avahi-daemon 0.8 exiting. 3月 29 13:06:17 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. 3月 29 13:06:17 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. 3月 29 13:06:17 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... 3月 29 13:06:17 volumio avahi-daemon[1554]: Process 719 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) 3月 29 13:06:17 volumio avahi-daemon[1554]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). 3月 29 13:06:17 volumio avahi-daemon[1554]: Successfully dropped root privileges. 3月 29 13:06:17 volumio avahi-daemon[1554]: avahi-daemon 0.8 starting up. 3月 29 13:06:17 volumio avahi-daemon[1554]: Successfully called chroot(). 3月 29 13:06:17 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. 3月 29 13:06:17 volumio avahi-daemon[1554]: Successfully dropped remaining capabilities. 3月 29 13:06:17 volumio avahi-daemon[1554]: No service file found in /etc/avahi/services. 3月 29 13:06:17 volumio avahi-daemon[1554]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.161. 3月 29 13:06:17 volumio avahi-daemon[1554]: New relevant interface wlan0.IPv4 for mDNS. 3月 29 13:06:17 volumio avahi-daemon[1554]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. 3月 29 13:06:17 volumio avahi-daemon[1554]: New relevant interface lo.IPv4 for mDNS. 3月 29 13:06:17 volumio avahi-daemon[1554]: Network interface enumeration completed. 3月 29 13:06:17 volumio avahi-daemon[1554]: Registering new address record for 192.168.0.161 on wlan0.IPv4. 3月 29 13:06:17 volumio avahi-daemon[1554]: Registering new address record for 127.0.0.1 on lo.IPv4. 3月 29 13:06:17 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 3月 29 13:06:17 volumio ntpd[1022]: IO: Listen normally on 3 wlan0 192.168.0.161:123 3月 29 13:06:17 volumio ntpd[1022]: IO: new interface(s) found: waking up resolver 3月 29 13:06:17 volumio ntpd[1022]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 3月 29 13:06:18 volumio nmbd[1342]: [2026/03/29 13:06:18.087325, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) 3月 29 13:06:18 volumio nmbd[1342]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.161 for name WORKGROUP<1d>. 3月 29 13:06:18 volumio nmbd[1342]: This response was from IP 192.168.0.54, reporting an IP address of 192.168.0.54. 3月 29 13:06:18 volumio nmbd[1342]: [2026/03/29 13:06:18.096889, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) 3月 29 13:06:18 volumio nmbd[1342]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.0.161 for name WORKGROUP<1d>. 3月 29 13:06:18 volumio nmbd[1342]: This response was from IP 192.168.0.54, reporting an IP address of 192.168.0.54. 3月 29 13:06:18 volumio nmbd[1342]: [2026/03/29 13:06:18.097297, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) 3月 29 13:06:18 volumio nmbd[1342]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.0.161 for name WORKGROUP<1d>. 3月 29 13:06:18 volumio nmbd[1342]: This response was from IP 192.168.0.54, reporting an IP address of 192.168.0.54. 3月 29 13:06:18 volumio avahi-daemon[1554]: Server startup complete. Host name is volumio.local. Local service cookie is 3896856960. 3月 29 13:06:18 volumio systemd[1]: Started winbind.service - Samba Winbind Daemon. 3月 29 13:06:18 volumio systemd[1]: Starting smbd.service - Samba SMB Daemon... 3月 29 13:06:18 volumio wireless.js[732]: WIRELESS.JS - INFO: Notified systemd about wireless ready 3月 29 13:06:19 volumio smbd[1593]: [2026/03/29 13:06:19.358053, 0] ../../source3/smbd/server.c:1741(main) 3月 29 13:06:19 volumio smbd[1593]: smbd version 4.17.8-Raspbian started. 3月 29 13:06:19 volumio smbd[1593]: Copyright Andrew Tridgell and the Samba Team 1992-2022 3月 29 13:06:21 volumio systemd[1]: Started smbd.service - Samba SMB Daemon. 3月 29 13:06:21 volumio systemd[1]: Reached target multi-user.target - Multi-User System. 3月 29 13:06:21 volumio systemd[1]: Reached target graphical.target - Graphical Interface. 3月 29 13:06:21 volumio systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... 3月 29 13:06:21 volumio systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. 3月 29 13:06:21 volumio systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. 3月 29 13:06:21 volumio systemd[1]: Startup finished in 16.047s (kernel) + 29.036s (userspace) = 45.084s. 3月 29 13:06:22 volumio ovpn-myvpn[969]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 3月 29 13:06:22 volumio ntpd[1022]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 208.88.66.8 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 46.250.253.227 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 129.250.35.251 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 160.16.113.133 3月 29 13:06:22 volumio ntpd[1022]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 3月 29 13:06:22 volumio volumio-remote-updater[731]: [2026-03-29 13:06:22] [connect] Successful connection 3月 29 13:06:22 volumio ovpn-myvpn[969]: TCP/UDP: Preserving recently used remote address: [AF_INET]219.100.37.98:443 3月 29 13:06:22 volumio ovpn-myvpn[969]: Socket Buffers: R=[131072->131072] S=[16384->16384] 3月 29 13:06:22 volumio ovpn-myvpn[969]: Attempting to establish TCP connection with [AF_INET]219.100.37.98:443 3月 29 13:06:22 volumio ovpn-myvpn[969]: TCP connection established with [AF_INET]219.100.37.98:443 3月 29 13:06:22 volumio ovpn-myvpn[969]: TCPv4_CLIENT link local: (not bound) 3月 29 13:06:22 volumio ovpn-myvpn[969]: TCPv4_CLIENT link remote: [AF_INET]219.100.37.98:443 3月 29 13:06:22 volumio ovpn-myvpn[969]: TLS: Initial packet from [AF_INET]219.100.37.98:443, sid=5a625e19 b2e21c2f 3月 29 13:06:22 volumio ovpn-myvpn[969]: WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this 3月 29 13:06:22 volumio ovpn-myvpn[969]: VERIFY OK: depth=2, C=US, O=Internet Security Research Group, CN=ISRG Root X1 3月 29 13:06:22 volumio ovpn-myvpn[969]: VERIFY OK: depth=1, C=US, O=Let's Encrypt, CN=R12 3月 29 13:06:22 volumio ovpn-myvpn[969]: VERIFY OK: depth=0, CN=opengw.net 3月 29 13:06:22 volumio ovpn-myvpn[969]: Control Channel: TLSv1.3, cipher TLSv1.3 TLS_AES_256_GCM_SHA384, peer certificate: 2048 bit RSA, signature: RSA-SHA256 3月 29 13:06:22 volumio ovpn-myvpn[969]: [opengw.net] Peer Connection Initiated with [AF_INET]219.100.37.98:443 3月 29 13:06:22 volumio ovpn-myvpn[969]: TLS: move_session: dest=TM_ACTIVE src=TM_INITIAL reinit_src=1 3月 29 13:06:22 volumio ovpn-myvpn[969]: TLS: tls_multi_process: initial untrusted session promoted to trusted 3月 29 13:06:22 volumio ntpd[1022]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 3月 29 13:06:22 volumio ntpd[1022]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 129.250.35.250 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 172.105.192.74 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 110.4.129.114 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 139.64.134.57 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 2001:678:8::123 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 2a06:a880:3:4915::123 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 240b:4009:272:9c06:def8:1b59:32ba:d5f 3月 29 13:06:22 volumio ntpd[1022]: DNS: Pool taking: 2001:19f0:7001:3fa1:5400:5ff:fee2:9881 3月 29 13:06:22 volumio ntpd[1022]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 3月 29 13:06:23 volumio ovpn-myvpn[969]: SENT CONTROL [opengw.net]: 'PUSH_REQUEST' (status=1) 3月 29 13:06:23 volumio ntpd[1022]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 3月 29 13:06:24 volumio volumio[1470]: info: ------------------------------------------- 3月 29 13:06:24 volumio volumio[1470]: info: ----- Volumio3 ---- 3月 29 13:06:24 volumio volumio[1470]: info: ------------------------------------------- 3月 29 13:06:24 volumio volumio[1470]: info: ----- System startup ---- 3月 29 13:06:24 volumio volumio[1470]: info: ------------------------------------------- 3月 29 13:06:25 volumio volumio[1470]: info: MYVOLUMIO Environment detected 3月 29 13:06:25 volumio volumio[1470]: info: Plugin folders cleanup 3月 29 13:06:25 volumio volumio[1470]: info: Scanning into folder /volumio/app/plugins/ 3月 29 13:06:25 volumio volumio[1470]: info: Scanning category audio_interface 3月 29 13:06:25 volumio volumio[1470]: info: Scanning category miscellanea 3月 29 13:06:25 volumio volumio[1470]: info: Scanning category music_service 3月 29 13:06:25 volumio volumio[1470]: info: Scanning category plugins.json 3月 29 13:06:25 volumio volumio[1470]: info: Scanning category system_controller 3月 29 13:06:25 volumio volumio[1470]: info: Scanning category user_interface 3月 29 13:06:25 volumio volumio[1470]: info: Scanning into folder /data/plugins/ 3月 29 13:06:25 volumio volumio[1470]: info: Scanning category music_service 3月 29 13:06:25 volumio volumio[1470]: info: Plugin folders cleanup completed 3月 29 13:06:25 volumio volumio[1470]: info: ------------------------------------------- 3月 29 13:06:25 volumio volumio[1470]: info: ----- Core plugins startup ---- 3月 29 13:06:25 volumio volumio[1470]: info: ------------------------------------------- 3月 29 13:06:25 volumio volumio[1470]: info: Loading plugins from folder /volumio/app/plugins/ 3月 29 13:06:25 volumio volumio[1470]: info: Adding plugin upnp to MyMusic Plugins 3月 29 13:06:25 volumio volumio[1470]: info: Adding plugin airplay_emulation to MyMusic Plugins 3月 29 13:06:25 volumio volumio[1470]: info: Adding plugin upnp_browser to MyMusic Plugins 3月 29 13:06:25 volumio volumio[1470]: info: Loading plugins from folder /data/plugins/ 3月 29 13:06:25 volumio volumio[1470]: info: Loading plugin "system"... 3月 29 13:06:25 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. 3月 29 13:06:25 volumio volumio[1470]: info: Loading plugin "appearance"... 3月 29 13:06:26 volumio ovpn-myvpn[969]: PUSH: Received control message: 'PUSH_REPLY,ping 3,ping-restart 10,ifconfig 10.239.11.229 10.239.11.230,dhcp-option DNS 10.239.254.254,dhcp-option DNS 8.8.8.8,route-gateway 10.239.11.230,redirect-gateway def1' 3月 29 13:06:26 volumio ovpn-myvpn[969]: OPTIONS IMPORT: --ifconfig/up options modified 3月 29 13:06:26 volumio ovpn-myvpn[969]: OPTIONS IMPORT: route options modified 3月 29 13:06:26 volumio ovpn-myvpn[969]: OPTIONS IMPORT: route-related options modified 3月 29 13:06:26 volumio ovpn-myvpn[969]: OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified 3月 29 13:06:26 volumio ovpn-myvpn[969]: 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. 3月 29 13:06:26 volumio ovpn-myvpn[969]: ERROR: Failed to apply push options 3月 29 13:06:26 volumio ovpn-myvpn[969]: Failed to open tun/tap interface 3月 29 13:06:26 volumio ovpn-myvpn[969]: SIGUSR1[soft,process-push-msg-failed] received, process restarting 3月 29 13:06:26 volumio ovpn-myvpn[969]: Restart pause, 10 second(s) 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "network"... 3月 29 13:06:28 volumio volumio[1470]: info: Refreshing Cached IP Addresses 3月 29 13:06:28 volumio sudo[1624]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 3月 29 13:06:28 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:28 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:28 volumio sudo[1622]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 3月 29 13:06:28 volumio sudo[1622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "services"... 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "volumio5onboarding"... 3月 29 13:06:28 volumio sudo[1622]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "alsa_controller"... 3月 29 13:06:28 volumio sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan 3月 29 13:06:28 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:28 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "wizard"... 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "networkfs"... 3月 29 13:06:28 volumio volumio[1470]: info: Starting Udev Watcher for removable devices 3月 29 13:06:28 volumio sudo[1655]: 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.0.54/ssd1 /mnt/NAS/ssd1 3月 29 13:06:28 volumio sudo[1653]: 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.0.54/ssd1_Music /mnt/NAS/ssd1_Music 3月 29 13:06:28 volumio sudo[1653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:28 volumio sudo[1655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:06:28 volumio volumio[1470]: info: Ignoring mount for partition: boot 3月 29 13:06:28 volumio volumio[1470]: info: Ignoring mount for partition: volumio 3月 29 13:06:28 volumio volumio[1470]: info: Ignoring mount for partition: volumio_data 3月 29 13:06:28 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "volumio_command_line_client"... 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "upnp"... 3月 29 13:06:28 volumio volumio[1470]: info: [1774757188622] Starting Upmpd Daemon 3月 29 13:06:28 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "my_music"... 3月 29 13:06:28 volumio volumio[1470]: info: Loading plugin "mpd"... 3月 29 13:06:28 volumio kernel: netfs: FS-Cache loaded 3月 29 13:06:28 volumio kernel: Key type cifs.spnego registered 3月 29 13:06:28 volumio kernel: Key type cifs.idmap registered 3月 29 13:06:28 volumio kernel: CIFS: Attempting to mount //192.168.0.54/ssd1_Music 3月 29 13:06:29 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:29 volumio volumio[1470]: info: Loading plugin "upnp_browser"... 3月 29 13:06:53 volumio ntpd[1022]: CLOCK: time stepped by 23.783829 3月 29 13:06:53 volumio ntpd[1022]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes 3月 29 13:06:54 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. 3月 29 13:06:54 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. 3月 29 13:06:54 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. 3月 29 13:06:54 volumio upmpdcli[1681]: Could not open config: /tmp/upmpdcli.conf 3月 29 13:06:54 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE 3月 29 13:06:54 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. 3月 29 13:06:55 volumio volumio[1470]: info: Starting UPNP Browser 3月 29 13:06:55 volumio volumio[1470]: info: Loading plugin "alarm-clock"... 3月 29 13:06:55 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. 3月 29 13:06:55 volumio volumio[1470]: info: Loading plugin "airplay_emulation"... 3月 29 13:06:55 volumio volumio[1470]: info: Starting Shairport Sync 3月 29 13:06:55 volumio volumio[1470]: info: Loading plugin "last_100"... 3月 29 13:06:55 volumio volumio[1470]: info: Loading plugin "webradio"... 3月 29 13:06:56 volumio volumio[1470]: info: Loading plugin "i2s_dacs"... 3月 29 13:06:56 volumio volumio[1470]: info: I2S DAC not set, start Auto-detection 3月 29 13:06:56 volumio volumio[1470]: info: Loading plugin "volumiodiscovery"... 3月 29 13:06:56 volumio volumio[1470]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. 3月 29 13:06:56 volumio node[1470]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. 3月 29 13:06:56 volumio volumio[1470]: *** WARNING *** Please fix your application to use the native API of Avahi! 3月 29 13:06:56 volumio node[1470]: *** WARNING *** Please fix your application to use the native API of Avahi! 3月 29 13:06:56 volumio volumio[1470]: *** WARNING *** For more information see 3月 29 13:06:56 volumio node[1470]: *** WARNING *** For more information see 3月 29 13:06:56 volumio volumio[1470]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. 3月 29 13:06:56 volumio node[1470]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. 3月 29 13:06:56 volumio volumio[1470]: *** WARNING *** Please fix your application to use the native API of Avahi! 3月 29 13:06:56 volumio node[1470]: *** WARNING *** Please fix your application to use the native API of Avahi! 3月 29 13:06:56 volumio volumio[1470]: *** WARNING *** For more information see 3月 29 13:06:56 volumio node[1470]: *** WARNING *** For more information see 3月 29 13:06:56 volumio volumio[1470]: info: Applying required configuration parameters for plugin volumiodiscovery 3月 29 13:06:56 volumio volumio[1470]: info: Discovery: Started advertising with name: Volumio 3月 29 13:06:56 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback 3月 29 13:06:56 volumio volumio[1470]: info: Loading plugin "spop"... 3月 29 13:06:57 volumio kernel: CIFS: VFS: BAD_NETWORK_NAME: \\192.168.0.54\ssd1_Music 3月 29 13:06:57 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 3月 29 13:06:57 volumio kernel: CIFS: Attempting to mount //192.168.0.54/ssd1 3月 29 13:06:57 volumio sudo[1653]: pam_unix(sudo:session): session closed for user root 3月 29 13:06:57 volumio ntpd[1022]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 3月 29 13:06:57 volumio ntpd[1022]: DNS: Pool taking: 45.77.20.103 3月 29 13:06:57 volumio ntpd[1022]: DNS: Pool taking: 85.121.122.62 3月 29 13:06:57 volumio ntpd[1022]: DNS: Pool taking: 142.91.108.61 3月 29 13:06:57 volumio ntpd[1022]: DNS: Pool taking: 162.159.200.123 3月 29 13:06:57 volumio ntpd[1022]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 3月 29 13:06:58 volumio ntpd[1022]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 3月 29 13:06:59 volumio volumio[1470]: info: Loading plugin "youtube2"... 3月 29 13:06:59 volumio ovpn-myvpn[969]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 3月 29 13:07:00 volumio volumio[1470]: info: Plugin ytcr is not enabled 3月 29 13:07:00 volumio volumio[1470]: info: Loading plugin "outputs"... 3月 29 13:07:00 volumio volumio[1470]: info: Loading plugin "albumart"... 3月 29 13:07:00 volumio volumio[1470]: info: Plugin example_plugin is not enabled 3月 29 13:07:00 volumio volumio[1470]: info: Loading plugin "inputs"... 3月 29 13:07:00 volumio volumio[1470]: info: Loading plugin "updater_comm"... 3月 29 13:07:01 volumio volumio-remote-updater[731]: [2026-03-29 13:07:01] [connect] Successful connection 3月 29 13:07:01 volumio sudo[1655]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:01 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... 3月 29 13:07:01 volumio volumio[1470]: info: Plugin mpdemulation is not enabled 3月 29 13:07:01 volumio volumio[1470]: info: Loading plugin "rest_api"... 3月 29 13:07:01 volumio volumio[1470]: info: Loading plugin "websocket"... 3月 29 13:07:01 volumio volumio[1470]: info: Starting Socket.io Server version 1.7.4 3月 29 13:07:01 volumio volumio[1470]: info: Loading i18n strings for locale ja 3月 29 13:07:01 volumio volumio[1470]: Updating browse sources language 3月 29 13:07:01 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 3月 29 13:07:02 volumio volumio[1693]: Forking 3 albumart workers 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::initPlayerControls 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:02 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 3月 29 13:07:03 volumio volumio[1470]: Express server listening on port 3000 3月 29 13:07:03 volumio volumio[1470]: [Metrics] WebUI: 17s 550.20ms 3月 29 13:07:03 volumio volumio[1470]: info: CoreStateMachine::resetVolumioState 3月 29 13:07:03 volumio volumio[1470]: info: CoreStateMachine::getcurrentVolume 3月 29 13:07:03 volumio volumio[1470]: info: CoreCommandRouter::volumioRetrievevolume 3月 29 13:07:03 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 3月 29 13:07:03 volumio sudo[1772]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 3月 29 13:07:03 volumio sudo[1772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:03 volumio sudo[1774]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 3月 29 13:07:03 volumio sudo[1774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:03 volumio sudo[1774]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:03 volumio sudo[1772]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:03 volumio volumio[1470]: info: Volumio Network Manager: Network status updated: 2 3月 29 13:07:03 volumio ntpd[1022]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 3月 29 13:07:03 volumio ntpd[1022]: DNS: Pool taking: 162.159.200.1 3月 29 13:07:03 volumio ntpd[1022]: DNS: Pool taking: 167.179.119.205 3月 29 13:07:03 volumio ntpd[1022]: DNS: Pool taking: 85.121.50.82 3月 29 13:07:03 volumio ntpd[1022]: DNS: Pool taking: 138.3.209.153 3月 29 13:07:03 volumio ntpd[1022]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 3月 29 13:07:03 volumio volumio[1470]: info: Cannot mount NAS ssd1_Music at system boot, trial number 1 ,retrying in 5 seconds 3月 29 13:07:04 volumio volumio[1470]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 3月 29 13:07:04 volumio volumio[1470]: info: CoreStateMachine::pushState 3月 29 13:07:04 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:04 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 3月 29 13:07:04 volumio volumio[1470]: info: CoreCommandRouter::volumioPushState 3月 29 13:07:04 volumio volumio[1470]: info: CoreStateMachine::updateTrackBlock 3月 29 13:07:04 volumio volumio[1470]: info: CorePlayQueue::getTrackBlock 3月 29 13:07:04 volumio volumio[1470]: info: CoreCommandRouter::volumioRetrievevolume 3月 29 13:07:05 volumio ovpn-myvpn[969]: TCP/UDP: Preserving recently used remote address: [AF_INET]219.100.37.98:443 3月 29 13:07:05 volumio ovpn-myvpn[969]: Socket Buffers: R=[131072->131072] S=[16384->16384] 3月 29 13:07:05 volumio ovpn-myvpn[969]: Attempting to establish TCP connection with [AF_INET]219.100.37.98:443 3月 29 13:07:05 volumio ovpn-myvpn[969]: TCP connection established with [AF_INET]219.100.37.98:443 3月 29 13:07:05 volumio ovpn-myvpn[969]: TCPv4_CLIENT link local: (not bound) 3月 29 13:07:05 volumio ovpn-myvpn[969]: TCPv4_CLIENT link remote: [AF_INET]219.100.37.98:443 3月 29 13:07:05 volumio ovpn-myvpn[969]: TLS: Initial packet from [AF_INET]219.100.37.98:443, sid=ea86c370 ea1108d7 3月 29 13:07:05 volumio volumio[1470]: info: Reloading queue from file 3月 29 13:07:05 volumio volumio[1470]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 3月 29 13:07:05 volumio volumio[1470]: info: CoreStateMachine::pushState 3月 29 13:07:05 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:05 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 3月 29 13:07:05 volumio volumio[1470]: info: CoreCommandRouter::volumioPushState 3月 29 13:07:05 volumio volumio[1470]: info: CoreStateMachine::setRepeat false single undefined 3月 29 13:07:05 volumio volumio[1470]: info: CoreStateMachine::pushState 3月 29 13:07:05 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:05 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 3月 29 13:07:05 volumio volumio[1470]: info: CoreCommandRouter::volumioPushState 3月 29 13:07:05 volumio volumio[1470]: info: CoreStateMachine::setRandom null 3月 29 13:07:05 volumio volumio[1470]: info: CoreStateMachine::pushState 3月 29 13:07:05 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:05 volumio volumio[1470]: info: CoreCommandRouter::volumioPushState 3月 29 13:07:05 volumio volumio[1470]: info: Setting Device type: Raspberry PI 3月 29 13:07:05 volumio volumio[1470]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb 3月 29 13:07:05 volumio volumio[1470]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 3月 29 13:07:05 volumio volumio[1470]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI 3月 29 13:07:05 volumio volumio[1470]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 3月 29 13:07:05 volumio volumio[1470]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 3月 29 13:07:05 volumio volumio-remote-updater[731]: [2026-03-29 13:07:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774757221 101 3月 29 13:07:06 volumio volumio[1470]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 3月 29 13:07:06 volumio sudo[1797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service 3月 29 13:07:06 volumio sudo[1797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:06 volumio volumio[1470]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 3月 29 13:07:06 volumio volumio[1470]: info: Completed loading Core Plugins 3月 29 13:07:06 volumio volumio[1470]: info: Preparing to generate the ALSA configuration file 3月 29 13:07:06 volumio volumio[1470]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf 3月 29 13:07:06 volumio volumio[1470]: info: Reading ALSA contributions from plugins. 3月 29 13:07:06 volumio volumio[1470]: info: Asound.conf file unchanged, so no further update is needed 3月 29 13:07:06 volumio volumio[1470]: info: Output device has changed, restarting MPD 3月 29 13:07:06 volumio volumio[1470]: info: Output device has changed, restarting Shairport Sync 3月 29 13:07:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 3月 29 13:07:06 volumio sudo[1800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf 3月 29 13:07:06 volumio sudo[1800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:06 volumio sudo[1802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service 3月 29 13:07:06 volumio sudo[1800]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:06 volumio sudo[1802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:07 volumio volumio[1470]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 3月 29 13:07:07 volumio volumio[1470]: info: ___________ START PLUGINS ___________ 3月 29 13:07:07 volumio volumio[1470]: info: ControllerMpd::onStart: Initializing MPD 3月 29 13:07:07 volumio volumio[1470]: info: Creating MPD Configuration file 3月 29 13:07:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. 3月 29 13:07:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... 3月 29 13:07:07 volumio sudo[1811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service 3月 29 13:07:07 volumio sudo[1811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:07 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 3月 29 13:07:07 volumio volumio[1470]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 3月 29 13:07:07 volumio volumio[1470]: info: [1774757227465] CoreMusicLibrary::Adding element DLNAサーバー 3月 29 13:07:07 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 3月 29 13:07:07 volumio sudo[1813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf 3月 29 13:07:07 volumio sudo[1813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:07 volumio sudo[1814]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log 3月 29 13:07:07 volumio sudo[1814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:07:07 volumio sudo[1819]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory 3月 29 13:07:07 volumio sudo[1814]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:07 volumio sudo[1816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service 3月 29 13:07:07 volumio sudo[1813]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:07 volumio volumio[1470]: info: UPNP Browser: Client initialized successfully 3月 29 13:07:07 volumio sudo[1816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:07 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. 3月 29 13:07:07 volumio sudo[1811]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:07 volumio systemd[1]: mpd.service: Deactivated successfully. 3月 29 13:07:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. 3月 29 13:07:07 volumio systemd[1]: mpd.socket: Deactivated successfully. 3月 29 13:07:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. 3月 29 13:07:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... 3月 29 13:07:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. 3月 29 13:07:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... 3月 29 13:07:07 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:07 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 3月 29 13:07:08 volumio sudo[1829]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log 3月 29 13:07:08 volumio sudo[1829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 3月 29 13:07:08 volumio sudo[1834]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory 3月 29 13:07:08 volumio sudo[1829]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:08 volumio volumio[1470]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 3月 29 13:07:08 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:08 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 3月 29 13:07:08 volumio volumio5-onboarding[1822]: time=2026-03-29T13:07:08.380+09:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z 3月 29 13:07:08 volumio volumio[1470]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 3月 29 13:07:08 volumio volumio[1470]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 3月 29 13:07:08 volumio volumio[1470]: info: [1774757228584] CoreMusicLibrary::Adding element Last_100 3月 29 13:07:08 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 3月 29 13:07:08 volumio volumio[1470]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 3月 29 13:07:08 volumio volumio[1470]: info: [1774757228649] CoreMusicLibrary::Adding element Webradio 3月 29 13:07:08 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 3月 29 13:07:08 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 3月 29 13:07:08 volumio volumio[1470]: info: Initializing BBC Radios 3月 29 13:07:09 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 3月 29 13:07:09 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 3月 29 13:07:09 volumio volumio[1470]: info: Creating Spotify config file 3月 29 13:07:09 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:09 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. 3月 29 13:07:09 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. 3月 29 13:07:09 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. 3月 29 13:07:09 volumio sudo[1797]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:13 volumio volumio[1470]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 3月 29 13:07:13 volumio volumio[1470]: info: [1774757233199] CoreMusicLibrary::Adding element YouTube2 3月 29 13:07:13 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources 3月 29 13:07:13 volumio volumio[1470]: Cannot find translation for source YouTube2 3月 29 13:07:13 volumio volumio[1470]: info: Volumio Calling Home 3月 29 13:07:13 volumio volumio[1736]: Starting albumart workers 3月 29 13:07:13 volumio volumio[1737]: Starting albumart workers 3月 29 13:07:14 volumio volumio[1735]: Starting albumart workers 3月 29 13:07:14 volumio sudo[1884]: 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.0.54/ssd1_Music /mnt/NAS/ssd1_Music 3月 29 13:07:14 volumio sudo[1884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:15 volumio kernel: CIFS: Attempting to mount //192.168.0.54/ssd1_Music 3月 29 13:07:15 volumio volumio[1470]: info: MPD Permissions set 3月 29 13:07:15 volumio volumio[1470]: info: MPD Permissions set 3月 29 13:07:15 volumio volumio[1470]: info: Upmpdcli Daemon Started 3月 29 13:07:15 volumio volumio[1470]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 3月 29 13:07:15 volumio volumio[1470]: info: Spotify config file written 3月 29 13:07:15 volumio sudo[1894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service 3月 29 13:07:16 volumio mpd[1835]: 2026-03-29T13:07:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg 3月 29 13:07:16 volumio volumio[1470]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 3月 29 13:07:16 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:16 volumio volumio[1470]: info: Received Get System Info 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice 3月 29 13:07:16 volumio volumio[1470]: info: Discovery: Getting this device information 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState 3月 29 13:07:16 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses 3月 29 13:07:16 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. 3月 29 13:07:16 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. 3月 29 13:07:16 volumio volumio5-onboarding[1822]: time=2026-03-29T13:07:16.284+09:00 level=INFO msg="system info for 97b3b0dcbfda2f97eee478b29c80c4f8" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 3月 29 13:07:16 volumio go-librespot[1896]: go-librespot daemon starting... 3月 29 13:07:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. 3月 29 13:07:16 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:16 volumio systemd[1]: Started mpd.service - Music Player Daemon. 3月 29 13:07:16 volumio sudo[1816]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:16 volumio sudo[1802]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio5-onboarding[1822]: time=2026-03-29T13:07:16.465+09:00 level=INFO msg="bootstrapping state" hasInternet=true 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:16 volumio volumio[1470]: info: No need to fix Spotify hosts 3月 29 13:07:16 volumio volumio[1470]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 3月 29 13:07:17 volumio go-librespot[1897]: time="2026-03-29T13:07:17+09:00" level=info msg="running go-librespot 0.4.0" 3月 29 13:07:17 volumio go-librespot[1897]: time="2026-03-29T13:07:17+09:00" level=debug msg="app state loaded" 3月 29 13:07:17 volumio go-librespot[1897]: time="2026-03-29T13:07:17+09:00" level=info msg="api server listening on 127.0.0.1:9879" 3月 29 13:07:17 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 3月 29 13:07:17 volumio sudo[1884]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:17 volumio volumio[1470]: info: Received Get System Info 3月 29 13:07:17 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo 3月 29 13:07:17 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice 3月 29 13:07:17 volumio volumio[1470]: info: Discovery: Getting this device information 3月 29 13:07:17 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState 3月 29 13:07:17 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:17 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses 3月 29 13:07:17 volumio volumio[1470]: info: Completed starting Core Plugins 3月 29 13:07:17 volumio volumio[1470]: info: ------------------------------------------- 3月 29 13:07:17 volumio volumio[1470]: info: ----- MyVolumio plugins startup ---- 3月 29 13:07:17 volumio volumio[1470]: info: ------------------------------------------- 3月 29 13:07:17 volumio volumio[1470]: info: [MyVolumio PluginManager] Fetching plans data.... 3月 29 13:07:17 volumio volumio-remote-updater[731]: Test mode disabled 3月 29 13:07:17 volumio volumio-remote-updater[731]: Alpha mode disabled 3月 29 13:07:17 volumio volumio-remote-updater[731]: Alpha legacy test mode disabled 3月 29 13:07:17 volumio volumio[1470]: info: Cannot mount NAS ssd1_Music at system boot, trial number 2 ,retrying in 5 seconds 3月 29 13:07:17 volumio volumio[1470]: info: Starting Shairport Sync 3月 29 13:07:17 volumio volumio[1470]: info: Starting Shairport Sync 3月 29 13:07:17 volumio volumio[1470]: info: Starting Shairport Sync 3月 29 13:07:17 volumio sudo[1923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync 3月 29 13:07:17 volumio sudo[1921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync 3月 29 13:07:17 volumio sudo[1919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync 3月 29 13:07:17 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:17 volumio sudo[1923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:17 volumio sudo[1921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... 3月 29 13:07:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. 3月 29 13:07:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 3月 29 13:07:17 volumio systemd[1]: shairport-sync.service: Consumed 1.564s CPU time. 3月 29 13:07:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 3月 29 13:07:18 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:18 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... 3月 29 13:07:18 volumio systemd[1]: shairport-sync.service: Deactivated successfully. 3月 29 13:07:18 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 3月 29 13:07:18 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 3月 29 13:07:18 volumio sudo[1921]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:18 volumio sudo[1923]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:18 volumio volumio[1470]: error: MPD error: The expression evaluated to a falsy value: 3月 29 13:07:18 volumio volumio[1470]: assert.ok(self.idling) 3月 29 13:07:18 volumio volumio[1470]: error: The expression evaluated to a falsy value: 3月 29 13:07:18 volumio volumio[1470]: assert.ok(self.idling) 3月 29 13:07:18 volumio volumio[1470]: error: updateQueue error: null 3月 29 13:07:18 volumio volumio[1470]: info: Shairport-Sync Started 3月 29 13:07:18 volumio volumio[1470]: Error adding Membership: Error: addMembership EINVAL 3月 29 13:07:18 volumio volumio[1470]: info: Shairport-Sync Started 3月 29 13:07:18 volumio volumio[1470]: info: Shairport-Sync Started 3月 29 13:07:18 volumio volumio[1470]: info: MPD running with PID1835 3月 29 13:07:18 volumio volumio[1470]: ,establishing connection 3月 29 13:07:18 volumio volumio[1470]: error: updateQueue error: null 3月 29 13:07:19 volumio volumio[1470]: info: Discovery: adding 8bcbac9c-eed5-4c38-bccc-98f0741bf9f5 3月 29 13:07:19 volumio volumio[1470]: info: Discovery: Found device Volumio 3月 29 13:07:19 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState 3月 29 13:07:19 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:20 volumio volumio[1470]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 3月 29 13:07:20 volumio volumio[1470]: info: go-librespot daemon successfully initialized 3月 29 13:07:20 volumio volumio[1470]: info: Discovery: this is already registered, 8bcbac9c-eed5-4c38-bccc-98f0741bf9f5 3月 29 13:07:20 volumio volumio[1470]: info: Discovery: Found device Volumio 3月 29 13:07:20 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState 3月 29 13:07:20 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:21 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState 3月 29 13:07:21 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:21 volumio volumio[1470]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 3月 29 13:07:21 volumio volumio[1470]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.131 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 3月 29 13:07:22 volumio sudo[1972]: 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.0.54/ssd1_Music /mnt/NAS/ssd1_Music 3月 29 13:07:22 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:22 volumio kernel: CIFS: Attempting to mount //192.168.0.54/ssd1_Music 3月 29 13:07:23 volumio volumio[1470]: info: Volumio called home 3月 29 13:07:23 volumio volumio[1470]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory 3月 29 13:07:24 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:24 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 3月 29 13:07:24 volumio volumio[1470]: info: Initializing connection to go-librespot Websocket 3月 29 13:07:27 volumio go-librespot[1897]: time="2026-03-29T13:07:27+09:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" 3月 29 13:07:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE 3月 29 13:07:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. 3月 29 13:07:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. 3月 29 13:07:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. 3月 29 13:07:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. 3月 29 13:07:30 volumio go-librespot[1986]: go-librespot daemon starting... 3月 29 13:07:30 volumio go-librespot[1987]: time="2026-03-29T13:07:30+09:00" level=info msg="running go-librespot 0.4.0" 3月 29 13:07:30 volumio go-librespot[1987]: time="2026-03-29T13:07:30+09:00" level=debug msg="app state loaded" 3月 29 13:07:30 volumio go-librespot[1987]: time="2026-03-29T13:07:30+09:00" level=info msg="api server listening on 127.0.0.1:9879" 3月 29 13:07:30 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled 3月 29 13:07:31 volumio volumio[1470]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

  • Fix for missing PWM backlight module
  • Fix for crash loop on Onboarding plugin

NEW ADDITIONS

  • Bump RPi/CM4 kernel to 6.12.74

","title":"Update v4.119","updateavailable":true} 3月 29 13:07:31 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache 3月 29 13:07:32 volumio volumio[1470]: info: Cannot mount NAS ssd1_Music at system boot, trial number 3 ,retrying in 5 seconds 3月 29 13:07:36 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus 3月 29 13:07:36 volumio volumio5-onboarding[1822]: time=2026-03-29T13:07:36.507+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" 3月 29 13:07:36 volumio volumio5-onboarding[1822]: time=2026-03-29T13:07:36.509+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" 3月 29 13:07:36 volumio volumio5-onboarding[1822]: time=2026-03-29T13:07:36.509+09:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" 3月 29 13:07:36 volumio go-librespot[1987]: time="2026-03-29T13:07:36+09:00" level=debug msg="new websocket client" 3月 29 13:07:36 volumio ovpn-myvpn[969]: Connection reset, restarting [-1] 3月 29 13:07:36 volumio ovpn-myvpn[969]: SIGUSR1[soft,connection-reset] received, process restarting 3月 29 13:07:36 volumio ovpn-myvpn[969]: Restart pause, 10 second(s) 3月 29 13:07:37 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus 3月 29 13:07:38 volumio volumio[1470]: info: Connection to go-librespot Websocket established 3月 29 13:07:38 volumio volumio[1470]: error: MyVolumio Plugin failed to start in a timely fashion 3月 29 13:07:38 volumio volumio[1470]: [Metrics] CommandRouter: 50s 508.83ms 3月 29 13:07:38 volumio volumio[1470]: info: CoreCommandRouter::volumiosetStartupVolume 3月 29 13:07:38 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 3月 29 13:07:38 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 3月 29 13:07:38 volumio volumio[1470]: info: CoreCommandRouter::Close All Modals sent 3月 29 13:07:38 volumio sudo[2019]: 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.0.54/ssd1_Music /mnt/NAS/ssd1_Music 3月 29 13:07:38 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:38 volumio volumio[1470]: info: CoreCommandRouter::Close All Modals sent 3月 29 13:07:38 volumio kernel: CIFS: Attempting to mount //192.168.0.54/ssd1_Music 3月 29 13:07:38 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 3月 29 13:07:38 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:40 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus 3月 29 13:07:40 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable 3月 29 13:07:40 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus 3月 29 13:07:40 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect 3月 29 13:07:42 volumio upmpdcli[2028]: writing RSA key 3月 29 13:07:44 volumio volumio[1470]: info: Received Get System Info 3月 29 13:07:44 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo 3月 29 13:07:44 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice 3月 29 13:07:44 volumio volumio[1470]: info: Discovery: Getting this device information 3月 29 13:07:44 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState 3月 29 13:07:44 volumio volumio[1470]: info: CorePlayQueue::getTrack 0 3月 29 13:07:44 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses 3月 29 13:07:44 volumio volumio[1470]: info: Cannot mount NAS ssd1_Music at system boot, trial number 4 ,retrying in 5 seconds 3月 29 13:07:44 volumio volumio[1470]: info: Getting Spotify volume 3月 29 13:07:44 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 3月 29 13:07:44 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 3月 29 13:07:44 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled 3月 29 13:07:46 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard 3月 29 13:07:46 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard 3月 29 13:07:46 volumio volumio5-onboarding[1822]: time=2026-03-29T13:07:46.258+09:00 level=INFO msg="enabling local network discovery" 3月 29 13:07:46 volumio volumio5-onboarding[1822]: time=2026-03-29T13:07:46.290+09:00 level=INFO msg="enabling BLE discovery" 3月 29 13:07:46 volumio volumio[1470]: info: BOOT COMPLETED 3月 29 13:07:46 volumio ovpn-myvpn[969]: WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info. 3月 29 13:07:47 volumio volumio5-onboarding[1822]: time=2026-03-29T13:07:47.294+09:00 level=INFO msg="service successfully established" component=discovery/localnet 3月 29 13:07:47 volumio go-librespot[1987]: time="2026-03-29T13:07:47+09:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" 3月 29 13:07:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE 3月 29 13:07:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. 3月 29 13:07:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. 3月 29 13:07:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. 3月 29 13:07:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. 3月 29 13:07:50 volumio go-librespot[2069]: go-librespot daemon starting... 3月 29 13:07:50 volumio go-librespot[2070]: time="2026-03-29T13:07:50+09:00" level=info msg="running go-librespot 0.4.0" 3月 29 13:07:50 volumio go-librespot[2070]: time="2026-03-29T13:07:50+09:00" level=debug msg="app state loaded" 3月 29 13:07:50 volumio go-librespot[2070]: time="2026-03-29T13:07:50+09:00" level=info msg="api server listening on 127.0.0.1:9879" 3月 29 13:07:51 volumio sudo[2077]: 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.0.54/ssd1_Music /mnt/NAS/ssd1_Music 3月 29 13:07:51 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 3月 29 13:07:52 volumio kernel: CIFS: Attempting to mount //192.168.0.54/ssd1_Music 3月 29 13:07:52 volumio go-librespot[2070]: time="2026-03-29T13:07:52+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]" 3月 29 13:07:52 volumio go-librespot[2070]: time="2026-03-29T13:07:52+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]" 3月 29 13:07:52 volumio go-librespot[2070]: time="2026-03-29T13:07:52+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]" 3月 29 13:07:52 volumio go-librespot[2070]: time="2026-03-29T13:07:52+09:00" level=info msg="zeroconf server listening on port 33249" 3月 29 13:07:52 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root 3月 29 13:07:52 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 3月 29 13:07:53 volumio volumio[1470]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| 3月 29 13:07:53 volumio volumio[1470]: Error: socket hang up 3月 29 13:07:53 volumio volumio[1470]: at connResetException (node:internal/errors:720:14) 3月 29 13:07:53 volumio volumio[1470]: at Socket.socketOnEnd (node:_http_client:519:23) 3月 29 13:07:53 volumio volumio[1470]: at Socket.emit (node:events:526:35) 3月 29 13:07:53 volumio volumio[1470]: at endReadableNT (node:internal/streams/readable:1376:12) 3月 29 13:07:53 volumio volumio[1470]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { 3月 29 13:07:53 volumio volumio[1470]: code: 'ECONNRESET', 3月 29 13:07:53 volumio volumio[1470]: response: undefined 3月 29 13:07:53 volumio volumio[1470]: } 3月 29 13:07:53 volumio volumio[1470]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| 3月 29 13:07:55 volumio go-librespot[2070]: time="2026-03-29T13:07:55+09:00" level=debug msg="obtained new client token: AAAtbdnVoLaunWht5/7Pzd1fQ9lo6woRk9i1au6mFYGSfIyqO6bbxB6GcSNwBEHNqBCy6hUz5Ve7lalPLjV3u/S7oznuW4Eq592fl9eVgfyqTvMOjZGOwRAXQmgzZS74Et5eqXlcPpiiyw0MZRt1gNA+unXWz8k7+CL/Iyvik71SAYGybZQdgvaRt5P85yxaD843NKy0eLvKP5DpMFhy1rb9IoFH2LirbsrRmwGgw574bKn9pJo6Pp1TWdRw" 3月 29 13:07:55 volumio go-librespot[2070]: time="2026-03-29T13:07:55+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" 3月 29 13:07:57 volumio ovpn-myvpn[969]: TCP/UDP: Preserving recently used remote address: [AF_INET]219.100.37.98:443 3月 29 13:07:57 volumio ovpn-myvpn[969]: Socket Buffers: R=[131072->131072] S=[16384->16384] 3月 29 13:07:57 volumio ovpn-myvpn[969]: Attempting to establish TCP connection with [AF_INET]219.100.37.98:443 3月 29 13:07:57 volumio ovpn-myvpn[969]: TCP connection established with [AF_INET]219.100.37.98:443 3月 29 13:07:57 volumio ovpn-myvpn[969]: TCPv4_CLIENT link local: (not bound) 3月 29 13:07:57 volumio ovpn-myvpn[969]: TCPv4_CLIENT link remote: [AF_INET]219.100.37.98:443 3月 29 13:07:57 volumio go-librespot[2070]: time="2026-03-29T13:07:57+09:00" level=debug msg="completed keyexchange" 3月 29 13:07:57 volumio go-librespot[2070]: time="2026-03-29T13:07:57+09:00" level=debug msg="completed challenge" 3月 29 13:07:57 volumio ovpn-myvpn[969]: TLS: Initial packet from [AF_INET]219.100.37.98:443, sid=85a3db6a 3b228d61 3月 29 13:07:59 volumio sudo[2121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 13:06' 3月 29 13:07:59 volumio sudo[2121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"