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"