-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Tue 2025-12-23 17:51:41 CST. -- Dec 23 17:50:51 so1905-b6d5 systemd[1]: Started Network Time Synchronization. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Reached target System Initialization. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Reached target System Time Set. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Reached target System Time Synchronized. Dec 23 17:50:51 so1905-b6d5 systemd-resolved[2151]: Positive Trust Anchors: Dec 23 17:50:51 so1905-b6d5 systemd-resolved[2151]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 23 17:50:51 so1905-b6d5 systemd-resolved[2151]: Negative trust anchors: 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Dec 23 17:50:51 so1905-b6d5 systemd[1]: Started Daily apt download activities. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Started Daily apt upgrade and clean activities. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Started Periodic ext4 Online Metadata Check for All Filesystems. Dec 23 17:50:51 so1905-b6d5 systemd-resolved[2151]: Using system hostname 'so1905-b6d5'. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Started Discard unused blocks once a week. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Started Daily rotation of log files. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Started Message of the Day. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Reached target Timers. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Reached target Sockets. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Reached target Basic System. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Condition check resulted in Manage Sound Card State (restore and store) being skipped. Dec 23 17:50:51 so1905-b6d5 systemd[1]: Starting Save/Restore Sound Card State... Dec 23 17:50:51 so1905-b6d5 kernel: RTW: wlan0- hw port(0) mac_addr =90:de:80:a7:ab:62 Dec 23 17:50:51 so1905-b6d5 kernel: RTW: wlan1- hw port(1) mac_addr =92:de:80:a7:ab:62 Dec 23 17:50:51 so1905-b6d5 systemd-networkd[2026]: wlan0: Link UP Dec 23 17:50:51 so1905-b6d5 systemd[1]: Starting Apply sysctl settings... Dec 23 17:50:52 so1905-b6d5 sysctl[2159]: fs.inotify.max_user_watches = 524288 Dec 23 17:50:52 so1905-b6d5 sysctl[2159]: sysctl: cannot stat /proc/sys/net/ipv6/conf/all/disable_ipv6: No such file or directory Dec 23 17:50:52 so1905-b6d5 sysctl[2159]: sysctl: cannot stat /proc/sys/net/ipv6/conf/default/disable_ipv6: No such file or directory Dec 23 17:50:52 so1905-b6d5 sysctl[2159]: sysctl: cannot stat /proc/sys/net/ipv6/conf/lo/disable_ipv6: No such file or directory Dec 23 17:50:52 so1905-b6d5 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started D-Bus System Message Bus. Dec 23 17:50:52 so1905-b6d5 sudo[2156]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started display Daemon. Dec 23 17:50:52 so1905-b6d5 sudo[2156]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Found user 'avahi' (UID 107) and group 'avahi' (GID 114). Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Successfully dropped root privileges. Dec 23 17:50:52 so1905-b6d5 display[2165]: lvgl_display daemon starting... Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: avahi-daemon 0.7 starting up. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Starting Remove Stale Online ext4 Metadata Check Snapshots... Dec 23 17:50:52 so1905-b6d5 sudo[2156]: pam_unix(sudo:session): session closed for user root Dec 23 17:50:52 so1905-b6d5 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started Volumio Iptables Module. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Starting Dispatcher daemon for systemd-networkd... Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started Set the CPU Frequency Scaling governor. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Starting Server OTA Update Service... Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started Server OTA Download Service. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Starting Login Service... Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started mount disks automatically with standby. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started Volumio First Start Script. Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Successfully called chroot(). Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Successfully dropped remaining capabilities. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started Volumio Log Rotation Service. Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Loading service file /services/volumio.service. Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: XML_ParseBuffer() failed at line 1: no element found. Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Failed to load service group file /services/volumio.service, ignoring. Dec 23 17:50:52 so1905-b6d5 volumio_first_start.sh[2188]: no find file Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started Volumio SSH enabler. Dec 23 17:50:52 so1905-b6d5 serverota.sh[2179]: Upgrade file or version file missing. Exiting. Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: socket() failed: Address family not supported by protocol Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Failed to create IPv6 socket, proceeding in IPv4 only mode Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: socket() failed: Address family not supported by protocol Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: New relevant interface lo.IPv4 for mDNS. Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Network interface enumeration completed. Dec 23 17:50:52 so1905-b6d5 avahi-daemon[2160]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Started Network Name Resolution. Dec 23 17:50:52 so1905-b6d5 systemd[1]: applysysctl.service: Main process exited, code=exited, status=255/EXCEPTION Dec 23 17:50:52 so1905-b6d5 systemd[1]: applysysctl.service: Failed with result 'exit-code'. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Failed to start Apply sysctl settings. Dec 23 17:50:52 so1905-b6d5 systemd[1]: e2scrub_reap.service: Succeeded. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots. Dec 23 17:50:52 so1905-b6d5 dbus-daemon[2162]: [system] Activating systemd to hand-off: service name='org.freedesktop.UDisks' unit='udisks.service' requested by ':1.4' (uid=1000 pid=2183 comm="/usr/bin/udisks-glue --foreground ") Dec 23 17:50:52 so1905-b6d5 systemd[1]: iptables.service: Succeeded. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Finished Server OTA Update Service. Dec 23 17:50:52 so1905-b6d5 systemd[1]: volumiossh.service: Succeeded. Dec 23 17:50:52 so1905-b6d5 systemd[1]: Finished Save/Restore Sound Card State. Dec 23 17:50:52 so1905-b6d5 systemd-logind[2181]: New seat seat0. Dec 23 17:50:53 so1905-b6d5 systemd[1]: Started Login Service. Dec 23 17:50:53 so1905-b6d5 dbus-daemon[2162]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 23 17:50:53 so1905-b6d5 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 23 17:50:53 so1905-b6d5 systemd[1]: Reached target Network. Dec 23 17:50:53 so1905-b6d5 systemd[1]: Reached target Network is Online. Dec 23 17:50:53 so1905-b6d5 systemd[1]: Reached target Sound Card. Dec 23 17:50:53 so1905-b6d5 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Dec 23 17:50:53 so1905-b6d5 systemd[1]: Starting Music Player Daemon... Dec 23 17:50:53 so1905-b6d5 systemd-networkd[2026]: eth0: Gained carrier Dec 23 17:50:53 so1905-b6d5 kernel: sunxi-gmac 4500000.eth eth0: Link is Up - 1Gbps/Full - flow control off Dec 23 17:50:53 so1905-b6d5 systemd[1]: Starting Samba NMB Daemon... Dec 23 17:50:53 so1905-b6d5 dnsmasq[2250]: dnsmasq: syntax check OK. Dec 23 17:50:53 so1905-b6d5 systemd[1]: Started NQPTP -- Not Quite PTP. Dec 23 17:50:53 so1905-b6d5 avahi-daemon[2160]: Server startup complete. Host name is so1905-b6d5.local. Local service cookie is 2404759896. Dec 23 17:50:53 so1905-b6d5 systemd[1]: Starting /etc/rc.local Compatibility... Dec 23 17:50:53 so1905-b6d5 systemd[1]: Starting OpenBSD Secure Shell server... Dec 23 17:50:53 so1905-b6d5 volumio[2185]: Could not open config: /tmp/upmpdcli.conf Dec 23 17:50:53 so1905-b6d5 systemd[1]: Starting Permit User Sessions... Dec 23 17:50:53 so1905-b6d5 systemd[1]: Starting Disk Manager (legacy version)... Dec 23 17:50:53 so1905-b6d5 sshd[2278]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Dec 23 17:50:53 so1905-b6d5 sshd[2278]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Dec 23 17:50:53 so1905-b6d5 sshd[2278]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Dec 23 17:50:53 so1905-b6d5 sshd[2278]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Dec 23 17:50:53 so1905-b6d5 sshd[2278]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Dec 23 17:50:53 so1905-b6d5 systemd[1]: Started Volumio Backend Module. Dec 23 17:50:53 so1905-b6d5 dbus-daemon[2162]: [system] Successfully activated service 'org.freedesktop.UDisks' Dec 23 17:50:53 so1905-b6d5 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 23 17:50:53 so1905-b6d5 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 23 17:50:54 so1905-b6d5 dbus-daemon[2162]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.8' (uid=0 pid=2288 comm="/usr/lib/udisks/udisks-daemon --no-debug ") Dec 23 17:50:54 so1905-b6d5 systemd[1]: Started /etc/rc.local Compatibility. Dec 23 17:50:54 so1905-b6d5 systemd[1]: Finished Permit User Sessions. Dec 23 17:50:54 so1905-b6d5 display[2182]: 初始化FreeType... Dec 23 17:50:54 so1905-b6d5 display[2182]: FreeType初始化失败 Dec 23 17:50:54 so1905-b6d5 display[2182]: [HeartbeatMonitor] 初始化完成 Dec 23 17:50:54 so1905-b6d5 systemd[1]: Started Disk Manager (legacy version). Dec 23 17:50:54 so1905-b6d5 sshd[2317]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Dec 23 17:50:54 so1905-b6d5 sshd[2317]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Dec 23 17:50:54 so1905-b6d5 sshd[2317]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Dec 23 17:50:54 so1905-b6d5 sshd[2317]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Dec 23 17:50:54 so1905-b6d5 sshd[2317]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Dec 23 17:50:54 so1905-b6d5 systemd[1]: Started Getty on tty1. Dec 23 17:50:54 so1905-b6d5 sshd[2317]: Server listening on 0.0.0.0 port 22. Dec 23 17:50:54 so1905-b6d5 systemd[1]: Starting Authorization Manager... Dec 23 17:50:54 so1905-b6d5 systemd[1]: Started Serial Getty on ttyAS0. Dec 23 17:50:54 so1905-b6d5 systemd[1]: Reached target Login Prompts. Dec 23 17:50:54 so1905-b6d5 systemd[1]: Started OpenBSD Secure Shell server. Dec 23 17:50:54 so1905-b6d5 polkitd[2327]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 23 17:50:54 so1905-b6d5 dbus-daemon[2162]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 23 17:50:54 so1905-b6d5 systemd[1]: Started Authorization Manager. Dec 23 17:50:54 so1905-b6d5 dnsmasq[2310]: dnsmasq: unknown interface wlan0 Dec 23 17:50:54 so1905-b6d5 dnsmasq[2310]: unknown interface wlan0 Dec 23 17:50:54 so1905-b6d5 dnsmasq[2310]: FAILED to start up Dec 23 17:50:54 so1905-b6d5 systemd[1]: dnsmasq.service: Control process exited, code=exited, status=2/INVALIDARGUMENT Dec 23 17:50:54 so1905-b6d5 systemd[1]: dnsmasq.service: Failed with result 'exit-code'. Dec 23 17:50:54 so1905-b6d5 systemd[1]: Failed to start dnsmasq - A lightweight DHCP and caching DNS server. Dec 23 17:50:54 so1905-b6d5 systemd[1]: Reached target Host and Network Name Lookups. Dec 23 17:50:55 so1905-b6d5 udisks-glue[2183]: Device file /dev/mmcblk0p6 inserted Dec 23 17:50:55 so1905-b6d5 udisks-glue[2183]: Device file /dev/mmcblk0 inserted Dec 23 17:50:55 so1905-b6d5 systemd-networkd[2026]: eth0: DHCPv4 address 192.168.23.124/24 via 192.168.23.1 Dec 23 17:50:55 so1905-b6d5 avahi-daemon[2160]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.23.124. Dec 23 17:50:55 so1905-b6d5 avahi-daemon[2160]: New relevant interface eth0.IPv4 for mDNS. Dec 23 17:50:55 so1905-b6d5 avahi-daemon[2160]: Registering new address record for 192.168.23.124 on eth0.IPv4. Dec 23 17:50:55 so1905-b6d5 udisks-glue[2183]: Device file /dev/mmcblk0p4 inserted Dec 23 17:50:55 so1905-b6d5 systemd-timesyncd[2152]: Network configuration changed, trying to establish connection. Dec 23 17:50:55 so1905-b6d5 udisks-glue[2183]: Device file /dev/mmcblk0p3 inserted Dec 23 17:50:55 so1905-b6d5 udisks-glue[2183]: Device file /dev/mmcblk0p2 inserted Dec 23 17:50:55 so1905-b6d5 udisks-glue[2183]: Device file /dev/mmcblk0p8 inserted Dec 23 17:50:55 so1905-b6d5 udisks-glue[2183]: Device file /dev/mmcblk0p1 inserted Dec 23 17:50:55 so1905-b6d5 udisks-glue[2183]: Device file /dev/mmcblk0p7 inserted Dec 23 17:51:10 so1905-b6d5 systemd-timesyncd[2152]: Initial synchronization to time server 185.125.190.58:123 (ntp.ubuntu.com). Dec 23 17:51:10 so1905-b6d5 display[2182]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Dec 23 17:51:10 so1905-b6d5 display[2182]: 文件大小: 11364916 字节 Dec 23 17:51:10 so1905-b6d5 display[2182]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 50px) Dec 23 17:51:11 so1905-b6d5 display[2182]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Dec 23 17:51:11 so1905-b6d5 display[2182]: 文件大小: 11364916 字节 Dec 23 17:51:11 so1905-b6d5 display[2182]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 36px) Dec 23 17:51:11 so1905-b6d5 nmbd[2256]: [2025/12/23 17:51:11.041995, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 23 17:51:11 so1905-b6d5 nmbd[2256]: nmbd version 4.15.13-Ubuntu started. Dec 23 17:51:11 so1905-b6d5 nmbd[2256]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Dec 23 17:51:11 so1905-b6d5 nmbd[2256]: [2025/12/23 17:51:11.088464, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 23 17:51:11 so1905-b6d5 nmbd[2256]: started asyncdns process 2338 Dec 23 17:51:11 so1905-b6d5 systemd[1]: Started Samba NMB Daemon. Dec 23 17:51:11 so1905-b6d5 systemd[1]: systemd-rfkill.service: Succeeded. Dec 23 17:51:11 so1905-b6d5 nmbd[2256]: [2025/12/23 17:51:11.207261, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 23 17:51:11 so1905-b6d5 nmbd[2256]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.124 for name WORKGROUP<1d>. Dec 23 17:51:11 so1905-b6d5 nmbd[2256]: This response was from IP 192.168.23.83, reporting an IP address of 192.168.23.83. Dec 23 17:51:11 so1905-b6d5 systemd[1]: Starting Samba SMB Daemon... Dec 23 17:51:11 so1905-b6d5 display[2182]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Dec 23 17:51:11 so1905-b6d5 display[2182]: 文件大小: 11364916 字节 Dec 23 17:51:11 so1905-b6d5 display[2182]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 40px) Dec 23 17:51:11 so1905-b6d5 systemd[1]: Started Dispatcher daemon for systemd-networkd. Dec 23 17:51:11 so1905-b6d5 display[2182]: setImageSrc: this=0x23fd278, str=/volumio/display/assets/source-icon/streaming.png Dec 23 17:51:11 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 23 17:51:11 so1905-b6d5 display[2182]: setImageSrc: this=0x2401810, str=/volumio/display/assets/source-icon/bt.png Dec 23 17:51:11 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Dec 23 17:51:11 so1905-b6d5 display[2182]: setImageSrc: this=0x2401940, str=/volumio/display/assets/source-icon/aux.png Dec 23 17:51:11 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/aux.png, size=10326, buffer[0]=89 Dec 23 17:51:11 so1905-b6d5 display[2182]: setImageSrc: this=0x2401df0, str=/volumio/display/assets/source-icon/opt.png Dec 23 17:51:11 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Dec 23 17:51:11 so1905-b6d5 display[2182]: setImageSrc: this=0x2402098, str=/volumio/display/assets/source-icon/opt.png Dec 23 17:51:11 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Dec 23 17:51:12 so1905-b6d5 display[2182]: setImageSrc: this=0x24023b0, str=/volumio/display/assets/source-icon/hdmi-arc.png Dec 23 17:51:12 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/hdmi-arc.png, size=7908, buffer[0]=89 Dec 23 17:51:12 so1905-b6d5 display[2182]: setImageSrc: this=0x2402c80, str=/volumio/display/assets/source-icon/usb.png Dec 23 17:51:12 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/usb.png, size=7652, buffer[0]=89 Dec 23 17:51:12 so1905-b6d5 display[2182]: setImageSrc: this=0x23fdd98, str=/volumio/display/assets/source-icon/music-library.png Dec 23 17:51:12 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/music-library.png, size=22848, buffer[0]=89 Dec 23 17:51:12 so1905-b6d5 display[2182]: setImageSrc: this=0x23fe280, str=/volumio/display/assets/source-icon/bt.png Dec 23 17:51:12 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Dec 23 17:51:12 so1905-b6d5 display[2182]: setImageSrc: this=0x23fe850, str=/volumio/display/assets/albumart.jpg Dec 23 17:51:12 so1905-b6d5 display[2182]: read file /volumio/display/assets/albumart.jpg, size=77672, buffer[0]=ff Dec 23 17:51:12 so1905-b6d5 display[2182]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Dec 23 17:51:12 so1905-b6d5 display[2182]: Attempting to process with stb_image... Dec 23 17:51:12 so1905-b6d5 set-cpufreq[2178]: Setting ondemand scheduler for all CPUs Dec 23 17:51:12 so1905-b6d5 systemd[1]: ondemand.service: Succeeded. Dec 23 17:51:12 so1905-b6d5 display[2182]: stb_image successfully loaded: 855x855, channels=3 Dec 23 17:51:12 so1905-b6d5 display[2182]: Successfully processed with stb_image Dec 23 17:51:12 so1905-b6d5 display[2182]: Loading default image: /volumio/display/assets/albumart.jpg Dec 23 17:51:12 so1905-b6d5 display[2182]: Loaded default image from file: /volumio/display/assets/albumart.jpg, size=77672 Dec 23 17:51:12 so1905-b6d5 display[2182]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Dec 23 17:51:12 so1905-b6d5 display[2182]: Attempting to process with stb_image... Dec 23 17:51:13 so1905-b6d5 display[2182]: stb_image successfully loaded: 855x855, channels=3 Dec 23 17:51:13 so1905-b6d5 display[2182]: Successfully processed with stb_image Dec 23 17:51:13 so1905-b6d5 display[2182]: Default image loaded successfully Dec 23 17:51:13 so1905-b6d5 display[2182]: setImageSrc: this=0x23fea20, str=/volumio/display/assets/status-icon/wifi-error.png Dec 23 17:51:13 so1905-b6d5 display[2182]: read file /volumio/display/assets/status-icon/wifi-error.png, size=1494, buffer[0]=89 Dec 23 17:51:13 so1905-b6d5 display[2182]: setImageSrc: this=0x23ff860, str=/volumio/display/assets/status-icon/eth-error.png Dec 23 17:51:13 so1905-b6d5 display[2182]: read file /volumio/display/assets/status-icon/eth-error.png, size=936, buffer[0]=89 Dec 23 17:51:13 so1905-b6d5 smbd[2342]: [2025/12/23 17:51:13.444528, 0] ../../source3/smbd/server.c:1734(main) Dec 23 17:51:13 so1905-b6d5 smbd[2342]: smbd version 4.15.13-Ubuntu started. Dec 23 17:51:13 so1905-b6d5 smbd[2342]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Dec 23 17:51:13 so1905-b6d5 display[2182]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Dec 23 17:51:13 so1905-b6d5 display[2182]: 文件大小: 6344 字节 Dec 23 17:51:13 so1905-b6d5 display[2182]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 40px) Dec 23 17:51:13 so1905-b6d5 display[2182]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf Dec 23 17:51:13 so1905-b6d5 display[2182]: 文件大小: 11495876 字节 Dec 23 17:51:13 so1905-b6d5 display[2182]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf (大小: 40px) Dec 23 17:51:13 so1905-b6d5 display[2182]: setInnerAlign: image_desc is null Dec 23 17:51:13 so1905-b6d5 node[2203]: Cleaning previous... Dec 23 17:51:13 so1905-b6d5 systemd[1]: Started Samba SMB Daemon. Dec 23 17:51:14 so1905-b6d5 display[2182]: setImageSrc: this=0x2403ec8, str=/volumio/display/assets/source-icon/streaming.png Dec 23 17:51:14 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 23 17:51:14 so1905-b6d5 sudo[2356]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/ip addr flush dev wlan0 Dec 23 17:51:14 so1905-b6d5 sudo[2356]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:14 so1905-b6d5 display[2182]: setImageSrc: this=0x24164c8, str=/volumio/display/assets/source-icon/streaming.png Dec 23 17:51:14 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 23 17:51:14 so1905-b6d5 sudo[2356]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:14 so1905-b6d5 display[2182]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Dec 23 17:51:14 so1905-b6d5 display[2182]: 文件大小: 6344 字节 Dec 23 17:51:14 so1905-b6d5 display[2182]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 100px) Dec 23 17:51:14 so1905-b6d5 display[2182]: setImageSrc: this=0x2416bf0, str=/volumio/display/assets/source-icon/streaming.png Dec 23 17:51:14 so1905-b6d5 display[2182]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 23 17:51:14 so1905-b6d5 sudo[2369]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 23 17:51:15 so1905-b6d5 sudo[2369]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:15 so1905-b6d5 sudo[2369]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:15 so1905-b6d5 systemd-networkd[2026]: wlan0: Link DOWN Dec 23 17:51:15 so1905-b6d5 node[2180]: Failed to retrieve remote version or perform update: { Error: write EPROTO 3070149216:error:14094410:SSL routines:ssl3_read_bytes:sslv3 alert handshake failure:../deps/openssl/openssl/ssl/record/rec_layer_s3.c:1544:SSL alert number 40 Dec 23 17:51:15 so1905-b6d5 node[2180]: at WriteWrap.afterWrite [as oncomplete] (net.js:789:14) errno: 'EPROTO', code: 'EPROTO', syscall: 'write' } Dec 23 17:51:15 so1905-b6d5 smbd[2342]: [2025/12/23 17:51:15.858290, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Dec 23 17:51:15 so1905-b6d5 smbd[2342]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Dec 23 17:51:15 so1905-b6d5 smbd[2342]: [2025/12/23 17:51:15.879034, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Dec 23 17:51:15 so1905-b6d5 smbd[2342]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Dec 23 17:51:16 so1905-b6d5 node[2203]: Stopped aP Dec 23 17:51:16 so1905-b6d5 node[2203]: Start wireless flow Dec 23 17:51:16 so1905-b6d5 node[2203]: Stopped hotspot (if there).. Dec 23 17:51:16 so1905-b6d5 sudo[2388]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/ip addr flush dev wlan0 Dec 23 17:51:16 so1905-b6d5 sudo[2388]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:16 so1905-b6d5 sudo[2388]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:16 so1905-b6d5 sudo[2401]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 23 17:51:17 so1905-b6d5 sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:17 so1905-b6d5 sudo[2401]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:17 so1905-b6d5 node[2203]: DHCP IP Dec 23 17:51:17 so1905-b6d5 node[2203]: Start ap Dec 23 17:51:17 so1905-b6d5 wpa_supplicant[2410]: Successfully initialized wpa_supplicant Dec 23 17:51:17 so1905-b6d5 systemd-networkd[2026]: wlan0: Link UP Dec 23 17:51:17 so1905-b6d5 sudo[2411]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/udhcpc -i wlan0 -v Dec 23 17:51:17 so1905-b6d5 sudo[2411]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:17 so1905-b6d5 mpd[2254]: exception: No configuration file found Dec 23 17:51:17 so1905-b6d5 udhcpc[2423]: wlan0: deconfigured Dec 23 17:51:17 so1905-b6d5 systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 23 17:51:17 so1905-b6d5 systemd[1]: mpd.service: Failed with result 'exit-code'. Dec 23 17:51:17 so1905-b6d5 systemd[1]: Failed to start Music Player Daemon. Dec 23 17:51:17 so1905-b6d5 systemd[1]: Reached target Multi-User System. Dec 23 17:51:17 so1905-b6d5 systemd[1]: Reached target Graphical Interface. Dec 23 17:51:17 so1905-b6d5 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 23 17:51:18 so1905-b6d5 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 23 17:51:18 so1905-b6d5 systemd[1]: Finished Update UTMP about System Runlevel Changes. Dec 23 17:51:18 so1905-b6d5 node[2203]: trying... Dec 23 17:51:18 so1905-b6d5 sudo[2428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 23 17:51:18 so1905-b6d5 sudo[2428]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:18 so1905-b6d5 sudo[2428]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:18 so1905-b6d5 node[2203]: Connected to: ---- Dec 23 17:51:18 so1905-b6d5 node[2203]: ---- Dec 23 17:51:18 so1905-b6d5 sudo[2438]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 17:51:18 so1905-b6d5 sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:18 so1905-b6d5 sudo[2438]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:19 so1905-b6d5 node[2203]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Dec 23 17:51:19 so1905-b6d5 volumio[2292]: info: ------------------------------------------- Dec 23 17:51:19 so1905-b6d5 volumio[2292]: info: ----- Synaudio ---- Dec 23 17:51:19 so1905-b6d5 volumio[2292]: info: ------------------------------------------- Dec 23 17:51:19 so1905-b6d5 volumio[2292]: info: ----- System startup ---- Dec 23 17:51:19 so1905-b6d5 volumio[2292]: info: ------------------------------------------- Dec 23 17:51:19 so1905-b6d5 node[2203]: trying... Dec 23 17:51:19 so1905-b6d5 sudo[2456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 23 17:51:19 so1905-b6d5 sudo[2456]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:19 so1905-b6d5 sudo[2456]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:19 so1905-b6d5 node[2203]: Connected to: ---- Dec 23 17:51:19 so1905-b6d5 node[2203]: ---- Dec 23 17:51:19 so1905-b6d5 sudo[2466]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 17:51:20 so1905-b6d5 sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:20 so1905-b6d5 sudo[2466]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:20 so1905-b6d5 node[2203]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Dec 23 17:51:20 so1905-b6d5 node[2203]: trying... Dec 23 17:51:20 so1905-b6d5 sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 23 17:51:20 so1905-b6d5 sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:20 so1905-b6d5 sudo[2476]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:20 so1905-b6d5 node[2203]: Connected to: ---- Dec 23 17:51:20 so1905-b6d5 node[2203]: ---- Dec 23 17:51:20 so1905-b6d5 sudo[2486]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 17:51:20 so1905-b6d5 sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:20 so1905-b6d5 sudo[2486]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:20 so1905-b6d5 volumio[2292]: info: Found new core plugin music_service/cdplayer. Adding it Dec 23 17:51:20 so1905-b6d5 node[2203]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Dec 23 17:51:20 so1905-b6d5 volumio[2292]: info: Configured plugin music_service/cdplayer cannot be loaded. Removing from configuration Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Plugin folders cleanup Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Scanning into folder /volumio/app/plugins/ Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Scanning category audio_interface Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Scanning category miscellanea Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Scanning category music_service Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Scanning category plugins.json Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Scanning category system_controller Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Scanning category user_interface Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Scanning into folder /data/plugins/ Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Plugin folders cleanup completed Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: ------------------------------------------- Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: ----- Core plugins startup ---- Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: ------------------------------------------- Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Loading plugins from folder /volumio/app/plugins/ Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Adding plugin upnp to MyMusic Plugins Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Loading plugins from folder /data/plugins/ Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Loading plugin "system"... Dec 23 17:51:21 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/system_controller/system/index.js Dec 23 17:51:21 so1905-b6d5 volumio[2292]: info: Loading plugin "appearance"... Dec 23 17:51:21 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js Dec 23 17:51:21 so1905-b6d5 node[2203]: trying... Dec 23 17:51:21 so1905-b6d5 sudo[2498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 23 17:51:21 so1905-b6d5 sudo[2498]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:21 so1905-b6d5 sudo[2498]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:21 so1905-b6d5 node[2203]: Connected to: ---- Dec 23 17:51:21 so1905-b6d5 node[2203]: ---- Dec 23 17:51:21 so1905-b6d5 wpa_supplicant[2415]: wlan0: Trying to associate with 14:d8:64:4c:f2:43 (SSID='FORTEX' freq=2412 MHz) Dec 23 17:51:21 so1905-b6d5 wpa_supplicant[2415]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN Dec 23 17:51:21 so1905-b6d5 kernel: RTW: rtw_set_802_11_connect(wlan0) fw_state=0x00000008 Dec 23 17:51:21 so1905-b6d5 sudo[2508]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 17:51:21 so1905-b6d5 sudo[2508]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:22 so1905-b6d5 sudo[2508]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:22 so1905-b6d5 node[2203]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: Loading plugin "network"... Dec 23 17:51:22 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/system_controller/network/index.js Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: Refreshing Cached IP Addresses Dec 23 17:51:22 so1905-b6d5 volumio[2292]: ControllerNetwork autoStartNetworkConfig true Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: Loading plugin "services"... Dec 23 17:51:22 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/system_controller/services/index.js Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: Loading plugin "alsa_controller"... Dec 23 17:51:22 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Dec 23 17:51:22 so1905-b6d5 sudo[2518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 17:51:22 so1905-b6d5 sudo[2518]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:22 so1905-b6d5 sudo[2520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 17:51:22 so1905-b6d5 sudo[2520]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:22 so1905-b6d5 sudo[2518]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:22 so1905-b6d5 sudo[2520]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:22 so1905-b6d5 node[2203]: trying... Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: Applying required configuration parameters for plugin alsa_controller Dec 23 17:51:22 so1905-b6d5 kernel: RTW: start auth Dec 23 17:51:22 so1905-b6d5 kernel: RTW: auth success, start assoc Dec 23 17:51:22 so1905-b6d5 kernel: RTW: assoc success Dec 23 17:51:22 so1905-b6d5 wpa_supplicant[2415]: wlan0: Associated with 14:d8:64:4c:f2:43 Dec 23 17:51:22 so1905-b6d5 wpa_supplicant[2415]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 23 17:51:22 so1905-b6d5 sudo[2537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 23 17:51:22 so1905-b6d5 kernel: RTW: ============ STA [14:d8:64:4c:f2:43] =================== Dec 23 17:51:22 so1905-b6d5 kernel: RTW: mac_id : 0 Dec 23 17:51:22 so1905-b6d5 kernel: RTW: wireless_mode : 0x43 Dec 23 17:51:22 so1905-b6d5 kernel: RTW: mimo_type : 0 Dec 23 17:51:22 so1905-b6d5 kernel: RTW: bw_mode : 40MHz, ra_bw_mode : 40MHz Dec 23 17:51:22 so1905-b6d5 kernel: RTW: rate_id : 11 Dec 23 17:51:22 so1905-b6d5 kernel: RTW: rssi : 56 (%), rssi_level : 0 Dec 23 17:51:22 so1905-b6d5 kernel: RTW: is_support_sgi : Y, is_vht_enable : Y Dec 23 17:51:22 so1905-b6d5 kernel: RTW: disable_ra : N, disable_pt : N Dec 23 17:51:22 so1905-b6d5 kernel: RTW: is_noisy : N Dec 23 17:51:22 so1905-b6d5 kernel: RTW: recv eapol packet Dec 23 17:51:22 so1905-b6d5 kernel: RTW: txrx_state : 0 Dec 23 17:51:22 so1905-b6d5 kernel: RTW: curr_tx_rate : CCK_1M (L) Dec 23 17:51:22 so1905-b6d5 kernel: RTW: curr_tx_bw : 20MHz Dec 23 17:51:22 so1905-b6d5 kernel: RTW: curr_retry_ratio : 0 Dec 23 17:51:22 so1905-b6d5 kernel: RTW: ra_mask : 0x00000000003fffff Dec 23 17:51:22 so1905-b6d5 wpa_supplicant[2415]: wlan0: WPA: Key negotiation completed with 14:d8:64:4c:f2:43 [PTK=CCMP GTK=CCMP] Dec 23 17:51:22 so1905-b6d5 kernel: RTW: send eapol packet Dec 23 17:51:22 so1905-b6d5 kernel: RTW: recv eapol packet Dec 23 17:51:22 so1905-b6d5 kernel: RTW: send eapol packet Dec 23 17:51:22 so1905-b6d5 kernel: RTW: set pairwise key camid:0, addr:14:d8:64:4c:f2:43, kid:0, type:AES Dec 23 17:51:22 so1905-b6d5 wpa_supplicant[2415]: wlan0: CTRL-EVENT-CONNECTED - Connection to 14:d8:64:4c:f2:43 completed [id=0 id_str=] Dec 23 17:51:22 so1905-b6d5 systemd-networkd[2026]: wlan0: Gained carrier Dec 23 17:51:22 so1905-b6d5 systemd-networkd[2026]: wlan0: Connected WiFi access point: FORTEX (00:00:00:00:00:00) Dec 23 17:51:22 so1905-b6d5 kernel: RTW: set group key camid:1, addr:14:d8:64:4c:f2:43, kid:2, type:AES Dec 23 17:51:22 so1905-b6d5 systemd-networkd[2026]: wlan0: DHCPv4 address 192.168.23.129/24 via 192.168.23.1 Dec 23 17:51:22 so1905-b6d5 avahi-daemon[2160]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.23.129. Dec 23 17:51:22 so1905-b6d5 avahi-daemon[2160]: New relevant interface wlan0.IPv4 for mDNS. Dec 23 17:51:22 so1905-b6d5 avahi-daemon[2160]: Registering new address record for 192.168.23.129 on wlan0.IPv4. Dec 23 17:51:22 so1905-b6d5 sudo[2537]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:22 so1905-b6d5 wpa_supplicant[2415]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Dec 23 17:51:22 so1905-b6d5 sudo[2537]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: Loading plugin "wizard"... Dec 23 17:51:22 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Dec 23 17:51:22 so1905-b6d5 node[2203]: Connected to: ----FORTEX Dec 23 17:51:22 so1905-b6d5 node[2203]: ---- Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: Loading plugin "volumio_command_line_client"... Dec 23 17:51:22 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Dec 23 17:51:22 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:22 so1905-b6d5 sudo[2551]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 17:51:22 so1905-b6d5 sudo[2551]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:22 so1905-b6d5 sudo[2551]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:22 so1905-b6d5 node[2203]: ... joined AP, wlan0 IPv4 is 192.168.23.129, ipV6 is undefined Dec 23 17:51:22 so1905-b6d5 node[2203]: It's done! AP Dec 23 17:51:23 so1905-b6d5 volumio[2292]: info: Loading plugin "upnp"... Dec 23 17:51:23 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Dec 23 17:51:23 so1905-b6d5 volumio[2292]: info: [1766483483029] Starting Upmpd Daemon Dec 23 17:51:23 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 17:51:23 so1905-b6d5 volumio[2292]: info: Loading plugin "my_music"... Dec 23 17:51:23 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Dec 23 17:51:23 so1905-b6d5 volumio[2292]: info: Loading plugin "mpd"... Dec 23 17:51:23 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Dec 23 17:51:23 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 17:51:23 so1905-b6d5 go-librespot[2575]: go-librespot daemon starting... Dec 23 17:51:23 so1905-b6d5 udhcpc[2587]: wlan0: bound: IP=192.168.23.129/255.255.255.0 router=192.168.23.1 domain="" dns="211.136.192.6 120.196.165.24" lease=7200 Dec 23 17:51:23 so1905-b6d5 sudo[2411]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:24 so1905-b6d5 volumio[2292]: info: Creating MPD Configuration file Dec 23 17:51:24 so1905-b6d5 volumio[2292]: info: Loading plugin "upnp_browser"... Dec 23 17:51:24 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Dec 23 17:51:24 so1905-b6d5 go-librespot[2593]: time="2025-12-23T17:51:24+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 17:51:24 so1905-b6d5 go-librespot[2593]: time="2025-12-23T17:51:24+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 17:51:24 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 17:51:24 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 17:51:24 so1905-b6d5 sudo[2594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 17:51:24 so1905-b6d5 sudo[2601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 17:51:24 so1905-b6d5 sudo[2594]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:24 so1905-b6d5 sudo[2594]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:24 so1905-b6d5 sudo[2601]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:24 so1905-b6d5 systemd[1]: Starting Music Player Daemon... Dec 23 17:51:25 so1905-b6d5 volumio[2292]: info: Loading plugin "networkfs"... Dec 23 17:51:25 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Dec 23 17:51:25 so1905-b6d5 volumio[2292]: info: Starting IP address watcher Dec 23 17:51:25 so1905-b6d5 volumio[2292]: info: Started network interface ip monitor watcher Dec 23 17:51:25 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 17:51:25 so1905-b6d5 volumio[2292]: info: Loading plugin "alarm-clock"... Dec 23 17:51:25 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Dec 23 17:51:26 so1905-b6d5 volumio[2292]: info: Loading plugin "airplay_emulation"... Dec 23 17:51:26 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Dec 23 17:51:26 so1905-b6d5 volumio[2292]: 执行命令出错: Error: Command failed: /usr/bin/dbus-send --system --dest=org.freedesktop.DBus --type=method_call --print-reply /org/freedesktop/DBus org.freedesktop.DBus.ListNames | grep org.gnome.ShairportSync Dec 23 17:51:26 so1905-b6d5 volumio[2292]: info: Starting Shairport Sync Dec 23 17:51:26 so1905-b6d5 mpd[2615]: exception: No configuration file found Dec 23 17:51:26 so1905-b6d5 volumio[2292]: info: [AirPlay] Started coverart directory watcher Dec 23 17:51:26 so1905-b6d5 volumio[2292]: info: Loading plugin "last_100"... Dec 23 17:51:26 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Dec 23 17:51:26 so1905-b6d5 systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 23 17:51:26 so1905-b6d5 systemd[1]: mpd.service: Failed with result 'exit-code'. Dec 23 17:51:26 so1905-b6d5 systemd[1]: Failed to start Music Player Daemon. Dec 23 17:51:26 so1905-b6d5 volumio[2292]: info: Loading plugin "spop"... Dec 23 17:51:26 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/spop/index.js Dec 23 17:51:26 so1905-b6d5 sudo[2601]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:26 so1905-b6d5 volumio[2292]: info: Loading plugin "webradio"... Dec 23 17:51:26 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Dec 23 17:51:27 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 23 17:51:27 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 17:51:27 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 17:51:27 so1905-b6d5 go-librespot[2623]: go-librespot daemon starting... Dec 23 17:51:27 so1905-b6d5 go-librespot[2625]: time="2025-12-23T17:51:27+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 17:51:27 so1905-b6d5 go-librespot[2625]: time="2025-12-23T17:51:27+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 17:51:27 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 17:51:27 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 17:51:29 so1905-b6d5 volumio[2292]: info: Loading plugin "yandex_browser"... Dec 23 17:51:29 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/yandex_browser/index.js Dec 23 17:51:29 so1905-b6d5 volumio[2292]: info: Yandex plugin started Dec 23 17:51:29 so1905-b6d5 volumio[2292]: error: ATTENTION!!!: Plugin yandex_browser does not return adequate promise from onVolumioStart: please update! Dec 23 17:51:29 so1905-b6d5 volumio[2292]: info: Loading plugin "i2s_dacs"... Dec 23 17:51:29 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Dec 23 17:51:29 so1905-b6d5 volumio[2292]: info: I2S DAC not set, start Auto-detection Dec 23 17:51:29 so1905-b6d5 volumio[2292]: info: Loading plugin "volumiodiscovery"... Dec 23 17:51:29 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Dec 23 17:51:29 so1905-b6d5 volumio[2292]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 23 17:51:29 so1905-b6d5 volumio[2292]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 17:51:29 so1905-b6d5 node[2292]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 23 17:51:29 so1905-b6d5 volumio[2292]: *** WARNING *** For more information see Dec 23 17:51:29 so1905-b6d5 volumio[2292]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 23 17:51:29 so1905-b6d5 volumio[2292]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 17:51:29 so1905-b6d5 volumio[2292]: *** WARNING *** For more information see Dec 23 17:51:29 so1905-b6d5 node[2292]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 17:51:29 so1905-b6d5 node[2292]: *** WARNING *** For more information see Dec 23 17:51:29 so1905-b6d5 node[2292]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 23 17:51:29 so1905-b6d5 node[2292]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 17:51:29 so1905-b6d5 node[2292]: *** WARNING *** For more information see Dec 23 17:51:29 so1905-b6d5 volumio[2292]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 23 17:51:29 so1905-b6d5 volumio[2292]: info: Discovery: Started advertising with name: SO1905-b6d5 Dec 23 17:51:29 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 17:51:29 so1905-b6d5 volumio[2292]: info: Loading plugin "outputs"... Dec 23 17:51:29 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Dec 23 17:51:30 so1905-b6d5 volumio[2292]: info: Loading plugin "albumart"... Dec 23 17:51:30 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Dec 23 17:51:30 so1905-b6d5 kernel: usb0-vbus: disabling Dec 23 17:51:30 so1905-b6d5 volumio[2292]: info: Plugin example_plugin is not enabled Dec 23 17:51:30 so1905-b6d5 volumio[2292]: info: Loading plugin "inputs"... Dec 23 17:51:30 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Dec 23 17:51:30 so1905-b6d5 volumio[2292]: info: Loading plugin "pandora"... Dec 23 17:51:30 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Dec 23 17:51:30 so1905-b6d5 volumio[2292]: info: Applying required configuration parameters for plugin pandora Dec 23 17:51:30 so1905-b6d5 volumio[2292]: info: Loading plugin "podcast"... Dec 23 17:51:30 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Dec 23 17:51:30 so1905-b6d5 volumio[2292]: info: ControllerPodcast::constructor Dec 23 17:51:30 so1905-b6d5 volumio[2292]: info: Loading plugin "qobuz"... Dec 23 17:51:30 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Dec 23 17:51:30 so1905-b6d5 volumio[2292]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Dec 23 17:51:30 so1905-b6d5 volumio[2292]: info: Loading plugin "tidal"... Dec 23 17:51:30 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491021] ControllerTidalPlugin::constructor Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491023] ControllerTidalPlugin::onVolumioStart Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: Plugin volspotconnect2 is not enabled Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: Loading plugin "updater_comm"... Dec 23 17:51:31 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Dec 23 17:51:31 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 23 17:51:31 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 17:51:31 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 17:51:31 so1905-b6d5 go-librespot[2638]: go-librespot daemon starting... Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: Plugin mpdemulation is not enabled Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: Loading plugin "rest_api"... Dec 23 17:51:31 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: Loading plugin "websocket"... Dec 23 17:51:31 so1905-b6d5 volumio[2292]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Dec 23 17:51:31 so1905-b6d5 go-librespot[2640]: time="2025-12-23T17:51:31+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 17:51:31 so1905-b6d5 go-librespot[2640]: time="2025-12-23T17:51:31+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 17:51:31 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 17:51:31 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: ___________ START PLUGINS ___________ Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491571] CoreMusicLibrary::Adding element Media Servers Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: SSDP Client initialized. Dec 23 17:51:31 so1905-b6d5 volumio[2292]: Starting initial SSDP search... Dec 23 17:51:31 so1905-b6d5 volumio[2292]: Discovering on interface: lo Dec 23 17:51:31 so1905-b6d5 volumio[2292]: Discovering on interface: eth0 Dec 23 17:51:31 so1905-b6d5 volumio[2292]: Discovering on interface: wlan0 Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491733] CoreMusicLibrary::Adding element Last_100 Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: Creating Spotify config file Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491794] CoreMusicLibrary::Adding element Webradio Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: Yandex plugin started Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491826] ControllerYandexPlugin::addToBrowseSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491828] CoreMusicLibrary::Adding element Yandex Music Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: error: Plugin yandex_browser does not return adequate promise from onStart: please update! Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491845] CoreMusicLibrary::Adding element Pandora Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491852] ControllerPandora::initializeMQTT Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491854] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491872] CoreMusicLibrary::Adding element Podcast Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491880] CoreMusicLibrary::Adding element Qobuz Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491894] ControllerTidalPlugin::onStart Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491902] ControllerTidalPlugin::addToBrowseSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: [1766483491904] CoreMusicLibrary::Adding element Tidal Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: Loading i18n strings for locale en Dec 23 17:51:31 so1905-b6d5 volumio[2292]: Updating browse sources language Dec 23 17:51:31 so1905-b6d5 volumio[2292]: Cannot find translation for sourceMedia Servers Dec 23 17:51:31 so1905-b6d5 volumio[2292]: Cannot find translation for sourceYandex Music Dec 23 17:51:31 so1905-b6d5 volumio[2292]: Cannot find translation for sourcePodcast Dec 23 17:51:31 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::initPlayerControls Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: BOOT COMPLETED Dec 23 17:51:32 so1905-b6d5 volumio[2292]: [Metrics] CommandRouter: 12s 809.66ms Dec 23 17:51:32 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 17:51:32 so1905-b6d5 kernel: sunxi-spdif 2036000.spdif: active: 1 Dec 23 17:51:34 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 23 17:51:34 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 17:51:34 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 17:51:34 so1905-b6d5 go-librespot[2654]: go-librespot daemon starting... Dec 23 17:51:34 so1905-b6d5 go-librespot[2656]: time="2025-12-23T17:51:34+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 17:51:34 so1905-b6d5 go-librespot[2656]: time="2025-12-23T17:51:34+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 17:51:34 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 17:51:34 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 17:51:37 so1905-b6d5 volumio[2292]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 23 17:51:37 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::Close All Modals sent Dec 23 17:51:37 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::Close All Modals sent Dec 23 17:51:37 so1905-b6d5 volumio[2292]: Express server listening on port 3000 Dec 23 17:51:37 so1905-b6d5 volumio[2292]: [Metrics] WebUI: 19s 208.82ms Dec 23 17:51:37 so1905-b6d5 volumio[2292]: (node:2292) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Dec 23 17:51:37 so1905-b6d5 volumio[2292]: info: [1766483497747] ControllerPandora::validateAndSetAccountOptions Dec 23 17:51:37 so1905-b6d5 volumio[2292]: error: [1766483497750] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Dec 23 17:51:37 so1905-b6d5 volumio[2292]: info: CoreStateMachine::resetVolumioState Dec 23 17:51:37 so1905-b6d5 volumio[2292]: info: CoreStateMachine::getcurrentVolume Dec 23 17:51:37 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 17:51:37 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 17:51:38 so1905-b6d5 sudo[2674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 17:51:38 so1905-b6d5 sudo[2674]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:38 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 23 17:51:38 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 17:51:38 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 17:51:38 so1905-b6d5 sudo[2676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 17:51:38 so1905-b6d5 volumio[2292]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 23 17:51:38 so1905-b6d5 go-librespot[2682]: go-librespot daemon starting... Dec 23 17:51:38 so1905-b6d5 sudo[2674]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:38 so1905-b6d5 sudo[2676]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:38 so1905-b6d5 sudo[2676]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:38 so1905-b6d5 go-librespot[2695]: time="2025-12-23T17:51:38+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 17:51:38 so1905-b6d5 go-librespot[2695]: time="2025-12-23T17:51:38+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 17:51:38 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 17:51:38 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 17:51:38 so1905-b6d5 volumio[2292]: info: IP address changed from "" to "192.168.23.124 192.168.23.129" Dec 23 17:51:38 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 23 17:51:38 so1905-b6d5 sudo[2708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 23 17:51:38 so1905-b6d5 sudo[2708]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:39 so1905-b6d5 sudo[2708]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:39 so1905-b6d5 sudo[2724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 23 17:51:39 so1905-b6d5 sudo[2724]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:39 so1905-b6d5 sudo[2718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 23 17:51:39 so1905-b6d5 sudo[2724]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: Connecting to wireless device: undefined Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: this.stateMachine.currentVolume: null, vol: 0 Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 17:51:39 so1905-b6d5 sudo[2738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 17:51:39 so1905-b6d5 sudo[2718]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: [AirPlay] Received volume update: {"vol":0,"mute":false,"disableVolumeControl":false} Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CoreStateMachine::pushState Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CoreStateMachine::updateTrackBlock Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CorePlayQueue::getTrackBlock Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 17:51:39 so1905-b6d5 sudo[2718]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:39 so1905-b6d5 sudo[2738]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:39 so1905-b6d5 sudo[2747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 17:51:39 so1905-b6d5 sudo[2738]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:39 so1905-b6d5 sudo[2747]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 17:51:39 so1905-b6d5 sudo[2747]: pam_unix(sudo:session): session closed for user root Dec 23 17:51:39 so1905-b6d5 volumio[2292]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 23 17:51:39 so1905-b6d5 volumio[2292]: Job for mpd.service failed because the control process exited with error code. Dec 23 17:51:39 so1905-b6d5 volumio[2292]: See "systemctl status mpd.service" and "journalctl -xe" for details. Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: MPD Permissions set Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 17:51:39 so1905-b6d5 volumio[2292]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 23 17:51:39 so1905-b6d5 volumio[2292]: error: MPD error: Error: connect ENOENT /run/mpd/socket Dec 23 17:51:39 so1905-b6d5 volumio[2292]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 17:51:39 so1905-b6d5 volumio[2292]: TypeError: err.includes is not a function Dec 23 17:51:39 so1905-b6d5 volumio[2292]: at MpdClient.eval (webpack://Volumio2/./app/plugins/music_service/mpd/index.js?:861:13) Dec 23 17:51:39 so1905-b6d5 volumio[2292]: at MpdClient.emit (events.js:198:13) Dec 23 17:51:39 so1905-b6d5 volumio[2292]: at Socket.eval (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:49:12) Dec 23 17:51:39 so1905-b6d5 volumio[2292]: at Socket.emit (events.js:198:13) Dec 23 17:51:39 so1905-b6d5 volumio[2292]: at emitErrorNT (internal/streams/destroy.js:91:8) Dec 23 17:51:39 so1905-b6d5 volumio[2292]: at emitErrorAndCloseNT (internal/streams/destroy.js:59:3) Dec 23 17:51:39 so1905-b6d5 volumio[2292]: at process._tickCallback (internal/process/next_tick.js:63:19) Dec 23 17:51:39 so1905-b6d5 volumio[2292]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 17:51:40 so1905-b6d5 volumio[2771]: Forking 1 albumart workers Dec 23 17:51:41 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 23 17:51:41 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 17:51:41 so1905-b6d5 sudo[2806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 17:50 Dec 23 17:51:41 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 17:51:41 so1905-b6d5 go-librespot[2808]: go-librespot daemon starting... Dec 23 17:51:41 so1905-b6d5 sudo[2806]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal BRAND_NAME="PREMIERA" MODEL_NAME="FT1905" IMG_VERSION="1.100.006" VOLUMIO_BUILD_VERSION="" VOLUMIO_ARCH="armv7" VOLUMIO_VERSION=1.5.4.20 VOLUMIO_BUILD_DATE=2025-12-05 14:21:17 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system