-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Wed 2025-12-17 14:41:09 CST. -- Mar 28 01:54:12 so1905-bfc4 systemd-timesyncd[2188]: System clock time unset or jumped backwards, restoring from recorded timestamp: Wed 2025-12-17 14:40:10 CST Dec 17 14:40:10 so1905-bfc4 systemd[1]: Started Network Time Synchronization. Dec 17 14:40:10 so1905-bfc4 systemd-resolved[2187]: Positive Trust Anchors: Dec 17 14:40:10 so1905-bfc4 systemd-resolved[2187]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 17 14:40:10 so1905-bfc4 systemd-resolved[2187]: 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 17 14:40:10 so1905-bfc4 systemd[1]: Reached target System Initialization. Dec 17 14:40:10 so1905-bfc4 systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 17 14:40:10 so1905-bfc4 systemd[1]: Reached target System Time Set. Dec 17 14:40:11 so1905-bfc4 kernel: RTW: wlan0- hw port(0) mac_addr =90:de:80:b9:d3:51 Dec 17 14:40:11 so1905-bfc4 kernel: RTW: wlan1- hw port(1) mac_addr =92:de:80:b9:d3:51 Dec 17 14:40:11 so1905-bfc4 systemd[1]: Reached target System Time Synchronized. Dec 17 14:40:11 so1905-bfc4 systemd-resolved[2187]: Using system hostname 'so1905-bfc4'. Dec 17 14:40:11 so1905-bfc4 systemd-networkd[2056]: wlan0: Link UP Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started Daily apt download activities. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started Daily apt upgrade and clean activities. Dec 17 14:40:11 so1905-bfc4 sudo[2186]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started Periodic ext4 Online Metadata Check for All Filesystems. Dec 17 14:40:11 so1905-bfc4 sudo[2186]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:11 so1905-bfc4 sudo[2186]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started Discard unused blocks once a week. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started Daily rotation of log files. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started Message of the Day. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Reached target Timers. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Listening on mpd.socket. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Reached target Sockets. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Reached target Basic System. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Condition check resulted in Manage Sound Card State (restore and store) being skipped. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Starting Save/Restore Sound Card State... Dec 17 14:40:11 so1905-bfc4 systemd[1]: Starting Apply sysctl settings... Dec 17 14:40:11 so1905-bfc4 sysctl[2202]: fs.inotify.max_user_watches = 524288 Dec 17 14:40:11 so1905-bfc4 sysctl[2202]: sysctl: cannot stat /proc/sys/net/ipv6/conf/all/disable_ipv6: No such file or directory Dec 17 14:40:11 so1905-bfc4 sysctl[2202]: sysctl: cannot stat /proc/sys/net/ipv6/conf/default/disable_ipv6: No such file or directory Dec 17 14:40:11 so1905-bfc4 sysctl[2202]: sysctl: cannot stat /proc/sys/net/ipv6/conf/lo/disable_ipv6: No such file or directory Dec 17 14:40:11 so1905-bfc4 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started D-Bus System Message Bus. Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: Found user 'avahi' (UID 107) and group 'avahi' (GID 114). Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: Successfully dropped root privileges. Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: avahi-daemon 0.7 starting up. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started display Daemon. Dec 17 14:40:11 so1905-bfc4 display[2206]: lvgl_display daemon starting... Dec 17 14:40:11 so1905-bfc4 systemd[1]: Starting Remove Stale Online ext4 Metadata Check Snapshots... Dec 17 14:40:11 so1905-bfc4 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started Volumio Iptables Module. Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: Successfully called chroot(). Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: Successfully dropped remaining capabilities. Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: Loading service file /services/volumio.service. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Starting Dispatcher daemon for systemd-networkd... Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started Set the CPU Frequency Scaling governor. Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: socket() failed: Address family not supported by protocol Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: Failed to create IPv6 socket, proceeding in IPv4 only mode Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: socket() failed: Address family not supported by protocol Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: New relevant interface lo.IPv4 for mDNS. Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: Network interface enumeration completed. Dec 17 14:40:11 so1905-bfc4 avahi-daemon[2203]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 17 14:40:11 so1905-bfc4 systemd[1]: Starting Server OTA Update Service... Dec 17 14:40:11 so1905-bfc4 systemd[1]: Started Server OTA Download Service. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Starting Login Service... Dec 17 14:40:12 so1905-bfc4 systemd[1]: Started mount disks automatically with standby. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Started Volumio First Start Script. Dec 17 14:40:12 so1905-bfc4 volumio_first_start.sh[2234]: no find file Dec 17 14:40:12 so1905-bfc4 systemd[1]: Started Volumio Log Rotation Service. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Started Volumio SSH enabler. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Started Network Name Resolution. Dec 17 14:40:12 so1905-bfc4 systemd[1]: applysysctl.service: Main process exited, code=exited, status=255/EXCEPTION Dec 17 14:40:12 so1905-bfc4 systemd[1]: applysysctl.service: Failed with result 'exit-code'. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Failed to start Apply sysctl settings. Dec 17 14:40:12 so1905-bfc4 systemd[1]: e2scrub_reap.service: Succeeded. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots. Dec 17 14:40:12 so1905-bfc4 dbus-daemon[2204]: [system] Activating systemd to hand-off: service name='org.freedesktop.UDisks' unit='udisks.service' requested by ':1.4' (uid=1000 pid=2225 comm="/usr/bin/udisks-glue --foreground ") Dec 17 14:40:12 so1905-bfc4 systemd-logind[2222]: New seat seat0. Dec 17 14:40:12 so1905-bfc4 systemd[1]: iptables.service: Succeeded. Dec 17 14:40:12 so1905-bfc4 systemd[1]: volumiossh.service: Succeeded. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Finished Save/Restore Sound Card State. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Dec 17 14:40:12 so1905-bfc4 dbus-daemon[2204]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 17 14:40:12 so1905-bfc4 systemd[1]: Started Login Service. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Reached target Network. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Reached target Network is Online. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Reached target Sound Card. Dec 17 14:40:12 so1905-bfc4 avahi-daemon[2203]: Server startup complete. Host name is so1905-bfc4.local. Local service cookie is 3977157362. Dec 17 14:40:12 so1905-bfc4 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Dec 17 14:40:12 so1905-bfc4 systemd[1]: Starting Message of the Day... Dec 17 14:40:12 so1905-bfc4 systemd[1]: Starting Music Player Daemon... Dec 17 14:40:13 so1905-bfc4 systemd[1]: Starting Samba NMB Daemon... Dec 17 14:40:13 so1905-bfc4 dnsmasq[2286]: dnsmasq: syntax check OK. Dec 17 14:40:13 so1905-bfc4 systemd[1]: Started NQPTP -- Not Quite PTP. Dec 17 14:40:13 so1905-bfc4 volumio[2230]: Could not open config: /tmp/upmpdcli.conf Dec 17 14:40:13 so1905-bfc4 systemd[1]: Starting /etc/rc.local Compatibility... Dec 17 14:40:13 so1905-bfc4 systemd[1]: Starting OpenBSD Secure Shell server... Dec 17 14:40:13 so1905-bfc4 systemd[1]: Starting Permit User Sessions... Dec 17 14:40:13 so1905-bfc4 systemd[1]: Starting Disk Manager (legacy version)... Dec 17 14:40:13 so1905-bfc4 sshd[2325]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Dec 17 14:40:13 so1905-bfc4 sshd[2325]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Dec 17 14:40:13 so1905-bfc4 sshd[2325]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Dec 17 14:40:13 so1905-bfc4 sshd[2325]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Dec 17 14:40:13 so1905-bfc4 sshd[2325]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Dec 17 14:40:13 so1905-bfc4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 17 14:40:13 so1905-bfc4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 17 14:40:13 so1905-bfc4 dbus-daemon[2204]: [system] Successfully activated service 'org.freedesktop.UDisks' Dec 17 14:40:13 so1905-bfc4 display[2218]: 初始化FreeType... Dec 17 14:40:13 so1905-bfc4 display[2218]: FreeType初始化失败 Dec 17 14:40:13 so1905-bfc4 display[2218]: [HeartbeatMonitor] 初始化完成 Dec 17 14:40:13 so1905-bfc4 systemd[1]: motd-news.service: Succeeded. Dec 17 14:40:13 so1905-bfc4 systemd[1]: Finished Message of the Day. Dec 17 14:40:13 so1905-bfc4 serverota.sh[2217]: chmod +x /volumio/**/*.sh Dec 17 14:40:13 so1905-bfc4 dbus-daemon[2204]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.8' (uid=0 pid=2333 comm="/usr/lib/udisks/udisks-daemon --no-debug ") Dec 17 14:40:13 so1905-bfc4 systemd[1]: Started /etc/rc.local Compatibility. Dec 17 14:40:13 so1905-bfc4 serverota.sh[2217]: chmod +x /volumio/**/vollibrespot Dec 17 14:40:13 so1905-bfc4 avahi-daemon[2203]: Service "SO1905-bfc4" (/services/volumio.service) successfully established. Dec 17 14:40:13 so1905-bfc4 serverota.sh[2217]: Upgrade file or version file missing. Exiting. Dec 17 14:40:13 so1905-bfc4 systemd[1]: Finished Server OTA Update Service. Dec 17 14:40:13 so1905-bfc4 systemd[1]: Finished Permit User Sessions. Dec 17 14:40:13 so1905-bfc4 systemd[1]: Started Disk Manager (legacy version). Dec 17 14:40:13 so1905-bfc4 sshd[2359]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Dec 17 14:40:13 so1905-bfc4 sshd[2359]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Dec 17 14:40:13 so1905-bfc4 sshd[2359]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Dec 17 14:40:13 so1905-bfc4 sshd[2359]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Dec 17 14:40:13 so1905-bfc4 sshd[2359]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Dec 17 14:40:14 so1905-bfc4 sshd[2359]: Server listening on 0.0.0.0 port 22. Dec 17 14:40:14 so1905-bfc4 systemd[1]: Started Getty on tty1. Dec 17 14:40:14 so1905-bfc4 systemd[1]: Starting Authorization Manager... Dec 17 14:40:14 so1905-bfc4 systemd[1]: Started Serial Getty on ttyAS0. Dec 17 14:40:14 so1905-bfc4 dnsmasq[2342]: dnsmasq: unknown interface wlan0 Dec 17 14:40:14 so1905-bfc4 dnsmasq[2342]: unknown interface wlan0 Dec 17 14:40:14 so1905-bfc4 dnsmasq[2342]: FAILED to start up Dec 17 14:40:14 so1905-bfc4 systemd[1]: Reached target Login Prompts. Dec 17 14:40:14 so1905-bfc4 systemd[1]: Started Volumio Backend Module. Dec 17 14:40:14 so1905-bfc4 systemd[1]: Started OpenBSD Secure Shell server. Dec 17 14:40:14 so1905-bfc4 polkitd[2374]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 17 14:40:14 so1905-bfc4 systemd[1]: dnsmasq.service: Control process exited, code=exited, status=2/INVALIDARGUMENT Dec 17 14:40:14 so1905-bfc4 systemd[1]: dnsmasq.service: Failed with result 'exit-code'. Dec 17 14:40:14 so1905-bfc4 systemd[1]: Failed to start dnsmasq - A lightweight DHCP and caching DNS server. Dec 17 14:40:14 so1905-bfc4 dbus-daemon[2204]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 17 14:40:14 so1905-bfc4 systemd[1]: Started Authorization Manager. Dec 17 14:40:14 so1905-bfc4 systemd[1]: Reached target Host and Network Name Lookups. Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Device file /dev/mmcblk0p6 inserted Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Device file /dev/mmcblk0 inserted Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Device file /dev/mmcblk0p4 inserted Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Device file /dev/mmcblk0p3 inserted Dec 17 14:40:15 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:15.290419, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 17 14:40:15 so1905-bfc4 nmbd[2297]: nmbd version 4.15.13-Ubuntu started. Dec 17 14:40:15 so1905-bfc4 nmbd[2297]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Dec 17 14:40:15 so1905-bfc4 systemd[1]: systemd-rfkill.service: Succeeded. Dec 17 14:40:15 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:15.376656, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 17 14:40:15 so1905-bfc4 nmbd[2297]: started asyncdns process 2389 Dec 17 14:40:15 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:15.390694, 0] ../../lib/util/become_daemon.c:150(daemon_status) Dec 17 14:40:15 so1905-bfc4 display[2218]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Dec 17 14:40:15 so1905-bfc4 nmbd[2297]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Dec 17 14:40:15 so1905-bfc4 display[2218]: 文件大小: 11364916 字节 Dec 17 14:40:15 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:15.415391, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Dec 17 14:40:15 so1905-bfc4 nmbd[2297]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Dec 17 14:40:15 so1905-bfc4 display[2218]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 50px) Dec 17 14:40:15 so1905-bfc4 display[2218]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Dec 17 14:40:15 so1905-bfc4 display[2218]: 文件大小: 11364916 字节 Dec 17 14:40:15 so1905-bfc4 display[2218]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 36px) Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Device file /dev/mmcblk0p2 inserted Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Device file /dev/mmcblk0p8 inserted Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Device file /dev/mmcblk0p1 inserted Dec 17 14:40:15 so1905-bfc4 display[2218]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Dec 17 14:40:15 so1905-bfc4 display[2218]: 文件大小: 11364916 字节 Dec 17 14:40:15 so1905-bfc4 display[2218]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 40px) Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Device file /dev/mmcblk0p7 inserted Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Device file /dev/sda1 inserted Dec 17 14:40:15 so1905-bfc4 udisks-glue[2225]: Trying to automount /dev/sda1... Dec 17 14:40:15 so1905-bfc4 display[2218]: setImageSrc: this=0x2b43278, str=/volumio/display/assets/source-icon/streaming.png Dec 17 14:40:15 so1905-bfc4 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Dec 17 14:40:16 so1905-bfc4 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 17 14:40:16 so1905-bfc4 udisks-glue[2225]: Successfully automounted /dev/sda1 at /media/udisk Dec 17 14:40:16 so1905-bfc4 display[2218]: setImageSrc: this=0x2b47810, str=/volumio/display/assets/source-icon/bt.png Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Dec 17 14:40:16 so1905-bfc4 udisks-glue[2225]: Device file /dev/sda inserted Dec 17 14:40:16 so1905-bfc4 udisks-glue[2225]: Device file /dev/sda1 mounted at /media/udisk Dec 17 14:40:16 so1905-bfc4 display[2218]: setImageSrc: this=0x2b47940, str=/volumio/display/assets/source-icon/aux.png Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/aux.png, size=10326, buffer[0]=89 Dec 17 14:40:16 so1905-bfc4 display[2218]: setImageSrc: this=0x2b47df0, str=/volumio/display/assets/source-icon/opt.png Dec 17 14:40:16 so1905-bfc4 systemd[1]: Started Dispatcher daemon for systemd-networkd. Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Dec 17 14:40:16 so1905-bfc4 display[2218]: setImageSrc: this=0x2b48098, str=/volumio/display/assets/source-icon/opt.png Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Dec 17 14:40:16 so1905-bfc4 display[2218]: setImageSrc: this=0x2b483b0, str=/volumio/display/assets/source-icon/hdmi-arc.png Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/hdmi-arc.png, size=7908, buffer[0]=89 Dec 17 14:40:16 so1905-bfc4 display[2218]: setImageSrc: this=0x2b48c80, str=/volumio/display/assets/source-icon/usb.png Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/usb.png, size=7652, buffer[0]=89 Dec 17 14:40:16 so1905-bfc4 display[2218]: setImageSrc: this=0x2b43d98, str=/volumio/display/assets/source-icon/music-library.png Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/music-library.png, size=22848, buffer[0]=89 Dec 17 14:40:16 so1905-bfc4 display[2218]: setImageSrc: this=0x2b44280, str=/volumio/display/assets/source-icon/bt.png Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Dec 17 14:40:16 so1905-bfc4 display[2218]: setImageSrc: this=0x2b44850, str=/volumio/display/assets/albumart.jpg Dec 17 14:40:16 so1905-bfc4 display[2218]: read file /volumio/display/assets/albumart.jpg, size=77672, buffer[0]=ff Dec 17 14:40:16 so1905-bfc4 display[2218]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Dec 17 14:40:16 so1905-bfc4 display[2218]: Attempting to process with stb_image... Dec 17 14:40:17 so1905-bfc4 display[2218]: stb_image successfully loaded: 855x855, channels=3 Dec 17 14:40:17 so1905-bfc4 set-cpufreq[2215]: Setting ondemand scheduler for all CPUs Dec 17 14:40:17 so1905-bfc4 systemd[1]: ondemand.service: Succeeded. Dec 17 14:40:17 so1905-bfc4 display[2218]: Successfully processed with stb_image Dec 17 14:40:17 so1905-bfc4 display[2218]: Loading default image: /volumio/display/assets/albumart.jpg Dec 17 14:40:17 so1905-bfc4 display[2218]: Loaded default image from file: /volumio/display/assets/albumart.jpg, size=77672 Dec 17 14:40:17 so1905-bfc4 display[2218]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Dec 17 14:40:17 so1905-bfc4 display[2218]: Attempting to process with stb_image... Dec 17 14:40:17 so1905-bfc4 node[2239]: Cleaning previous... Dec 17 14:40:17 so1905-bfc4 display[2218]: stb_image successfully loaded: 855x855, channels=3 Dec 17 14:40:17 so1905-bfc4 display[2218]: Successfully processed with stb_image Dec 17 14:40:17 so1905-bfc4 display[2218]: Default image loaded successfully Dec 17 14:40:17 so1905-bfc4 display[2218]: setImageSrc: this=0x2b44a20, str=/volumio/display/assets/status-icon/wifi-error.png Dec 17 14:40:17 so1905-bfc4 display[2218]: read file /volumio/display/assets/status-icon/wifi-error.png, size=1494, buffer[0]=89 Dec 17 14:40:17 so1905-bfc4 display[2218]: setImageSrc: this=0x2b45860, str=/volumio/display/assets/status-icon/eth-error.png Dec 17 14:40:17 so1905-bfc4 display[2218]: read file /volumio/display/assets/status-icon/eth-error.png, size=936, buffer[0]=89 Dec 17 14:40:17 so1905-bfc4 display[2218]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Dec 17 14:40:17 so1905-bfc4 display[2218]: 文件大小: 6344 字节 Dec 17 14:40:17 so1905-bfc4 display[2218]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 40px) Dec 17 14:40:17 so1905-bfc4 display[2218]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf Dec 17 14:40:17 so1905-bfc4 display[2218]: 文件大小: 11495876 字节 Dec 17 14:40:17 so1905-bfc4 sudo[2403]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/ip addr flush dev wlan0 Dec 17 14:40:17 so1905-bfc4 display[2218]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf (大小: 40px) Dec 17 14:40:17 so1905-bfc4 sudo[2403]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:17 so1905-bfc4 display[2218]: setInnerAlign: image_desc is null Dec 17 14:40:17 so1905-bfc4 sudo[2403]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:17 so1905-bfc4 sudo[2412]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 17 14:40:18 so1905-bfc4 sudo[2412]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:18 so1905-bfc4 systemd-networkd[2056]: wlan0: Link DOWN Dec 17 14:40:18 so1905-bfc4 sudo[2412]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:18 so1905-bfc4 display[2218]: setImageSrc: this=0x2b49ec8, str=/volumio/display/assets/source-icon/streaming.png Dec 17 14:40:18 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 17 14:40:18 so1905-bfc4 display[2218]: setImageSrc: this=0x2b5c4c8, str=/volumio/display/assets/source-icon/streaming.png Dec 17 14:40:18 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 17 14:40:18 so1905-bfc4 display[2218]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Dec 17 14:40:18 so1905-bfc4 display[2218]: 文件大小: 6344 字节 Dec 17 14:40:18 so1905-bfc4 display[2218]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 100px) Dec 17 14:40:18 so1905-bfc4 node[2239]: Stopped aP Dec 17 14:40:18 so1905-bfc4 node[2239]: Start wireless flow Dec 17 14:40:18 so1905-bfc4 node[2239]: Stopped hotspot (if there).. Dec 17 14:40:18 so1905-bfc4 display[2218]: setImageSrc: this=0x2b5cbf0, str=/volumio/display/assets/source-icon/streaming.png Dec 17 14:40:18 so1905-bfc4 display[2218]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 17 14:40:18 so1905-bfc4 sudo[2430]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/ip addr flush dev wlan0 Dec 17 14:40:18 so1905-bfc4 sudo[2430]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:19 so1905-bfc4 sudo[2430]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:19 so1905-bfc4 sudo[2441]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 17 14:40:19 so1905-bfc4 node[2219]: Failed to retrieve remote version or perform update: { Error: getaddrinfo EBUSY ota.synaudio.org ota.synaudio.org:443 Dec 17 14:40:19 so1905-bfc4 node[2219]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:56:26) Dec 17 14:40:19 so1905-bfc4 node[2219]: errno: 'EBUSY', Dec 17 14:40:19 so1905-bfc4 node[2219]: code: 'EBUSY', Dec 17 14:40:19 so1905-bfc4 node[2219]: syscall: 'getaddrinfo', Dec 17 14:40:19 so1905-bfc4 node[2219]: hostname: 'ota.synaudio.org', Dec 17 14:40:19 so1905-bfc4 node[2219]: host: 'ota.synaudio.org', Dec 17 14:40:19 so1905-bfc4 node[2219]: port: 443 } Dec 17 14:40:19 so1905-bfc4 sudo[2441]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:19 so1905-bfc4 sudo[2441]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:19 so1905-bfc4 node[2239]: DHCP IP Dec 17 14:40:19 so1905-bfc4 node[2239]: Start ap Dec 17 14:40:19 so1905-bfc4 wpa_supplicant[2454]: Successfully initialized wpa_supplicant Dec 17 14:40:19 so1905-bfc4 systemd-networkd[2056]: wlan0: Link UP Dec 17 14:40:19 so1905-bfc4 sudo[2455]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/udhcpc -i wlan0 -v Dec 17 14:40:19 so1905-bfc4 sudo[2455]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:19 so1905-bfc4 udhcpc[2467]: wlan0: deconfigured Dec 17 14:40:20 so1905-bfc4 node[2239]: trying... Dec 17 14:40:20 so1905-bfc4 mpd[2293]: Dec 17 14:40 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 17 14:40:20 so1905-bfc4 mpd[2293]: Dec 17 14:40 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 17 14:40:20 so1905-bfc4 mpd[2293]: Dec 17 14:40 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 17 14:40:20 so1905-bfc4 sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 17 14:40:20 so1905-bfc4 sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:21 so1905-bfc4 sudo[2469]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:21 so1905-bfc4 node[2239]: Connected to: ---- Dec 17 14:40:21 so1905-bfc4 node[2239]: ---- Dec 17 14:40:21 so1905-bfc4 sudo[2482]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:40:21 so1905-bfc4 node[2239]: trying... Dec 17 14:40:21 so1905-bfc4 sudo[2482]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:21 so1905-bfc4 systemd[1]: Started Music Player Daemon. Dec 17 14:40:21 so1905-bfc4 sudo[2482]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:21 so1905-bfc4 udisks-glue[2393]: Updating DB (#1) ... Dec 17 14:40:21 so1905-bfc4 udisks-glue[2393]: volume:100% repeat: off random: off single: off consume: off Dec 17 14:40:21 so1905-bfc4 sudo[2488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 17 14:40:21 so1905-bfc4 sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:21 so1905-bfc4 sudo[2488]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:21 so1905-bfc4 node[2239]: Connected to: ---- Dec 17 14:40:21 so1905-bfc4 node[2239]: ---- Dec 17 14:40:21 so1905-bfc4 node[2239]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Dec 17 14:40:22 so1905-bfc4 sudo[2503]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:40:22 so1905-bfc4 sudo[2503]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:22 so1905-bfc4 sudo[2503]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:22 so1905-bfc4 node[2239]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Dec 17 14:40:22 so1905-bfc4 node[2239]: trying... Dec 17 14:40:22 so1905-bfc4 sudo[2514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 17 14:40:22 so1905-bfc4 sudo[2514]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:22 so1905-bfc4 sudo[2514]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:22 so1905-bfc4 node[2239]: Connected to: ---- Dec 17 14:40:22 so1905-bfc4 node[2239]: ---- Dec 17 14:40:23 so1905-bfc4 sudo[2524]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:40:23 so1905-bfc4 sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:23 so1905-bfc4 sudo[2524]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:23 so1905-bfc4 node[2239]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Dec 17 14:40:23 so1905-bfc4 volumio[2380]: info: ------------------------------------------- Dec 17 14:40:23 so1905-bfc4 volumio[2380]: info: ----- Synaudio ---- Dec 17 14:40:23 so1905-bfc4 volumio[2380]: info: ------------------------------------------- Dec 17 14:40:23 so1905-bfc4 volumio[2380]: info: ----- System startup ---- Dec 17 14:40:23 so1905-bfc4 volumio[2380]: info: ------------------------------------------- Dec 17 14:40:23 so1905-bfc4 node[2239]: trying... Dec 17 14:40:23 so1905-bfc4 sudo[2542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 17 14:40:23 so1905-bfc4 sudo[2542]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:23 so1905-bfc4 sudo[2542]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:23 so1905-bfc4 node[2239]: Connected to: ---- Dec 17 14:40:23 so1905-bfc4 node[2239]: ---- Dec 17 14:40:24 so1905-bfc4 sudo[2552]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:40:24 so1905-bfc4 sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:24 so1905-bfc4 wpa_supplicant[2462]: wlan0: Trying to associate with c8:75:f4:65:68:fb (SSID='VeriderTech' freq=5180 MHz) Dec 17 14:40:24 so1905-bfc4 kernel: RTW: rtw_set_802_11_connect(wlan0) fw_state=0x00000008 Dec 17 14:40:24 so1905-bfc4 sudo[2552]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:24 so1905-bfc4 node[2239]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Dec 17 14:40:24 so1905-bfc4 kernel: RTW: start auth Dec 17 14:40:24 so1905-bfc4 kernel: RTW: auth success, start assoc Dec 17 14:40:24 so1905-bfc4 kernel: RTW: assoc success Dec 17 14:40:24 so1905-bfc4 wpa_supplicant[2462]: wlan0: Associated with c8:75:f4:65:68:fb Dec 17 14:40:24 so1905-bfc4 wpa_supplicant[2462]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 17 14:40:24 so1905-bfc4 kernel: RTW: ============ STA [c8:75:f4:65:68:fb] =================== Dec 17 14:40:24 so1905-bfc4 kernel: RTW: mac_id : 0 Dec 17 14:40:24 so1905-bfc4 kernel: RTW: wireless_mode : 0x44 Dec 17 14:40:24 so1905-bfc4 kernel: RTW: mimo_type : 0 Dec 17 14:40:24 so1905-bfc4 kernel: RTW: bw_mode : 80MHz, ra_bw_mode : 80MHz Dec 17 14:40:24 so1905-bfc4 kernel: RTW: rate_id : 10 Dec 17 14:40:24 so1905-bfc4 kernel: RTW: rssi : -1 (%), rssi_level : 0 Dec 17 14:40:24 so1905-bfc4 kernel: RTW: is_support_sgi : Y, is_vht_enable : Y Dec 17 14:40:24 so1905-bfc4 kernel: RTW: disable_ra : N, disable_pt : N Dec 17 14:40:24 so1905-bfc4 kernel: RTW: is_noisy : N Dec 17 14:40:24 so1905-bfc4 kernel: RTW: txrx_state : 0 Dec 17 14:40:24 so1905-bfc4 kernel: RTW: curr_tx_rate : CCK_1M (L) Dec 17 14:40:24 so1905-bfc4 kernel: RTW: curr_tx_bw : 20MHz Dec 17 14:40:24 so1905-bfc4 kernel: RTW: curr_retry_ratio : 0 Dec 17 14:40:24 so1905-bfc4 kernel: RTW: ra_mask : 0x00000000003ffff0 Dec 17 14:40:24 so1905-bfc4 wpa_supplicant[2462]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Dec 17 14:40:24 so1905-bfc4 kernel: RTW: recv eapol packet Dec 17 14:40:24 so1905-bfc4 kernel: RTW: send eapol packet Dec 17 14:40:24 so1905-bfc4 wpa_supplicant[2462]: wlan0: WPA: Key negotiation completed with c8:75:f4:65:68:fb [PTK=CCMP GTK=CCMP] Dec 17 14:40:24 so1905-bfc4 wpa_supplicant[2462]: wlan0: CTRL-EVENT-CONNECTED - Connection to c8:75:f4:65:68:fb completed [id=0 id_str=] Dec 17 14:40:24 so1905-bfc4 kernel: RTW: recv eapol packet Dec 17 14:40:24 so1905-bfc4 kernel: RTW: send eapol packet Dec 17 14:40:24 so1905-bfc4 kernel: RTW: set pairwise key camid:0, addr:c8:75:f4:65:68:fb, kid:0, type:AES Dec 17 14:40:24 so1905-bfc4 systemd-networkd[2056]: wlan0: Gained carrier Dec 17 14:40:24 so1905-bfc4 systemd-networkd[2056]: wlan0: Connected WiFi access point: VeriderTech (00:00:00:00:00:00) Dec 17 14:40:24 so1905-bfc4 kernel: RTW: set group key camid:1, addr:c8:75:f4:65:68:fb, kid:1, type:AES Dec 17 14:40:24 so1905-bfc4 systemd-networkd[2056]: wlan0: DHCPv4 address 192.168.2.115/24 via 192.168.2.1 Dec 17 14:40:24 so1905-bfc4 dbus-daemon[2204]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=102 pid=2056 comm="/lib/systemd/systemd-networkd ") Dec 17 14:40:24 so1905-bfc4 avahi-daemon[2203]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.115. Dec 17 14:40:24 so1905-bfc4 avahi-daemon[2203]: New relevant interface wlan0.IPv4 for mDNS. Dec 17 14:40:24 so1905-bfc4 avahi-daemon[2203]: Registering new address record for 192.168.2.115 on wlan0.IPv4. Dec 17 14:40:24 so1905-bfc4 systemd-timesyncd[2188]: Network configuration changed, trying to establish connection. Dec 17 14:40:40 so1905-bfc4 systemd-timesyncd[2188]: Initial synchronization to time server 91.189.91.157:123 (ntp.ubuntu.com). Dec 17 14:40:40 so1905-bfc4 node[2239]: trying... Dec 17 14:40:40 so1905-bfc4 systemd[1]: Starting Hostname Service... Dec 17 14:40:40 so1905-bfc4 systemd[1]: Started Samba NMB Daemon. Dec 17 14:40:40 so1905-bfc4 systemd[1]: Starting Samba SMB Daemon... Dec 17 14:40:40 so1905-bfc4 sudo[2566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 17 14:40:40 so1905-bfc4 sudo[2566]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Found new core plugin music_service/cdplayer. Adding it Dec 17 14:40:40 so1905-bfc4 sudo[2566]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:40 so1905-bfc4 node[2239]: Connected to: ----VeriderTech Dec 17 14:40:40 so1905-bfc4 node[2239]: ---- Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Configured plugin music_service/cdplayer cannot be loaded. Removing from configuration Dec 17 14:40:40 so1905-bfc4 sudo[2584]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Plugin folders cleanup Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Scanning into folder /volumio/app/plugins/ Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Scanning category audio_interface Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Scanning category miscellanea Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Scanning category music_service Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Scanning category plugins.json Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Scanning category system_controller Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Scanning category user_interface Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Scanning into folder /data/plugins/ Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Plugin folders cleanup completed Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: ------------------------------------------- Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: ----- Core plugins startup ---- Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: ------------------------------------------- Dec 17 14:40:40 so1905-bfc4 sudo[2584]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Loading plugins from folder /volumio/app/plugins/ Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Adding plugin upnp to MyMusic Plugins Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 17 14:40:40 so1905-bfc4 dbus-daemon[2204]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 17 14:40:40 so1905-bfc4 systemd[1]: Started Hostname Service. Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Loading plugins from folder /data/plugins/ Dec 17 14:40:40 so1905-bfc4 volumio[2380]: info: Loading plugin "system"... Dec 17 14:40:41 so1905-bfc4 sudo[2584]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:41 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/system_controller/system/index.js Dec 17 14:40:41 so1905-bfc4 avahi-daemon[2203]: Withdrawing address record for 192.168.2.115 on wlan0. Dec 17 14:40:41 so1905-bfc4 avahi-daemon[2203]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.115. Dec 17 14:40:41 so1905-bfc4 systemd-timesyncd[2188]: No network connectivity, watching for changes. Dec 17 14:40:41 so1905-bfc4 volumio[2380]: info: Loading plugin "appearance"... Dec 17 14:40:41 so1905-bfc4 systemd-timesyncd[2188]: Network configuration changed, trying to establish connection. Dec 17 14:40:41 so1905-bfc4 avahi-daemon[2203]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 17 14:40:41 so1905-bfc4 avahi-daemon[2203]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.118. Dec 17 14:40:41 so1905-bfc4 avahi-daemon[2203]: New relevant interface wlan0.IPv4 for mDNS. Dec 17 14:40:41 so1905-bfc4 avahi-daemon[2203]: Registering new address record for 192.168.2.118 on wlan0.IPv4. Dec 17 14:40:41 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js Dec 17 14:40:41 so1905-bfc4 node[2239]: ... joined AP, wlan0 IPv4 is 192.168.2.115, ipV6 is undefined Dec 17 14:40:41 so1905-bfc4 node[2239]: It's done! AP Dec 17 14:40:41 so1905-bfc4 udhcpc[2606]: wlan0: bound: IP=192.168.2.118/255.255.255.0 router=192.168.2.1 domain="lan" dns="192.168.2.1" lease=43200 Dec 17 14:40:41 so1905-bfc4 sudo[2455]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:41 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 17 14:40:41 so1905-bfc4 go-librespot[2612]: go-librespot daemon starting... Dec 17 14:40:41 so1905-bfc4 smbd[2577]: [2025/12/17 14:40:41.902353, 0] ../../source3/smbd/server.c:1734(main) Dec 17 14:40:41 so1905-bfc4 smbd[2577]: smbd version 4.15.13-Ubuntu started. Dec 17 14:40:41 so1905-bfc4 smbd[2577]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Dec 17 14:40:42 so1905-bfc4 sshd[2619]: rexec line 6: Deprecated option UsePrivilegeSeparation Dec 17 14:40:42 so1905-bfc4 sshd[2619]: rexec line 7: Deprecated option KeyRegenerationInterval Dec 17 14:40:42 so1905-bfc4 sshd[2619]: rexec line 8: Deprecated option ServerKeyBits Dec 17 14:40:42 so1905-bfc4 sshd[2619]: rexec line 14: Deprecated option RSAAuthentication Dec 17 14:40:42 so1905-bfc4 sshd[2619]: rexec line 17: Deprecated option RhostsRSAAuthentication Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.412431, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.413293, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9040 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.413649, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.413968, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9041 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.414268, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.414565, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9042 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.415022, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.415314, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9043 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.415605, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:42.415893, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:42 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9044 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:42 so1905-bfc4 sshd[2619]: reprocess config line 14: Deprecated option RSAAuthentication Dec 17 14:40:42 so1905-bfc4 sshd[2619]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Dec 17 14:40:42 so1905-bfc4 volumio[2380]: info: Loading plugin "network"... Dec 17 14:40:42 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/system_controller/network/index.js Dec 17 14:40:42 so1905-bfc4 volumio[2380]: [network] Refreshing Cached IP Addresses Dec 17 14:40:42 so1905-bfc4 systemd[1]: Started Samba SMB Daemon. Dec 17 14:40:42 so1905-bfc4 systemd[1]: Reached target Multi-User System. Dec 17 14:40:42 so1905-bfc4 systemd[1]: Reached target Graphical Interface. Dec 17 14:40:42 so1905-bfc4 volumio[2380]: ControllerNetwork autoStartNetworkConfig true Dec 17 14:40:42 so1905-bfc4 volumio[2380]: info: Loading plugin "services"... Dec 17 14:40:42 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/system_controller/services/index.js Dec 17 14:40:42 so1905-bfc4 volumio[2380]: info: Loading plugin "alsa_controller"... Dec 17 14:40:42 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Dec 17 14:40:42 so1905-bfc4 sudo[2630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:40:42 so1905-bfc4 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 17 14:40:42 so1905-bfc4 go-librespot[2624]: time="2025-12-17T14:40:42+08:00" level=info msg="running go-librespot 0.0.0" Dec 17 14:40:42 so1905-bfc4 sudo[2630]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:42 so1905-bfc4 sudo[2636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:40:42 so1905-bfc4 volumio[2380]: info: Applying required configuration parameters for plugin alsa_controller Dec 17 14:40:43 so1905-bfc4 sudo[2630]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:43 so1905-bfc4 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 17 14:40:43 so1905-bfc4 sudo[2636]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:43 so1905-bfc4 systemd[1]: Finished Update UTMP about System Runlevel Changes. Dec 17 14:40:43 so1905-bfc4 go-librespot[2624]: time="2025-12-17T14:40:43+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 17 14:40:43 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 17 14:40:43 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 17 14:40:43 so1905-bfc4 sudo[2636]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [alsa_controller] Creating shared var alsa.outputdevice='2' Dec 17 14:40:43 so1905-bfc4 volumio[2380]: info: Loading plugin "wizard"... Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Dec 17 14:40:43 so1905-bfc4 volumio[2380]: info: Loading plugin "volumio_command_line_client"... Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [volumio_command_line_client] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.416591, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.417166, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9040 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.417513, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.417832, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9041 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.418143, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.418448, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9042 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.418756, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.419060, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9043 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.419353, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:43.419736, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:43 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9044 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:43 so1905-bfc4 volumio[2380]: info: Loading plugin "upnp"... Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Dec 17 14:40:43 so1905-bfc4 smbd[2577]: [2025/12/17 14:40:43.640935, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Dec 17 14:40:43 so1905-bfc4 smbd[2577]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [upnp] [1765953643645] Starting Upmpd Daemon Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [upnp] CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 14:40:43 so1905-bfc4 volumio[2380]: info: Loading plugin "my_music"... Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Dec 17 14:40:43 so1905-bfc4 smbd[2577]: [2025/12/17 14:40:43.660674, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Dec 17 14:40:43 so1905-bfc4 smbd[2577]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Dec 17 14:40:43 so1905-bfc4 volumio[2380]: info: Loading plugin "mpd"... Dec 17 14:40:43 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Dec 17 14:40:44 so1905-bfc4 volumio[2380]: [mpd] MPD: starting udev watcher for USB partitions Dec 17 14:40:44 so1905-bfc4 volumio[2380]: [mpd] Creating MPD Configuration file Dec 17 14:40:44 so1905-bfc4 volumio[2380]: info: Loading plugin "upnp_browser"... Dec 17 14:40:44 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.421370, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.421887, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9040 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.422289, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.422638, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9041 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.422949, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.423261, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9042 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.428143, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.428623, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9043 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.428952, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: Packet send failed to 192.168.2.255(137) ERRNO=Network is unreachable Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: [2025/12/17 14:40:44.429264, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Dec 17 14:40:44 so1905-bfc4 nmbd[2297]: retransmit_or_expire_response_records: Failed to resend packet id 9044 to IP 192.168.2.255 on subnet 192.168.2.115 Dec 17 14:40:44 so1905-bfc4 sudo[2666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 14:40:44 so1905-bfc4 sudo[2668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 14:40:44 so1905-bfc4 sudo[2666]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:44 so1905-bfc4 sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:40:44 so1905-bfc4 sudo[2666]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:44 so1905-bfc4 systemd[1]: Stopping Music Player Daemon... Dec 17 14:40:45 so1905-bfc4 systemd[1]: mpd.service: Succeeded. Dec 17 14:40:45 so1905-bfc4 systemd[1]: Stopped Music Player Daemon. Dec 17 14:40:45 so1905-bfc4 systemd[1]: Starting Music Player Daemon... Dec 17 14:40:45 so1905-bfc4 sshd[2619]: Accepted password for volumio from 192.168.2.237 port 53049 ssh2 Dec 17 14:40:45 so1905-bfc4 volumio[2380]: info: Loading plugin "networkfs"... Dec 17 14:40:45 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Dec 17 14:40:45 so1905-bfc4 sshd[2619]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Dec 17 14:40:45 so1905-bfc4 volumio[2380]: [networkfs] Starting IP address watcher Dec 17 14:40:45 so1905-bfc4 systemd-logind[2222]: New session c1 of user volumio. Dec 17 14:40:45 so1905-bfc4 systemd[1]: Created slice User Slice of UID 1000. Dec 17 14:40:45 so1905-bfc4 systemd[1]: Starting User Runtime Directory /run/user/1000... Dec 17 14:40:45 so1905-bfc4 volumio[2380]: [networkfs] Started network interface ip monitor watcher Dec 17 14:40:45 so1905-bfc4 volumio[2380]: [networkfs] CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 14:40:45 so1905-bfc4 volumio[2380]: info: Loading plugin "alarm-clock"... Dec 17 14:40:45 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Dec 17 14:40:45 so1905-bfc4 systemd[1]: Finished User Runtime Directory /run/user/1000. Dec 17 14:40:45 so1905-bfc4 systemd[1]: Starting User Manager for UID 1000... Dec 17 14:40:45 so1905-bfc4 systemd[2688]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Dec 17 14:40:46 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 17 14:40:46 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 17 14:40:46 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 17 14:40:46 so1905-bfc4 go-librespot[2696]: go-librespot daemon starting... Dec 17 14:40:47 so1905-bfc4 go-librespot[2698]: time="2025-12-17T14:40:47+08:00" level=info msg="running go-librespot 0.0.0" Dec 17 14:40:47 so1905-bfc4 volumio[2380]: info: Loading plugin "airplay_emulation"... Dec 17 14:40:47 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Dec 17 14:40:47 so1905-bfc4 volumio[2380]: 执行命令出错: 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 17 14:40:47 so1905-bfc4 volumio[2380]: [airplay_emulation] Starting Shairport Sync Dec 17 14:40:47 so1905-bfc4 go-librespot[2698]: time="2025-12-17T14:40:47+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 17 14:40:47 so1905-bfc4 volumio[2380]: [airplay_emulation] [AirPlay] Started coverart directory watcher Dec 17 14:40:47 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 17 14:40:47 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 17 14:40:47 so1905-bfc4 volumio[2380]: info: Loading plugin "last_100"... Dec 17 14:40:47 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Dec 17 14:40:47 so1905-bfc4 volumio[2380]: info: Loading plugin "spop"... Dec 17 14:40:47 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/spop/index.js Dec 17 14:40:47 so1905-bfc4 mpd[2683]: Dec 17 14:40 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 17 14:40:47 so1905-bfc4 mpd[2683]: Dec 17 14:40 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Dec 17 14:40:47 so1905-bfc4 mpd[2683]: Dec 17 14:40 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Dec 17 14:40:47 so1905-bfc4 systemd[1]: Started Music Player Daemon. Dec 17 14:40:47 so1905-bfc4 sudo[2668]: pam_unix(sudo:session): session closed for user root Dec 17 14:40:48 so1905-bfc4 volumio[2380]: info: Loading plugin "webradio"... Dec 17 14:40:48 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Reached target Paths. Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Reached target Timers. Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Listening on GnuPG network certificate management daemon. Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Listening on GnuPG cryptographic agent and passphrase cache. Dec 17 14:40:48 so1905-bfc4 systemd[2688]: mpd.socket: Failed to create listening socket (0.0.0.0:6600): Address already in use Dec 17 14:40:48 so1905-bfc4 systemd[2688]: mpd.socket: Failed to listen on sockets: Address already in use Dec 17 14:40:48 so1905-bfc4 systemd[2688]: mpd.socket: Failed with result 'resources'. Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Failed to listen on mpd.socket. Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Reached target Sockets. Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Reached target Basic System. Dec 17 14:40:48 so1905-bfc4 systemd[1]: Started User Manager for UID 1000. Dec 17 14:40:48 so1905-bfc4 systemd[1]: Started Session c1 of user volumio. Dec 17 14:40:48 so1905-bfc4 systemd[2688]: Starting Music Player Daemon... Dec 17 14:40:49 so1905-bfc4 kernel: usb0-vbus: disabling Dec 17 14:40:50 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 17 14:40:50 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 17 14:40:50 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 17 14:40:50 so1905-bfc4 go-librespot[2732]: go-librespot daemon starting... Dec 17 14:40:50 so1905-bfc4 mpd[2712]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied Dec 17 14:40:50 so1905-bfc4 systemd[2688]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 17 14:40:50 so1905-bfc4 systemd[2688]: mpd.service: Failed with result 'exit-code'. Dec 17 14:40:50 so1905-bfc4 systemd[2688]: Failed to start Music Player Daemon. Dec 17 14:40:50 so1905-bfc4 systemd[2688]: Reached target Main User Target. Dec 17 14:40:50 so1905-bfc4 systemd[2688]: Startup finished in 4.831s. Dec 17 14:40:51 so1905-bfc4 go-librespot[2735]: time="2025-12-17T14:40:51+08:00" level=info msg="running go-librespot 0.0.0" Dec 17 14:40:51 so1905-bfc4 go-librespot[2735]: time="2025-12-17T14:40:51+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 17 14:40:51 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 17 14:40:51 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 17 14:40:51 so1905-bfc4 volumio[2380]: info: Loading plugin "yandex_browser"... Dec 17 14:40:51 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/yandex_browser/index.js Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [yandex_browser] Yandex plugin started Dec 17 14:40:52 so1905-bfc4 volumio[2380]: error: ATTENTION!!!: Plugin yandex_browser does not return adequate promise from onVolumioStart: please update! Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Loading plugin "i2s_dacs"... Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [i2s_dacs] I2S DAC not set, start Auto-detection Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Loading plugin "volumiodiscovery"... Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Dec 17 14:40:52 so1905-bfc4 volumio[2380]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 17 14:40:52 so1905-bfc4 volumio[2380]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 14:40:52 so1905-bfc4 volumio[2380]: *** WARNING *** For more information see Dec 17 14:40:52 so1905-bfc4 node[2380]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 17 14:40:52 so1905-bfc4 volumio[2380]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 17 14:40:52 so1905-bfc4 volumio[2380]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 14:40:52 so1905-bfc4 volumio[2380]: *** WARNING *** For more information see Dec 17 14:40:52 so1905-bfc4 node[2380]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 14:40:52 so1905-bfc4 node[2380]: *** WARNING *** For more information see Dec 17 14:40:52 so1905-bfc4 node[2380]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 17 14:40:52 so1905-bfc4 node[2380]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 14:40:52 so1905-bfc4 node[2380]: *** WARNING *** For more information see Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [volumiodiscovery] Discovery: Started advertising with name: SO1905-bfc4 Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [volumiodiscovery] CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Loading plugin "outputs"... Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Loading plugin "albumart"... Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Plugin example_plugin is not enabled Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Loading plugin "inputs"... Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Loading plugin "pandora"... Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Applying required configuration parameters for plugin pandora Dec 17 14:40:52 so1905-bfc4 volumio[2380]: info: Loading plugin "podcast"... Dec 17 14:40:52 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [podcast] ControllerPodcast::constructor Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: Loading plugin "qobuz"... Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Dec 17 14:40:53 so1905-bfc4 volumio[2380]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: Loading plugin "tidal"... Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [tidal] [1765953653336] ControllerTidalPlugin::constructor Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [tidal] [1765953653337] ControllerTidalPlugin::onVolumioStart Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: Plugin volspotconnect2 is not enabled Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: Loading plugin "updater_comm"... Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: Plugin mpdemulation is not enabled Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: Loading plugin "rest_api"... Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: Loading plugin "websocket"... Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: ___________ START PLUGINS ___________ Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [upnp_browser] CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [upnp_browser] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: [1765953653654] CoreMusicLibrary::Adding element Media Servers Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:53 so1905-bfc4 volumio[2380]: SSDP Client initialized. Dec 17 14:40:53 so1905-bfc4 volumio[2380]: Starting initial SSDP search... Dec 17 14:40:53 so1905-bfc4 volumio[2380]: Discovering on interface: lo Dec 17 14:40:53 so1905-bfc4 volumio[2380]: Discovering on interface: wlan0 Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [airplay_emulation] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [airplay_emulation] Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [last_100] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: [1765953653827] CoreMusicLibrary::Adding element Last_100 Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [spop] Creating Spotify config file Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [spop] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [webradio] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: [1765953653880] CoreMusicLibrary::Adding element Webradio Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [webradio] CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [yandex_browser] Yandex plugin started Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [yandex_browser] [1765953653912] ControllerYandexPlugin::addToBrowseSources Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [yandex_browser] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: [1765953653913] CoreMusicLibrary::Adding element Yandex Music Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [rest_api] API:printToastMessage Dec 17 14:40:53 so1905-bfc4 volumio[2380]: error: Plugin yandex_browser does not return adequate promise from onStart: please update! Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [pandora] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: [1765953653937] CoreMusicLibrary::Adding element Pandora Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [pandora] [1765953653949] ControllerPandora::initializeMQTT Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [pandora] [1765953653951] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [podcast] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: [1765953653971] CoreMusicLibrary::Adding element Podcast Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [qobuz] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: [1765953653979] CoreMusicLibrary::Adding element Qobuz Dec 17 14:40:53 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:53 so1905-bfc4 volumio[2380]: [tidal] [1765953653993] ControllerTidalPlugin::onStart Dec 17 14:40:54 so1905-bfc4 volumio[2380]: [tidal] [1765953654001] ControllerTidalPlugin::addToBrowseSources Dec 17 14:40:54 so1905-bfc4 volumio[2380]: [tidal] CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: [1765953654002] CoreMusicLibrary::Adding element Tidal Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:40:54 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: Loading i18n strings for locale en Dec 17 14:40:54 so1905-bfc4 volumio[2380]: Updating browse sources language Dec 17 14:40:54 so1905-bfc4 volumio[2380]: Cannot find translation for sourceMedia Servers Dec 17 14:40:54 so1905-bfc4 volumio[2380]: Cannot find translation for sourceYandex Music Dec 17 14:40:54 so1905-bfc4 volumio[2380]: Cannot find translation for sourcePodcast Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 14:40:54 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::initPlayerControls Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: BOOT COMPLETED Dec 17 14:40:54 so1905-bfc4 volumio[2380]: [Metrics] CommandRouter: 15s 256.77ms Dec 17 14:40:54 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 14:40:54 so1905-bfc4 kernel: sunxi-spdif 2036000.spdif: active: 1 Dec 17 14:40:54 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 17 14:40:54 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 17 14:40:54 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 17 14:40:54 so1905-bfc4 go-librespot[2756]: go-librespot daemon starting... Dec 17 14:40:54 so1905-bfc4 go-librespot[2758]: time="2025-12-17T14:40:54+08:00" level=info msg="running go-librespot 0.0.0" Dec 17 14:40:56 so1905-bfc4 go-librespot[2758]: time="2025-12-17T14:40:56+08:00" level=info msg="zeroconf server listening on port 36947" Dec 17 14:40:56 so1905-bfc4 go-librespot[2758]: 2025/12/17 14:40:56 [zeroconf] no suitable IPv6 interface: listen udp6 [ff02::]:5353: socket: address family not supported by protocol Dec 17 14:40:59 so1905-bfc4 volumio[2380]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 17 14:40:59 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::Close All Modals sent Dec 17 14:40:59 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:59 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::Close All Modals sent Dec 17 14:40:59 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:40:59 so1905-bfc4 volumio[2380]: Express server listening on port 3000 Dec 17 14:40:59 so1905-bfc4 volumio[2380]: [Metrics] WebUI: 21s 547.05ms Dec 17 14:40:59 so1905-bfc4 volumio[2380]: (node:2380) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Dec 17 14:40:59 so1905-bfc4 volumio[2380]: [pandora] [1765953659781] ControllerPandora::validateAndSetAccountOptions Dec 17 14:40:59 so1905-bfc4 volumio[2380]: [pandora] [1765953659784] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Dec 17 14:40:59 so1905-bfc4 volumio[2380]: info: CoreStateMachine::resetVolumioState Dec 17 14:40:59 so1905-bfc4 volumio[2380]: info: CoreStateMachine::getcurrentVolume Dec 17 14:40:59 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::volumioRetrievevolume Dec 17 14:40:59 so1905-bfc4 volumio[2380]: [i2s_dacs] CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 14:41:00 so1905-bfc4 volumio[2380]: [i2s_dacs] Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 17 14:41:00 so1905-bfc4 sudo[2779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:41:00 so1905-bfc4 sudo[2781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:41:00 so1905-bfc4 sudo[2779]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:00 so1905-bfc4 sudo[2781]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:00 so1905-bfc4 sudo[2779]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:00 so1905-bfc4 sudo[2781]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:00 so1905-bfc4 volumio[2380]: [networkfs] IP address changed from "" to "192.168.2.118" Dec 17 14:41:00 so1905-bfc4 volumio[2380]: [networkfs] CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 17 14:41:00 so1905-bfc4 sudo[2808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 14:41:00 so1905-bfc4 sudo[2808]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:00 so1905-bfc4 sudo[2813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 14:41:00 so1905-bfc4 sudo[2808]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:00 so1905-bfc4 sudo[2823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 14:41:00 so1905-bfc4 sudo[2813]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:00 so1905-bfc4 sudo[2813]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:00 so1905-bfc4 sudo[2823]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:00 so1905-bfc4 sudo[2823]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [networkfs] Connecting to wireless device: undefined Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: this.stateMachine.currentVolume: null, vol: 0 Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [airplay_emulation] [AirPlay] Received volume update: {"vol":0,"mute":false,"disableVolumeControl":false} Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [airplay_emulation] Sending DBus command: /usr/bin/dbus-send --system --print-reply --dest=org.gnome.ShairportSync "/org/gnome/ShairportSync" org.gnome.ShairportSync.RemoteControl.SetAirplayVolume double:-30.00 Dec 17 14:41:01 so1905-bfc4 sudo[2837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:41:01 so1905-bfc4 sudo[2848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:41:01 so1905-bfc4 sudo[2848]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:01 so1905-bfc4 sudo[2837]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: CoreStateMachine::pushState Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [rest_api] API:pushState Dec 17 14:41:01 so1905-bfc4 sudo[2848]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:01 so1905-bfc4 sudo[2837]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: CoreStateMachine::updateTrackBlock Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: CorePlayQueue::getTrackBlock Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::volumioRetrievevolume Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [mpd] MPD Permissions set Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [alsa_controller] CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 14:41:01 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:01 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [rest_api] CoreCommandRouter::executeOnPlugin: network , pushNetworkConfiguredStatus Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [network] pushNetworkConfiguredResult { status: 'success', message: 'ok', ssid: 'VeriderTech' } Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [network] Push network configured result Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [network] pushNetworkConfiguredResult callbackIp: networkSsid: VeriderTech Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:02 so1905-bfc4 volumio[2874]: Forking 1 albumart workers Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:02 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:03 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:03 so1905-bfc4 volumio[2380]: [i2s_dacs] Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Dec 17 14:41:03 so1905-bfc4 volumio[2380]: sudo: /usr/sbin/i2cdetect: command not found Dec 17 14:41:03 so1905-bfc4 volumio[2380]: [airplay_emulation] DBus command failed: Command failed: /usr/bin/dbus-send --system --print-reply --dest=org.gnome.ShairportSync "/org/gnome/ShairportSync" org.gnome.ShairportSync.RemoteControl.SetAirplayVolume double:-30.00 Dec 17 14:41:03 so1905-bfc4 volumio[2380]: Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files Dec 17 14:41:03 so1905-bfc4 volumio[2380]: { stdout: '', Dec 17 14:41:03 so1905-bfc4 volumio[2380]: stderr: Dec 17 14:41:03 so1905-bfc4 volumio[2380]: 'Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files\n' } Dec 17 14:41:03 so1905-bfc4 volumio[2380]: [rest_api] API:emitFavourites Dec 17 14:41:03 so1905-bfc4 volumio[2380]: [spop] Spotify config file written Dec 17 14:41:03 so1905-bfc4 volumio[2380]: [rest_api] Could not play sound: Cannot read property 'playSound' of undefined Dec 17 14:41:03 so1905-bfc4 sudo[2919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Dec 17 14:41:03 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:03 so1905-bfc4 sudo[2919]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:03 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:03 so1905-bfc4 systemd[1]: Stopping go-librespot Daemon... Dec 17 14:41:03 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Succeeded. Dec 17 14:41:03 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 17 14:41:03 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 17 14:41:03 so1905-bfc4 go-librespot[2927]: go-librespot daemon starting... Dec 17 14:41:03 so1905-bfc4 sudo[2919]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:03 so1905-bfc4 volumio[2380]: sendCommand: Command { name: 'playlistinfo', args: [] } Dec 17 14:41:03 so1905-bfc4 volumio[2380]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Dec 17 14:41:03 so1905-bfc4 volumio[2380]: sendCommand: Command { name: 'search album ""', args: [] } Dec 17 14:41:03 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:04 so1905-bfc4 go-librespot[2933]: time="2025-12-17T14:41:04+08:00" level=info msg="running go-librespot 0.0.0" Dec 17 14:41:04 so1905-bfc4 go-librespot[2933]: time="2025-12-17T14:41:04+08:00" level=debug msg="app state loaded" Dec 17 14:41:04 so1905-bfc4 go-librespot[2933]: time="2025-12-17T14:41:04+08:00" level=debug msg="stored credentials not found" Dec 17 14:41:04 so1905-bfc4 go-librespot[2933]: time="2025-12-17T14:41:04+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 17 14:41:04 so1905-bfc4 go-librespot[2933]: time="2025-12-17T14:41:04+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 17 14:41:04 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 17 14:41:04 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [websocket] [pushCmd] {"index":5,"getInfo":0} Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] MPD running with PID2683 Dec 17 14:41:04 so1905-bfc4 volumio[2380]: ,establishing connection Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [spop] No need to fix Spotify hosts Dec 17 14:41:04 so1905-bfc4 volumio[2380]: [mpd] updateQueue error: null Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [rest_api] CoreCommandRouter::executeOnPlugin: network , emitWirelessEvent Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [network] emitWirelessEvent ap Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [network] onceEventMap not found ap Dec 17 14:41:05 so1905-bfc4 systemd[1]: Started Wireless Services. Dec 17 14:41:05 so1905-bfc4 systemd[1]: Startup finished in 3.796s (kernel) + 45.563s (userspace) = 49.359s. Dec 17 14:41:05 so1905-bfc4 volumio[2904]: Starting albumart workers Dec 17 14:41:05 so1905-bfc4 systemd-timesyncd[2188]: Network configuration changed, trying to establish connection. Dec 17 14:41:05 so1905-bfc4 systemd-timesyncd[2188]: Initial synchronization to time server 91.189.91.157:123 (ntp.ubuntu.com). Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [rest_api] API:printToastMessage Dec 17 14:41:05 so1905-bfc4 volumio[2380]: info: CoreStateMachine::setRepeat null single undefined Dec 17 14:41:05 so1905-bfc4 volumio[2380]: info: CoreStateMachine::pushState Dec 17 14:41:05 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [rest_api] API:pushState Dec 17 14:41:05 so1905-bfc4 volumio[2380]: info: CoreStateMachine::setRandom null Dec 17 14:41:05 so1905-bfc4 volumio[2380]: info: CoreStateMachine::pushState Dec 17 14:41:05 so1905-bfc4 volumio[2380]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [rest_api] API:pushState Dec 17 14:41:05 so1905-bfc4 volumio[2380]: sendCommand: Command { name: 'playlistinfo', args: [] } Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [mpd] updateQueue error: null Dec 17 14:41:05 so1905-bfc4 display[2218]: setImageSrc: this=0x2b44850, str=http://127.0.0.1:3000/albumart Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [websocket] volumioGetState called before stateMachine initialization, returning empty state Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [websocket] volumioGetState called before stateMachine initialization, returning empty state Dec 17 14:41:05 so1905-bfc4 volumio[2380]: [airplay_emulation] Starting Shairport Sync Dec 17 14:41:06 so1905-bfc4 volumio[2380]: [websocket] volumioGetState called before stateMachine initialization, returning empty state Dec 17 14:41:06 so1905-bfc4 volumio[2380]: [websocket] Received Get System Version Dec 17 14:41:06 so1905-bfc4 sudo[2960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 17 14:41:06 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 14:41:06 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 17 14:41:06 so1905-bfc4 sudo[2958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 14:41:06 so1905-bfc4 sudo[2960]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:06 so1905-bfc4 sudo[2958]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:06 so1905-bfc4 systemd[1]: Started UPnP Renderer front-end to MPD. Dec 17 14:41:06 so1905-bfc4 sudo[2960]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:06 so1905-bfc4 sudo[2975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 14:41:06 so1905-bfc4 sudo[2975]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:06 so1905-bfc4 sudo[2975]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:06 so1905-bfc4 sudo[2992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 14:41:06 so1905-bfc4 sudo[2998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 17 14:41:06 so1905-bfc4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 17 14:41:06 so1905-bfc4 sudo[2958]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:06 so1905-bfc4 sudo[2992]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:06 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 14:41:06 so1905-bfc4 sudo[2998]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:06 so1905-bfc4 sudo[3008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 14:41:06 so1905-bfc4 sudo[2992]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:07 so1905-bfc4 sudo[2998]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:07 so1905-bfc4 volumio[2380]: [websocket] CoreCommandRouter::volumioGetQueue Dec 17 14:41:07 so1905-bfc4 volumio[2380]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 14:41:07 so1905-bfc4 volumio[2380]: TypeError: Cannot read property 'getQueue' of undefined Dec 17 14:41:07 so1905-bfc4 volumio[2380]: at CoreCommandRouter.volumioGetQueue (webpack://Volumio2/./app/index.js?:135:28) Dec 17 14:41:07 so1905-bfc4 volumio[2380]: at Socket.eval (webpack://Volumio2/./app/plugins/user_interface/websocket/index.js?:60:38) Dec 17 14:41:07 so1905-bfc4 volumio[2380]: at Socket.emit (events.js:198:13) Dec 17 14:41:07 so1905-bfc4 volumio[2380]: at eval (webpack://Volumio2/./node_modules/socket.io/lib/socket.js?:503:12) Dec 17 14:41:07 so1905-bfc4 volumio[2380]: at process._tickCallback (internal/process/next_tick.js:61:11) Dec 17 14:41:07 so1905-bfc4 sudo[3008]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:07 so1905-bfc4 volumio[2380]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 14:41:07 so1905-bfc4 sudo[3008]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:07 so1905-bfc4 sudo[3023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 14:41:07 so1905-bfc4 sudo[3023]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 14:41:07 so1905-bfc4 sudo[3023]: pam_unix(sudo:session): session closed for user root Dec 17 14:41:07 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 17 14:41:07 so1905-bfc4 systemd[1]: Stopped go-librespot Daemon. Dec 17 14:41:07 so1905-bfc4 systemd[1]: Started go-librespot Daemon. Dec 17 14:41:07 so1905-bfc4 go-librespot[3069]: go-librespot daemon starting... Dec 17 14:41:08 so1905-bfc4 go-librespot[3094]: time="2025-12-17T14:41:08+08:00" level=info msg="running go-librespot 0.0.0" Dec 17 14:41:08 so1905-bfc4 go-librespot[3094]: time="2025-12-17T14:41:08+08:00" level=debug msg="app state loaded" Dec 17 14:41:08 so1905-bfc4 go-librespot[3094]: time="2025-12-17T14:41:08+08:00" level=debug msg="stored credentials not found" Dec 17 14:41:08 so1905-bfc4 go-librespot[3094]: time="2025-12-17T14:41:08+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 17 14:41:09 so1905-bfc4 go-librespot[3094]: time="2025-12-17T14:41:09+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": EOF" Dec 17 14:41:09 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 17 14:41:09 so1905-bfc4 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 17 14:41:09 so1905-bfc4 sudo[3101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-17 14:40 Dec 17 14:41:09 so1905-bfc4 sudo[3101]: 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