-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Tue 2026-01-13 18:47:03 CST. -- Jan 13 18:46:12 so1905-9644 systemd[1]: Started Network Time Synchronization. Jan 13 18:46:12 so1905-9644 systemd[1]: Reached target System Initialization. Jan 13 18:46:12 so1905-9644 systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 13 18:46:12 so1905-9644 systemd-resolved[2112]: Using system hostname 'so1905-9644'. Jan 13 18:46:12 so1905-9644 systemd[1]: Reached target System Time Set. Jan 13 18:46:12 so1905-9644 systemd[1]: Reached target System Time Synchronized. Jan 13 18:46:12 so1905-9644 systemd[1]: Started Daily apt download activities. Jan 13 18:46:12 so1905-9644 systemd[1]: Started Daily apt upgrade and clean activities. Jan 13 18:46:12 so1905-9644 systemd[1]: Started Periodic ext4 Online Metadata Check for All Filesystems. Jan 13 18:46:12 so1905-9644 systemd[1]: Started Discard unused blocks once a week. Jan 13 18:46:12 so1905-9644 systemd[1]: Started Daily rotation of log files. Jan 13 18:46:12 so1905-9644 systemd[1]: Started Message of the Day. Jan 13 18:46:12 so1905-9644 systemd[1]: Reached target Timers. Jan 13 18:46:12 so1905-9644 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 13 18:46:12 so1905-9644 systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 13 18:46:12 so1905-9644 systemd[1]: Listening on mpd.socket. Jan 13 18:46:12 so1905-9644 systemd[1]: Reached target Sockets. Jan 13 18:46:12 so1905-9644 systemd[1]: Reached target Basic System. Jan 13 18:46:12 so1905-9644 systemd[1]: Condition check resulted in Manage Sound Card State (restore and store) being skipped. Jan 13 18:46:12 so1905-9644 systemd[1]: Starting Save/Restore Sound Card State... Jan 13 18:46:12 so1905-9644 systemd[1]: Starting Apply sysctl settings... Jan 13 18:46:12 so1905-9644 sysctl[2119]: fs.inotify.max_user_watches = 524288 Jan 13 18:46:12 so1905-9644 sysctl[2119]: sysctl: cannot stat /proc/sys/net/ipv6/conf/all/disable_ipv6: No such file or directory Jan 13 18:46:12 so1905-9644 sysctl[2119]: sysctl: cannot stat /proc/sys/net/ipv6/conf/default/disable_ipv6: No such file or directory Jan 13 18:46:12 so1905-9644 sysctl[2119]: sysctl: cannot stat /proc/sys/net/ipv6/conf/lo/disable_ipv6: No such file or directory Jan 13 18:46:13 so1905-9644 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 13 18:46:13 so1905-9644 systemd[1]: Started D-Bus System Message Bus. Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: Found user 'avahi' (UID 107) and group 'avahi' (GID 114). Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: Successfully dropped root privileges. Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: avahi-daemon 0.7 starting up. Jan 13 18:46:13 so1905-9644 systemd[1]: Started display Daemon. Jan 13 18:46:13 so1905-9644 display[2123]: lvgl_display daemon starting... Jan 13 18:46:13 so1905-9644 systemd[1]: Starting Remove Stale Online ext4 Metadata Check Snapshots... Jan 13 18:46:13 so1905-9644 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 13 18:46:13 so1905-9644 systemd[1]: Started Volumio Iptables Module. Jan 13 18:46:13 so1905-9644 systemd[1]: Starting Dispatcher daemon for systemd-networkd... Jan 13 18:46:13 so1905-9644 systemd[1]: Started Set the CPU Frequency Scaling governor. Jan 13 18:46:13 so1905-9644 systemd[1]: Starting Server OTA Update Service... Jan 13 18:46:13 so1905-9644 systemd[1]: Starting Login Service... Jan 13 18:46:13 so1905-9644 systemd[1]: Started mount disks automatically with standby. Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: Successfully called chroot(). Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: Successfully dropped remaining capabilities. Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: Loading service file /services/volumio.service. Jan 13 18:46:13 so1905-9644 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 13 18:46:13 so1905-9644 systemd[1]: Started Volumio First Start Script. Jan 13 18:46:13 so1905-9644 systemd-networkd[2022]: eth0: Gained carrier Jan 13 18:46:13 so1905-9644 kernel: sunxi-gmac 4500000.eth eth0: Link is Up - 100Mbps/Full - flow control off Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: socket() failed: Address family not supported by protocol Jan 13 18:46:13 so1905-9644 serverota.sh[2131]: Upgrade file or version file missing. Exiting. Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: Failed to create IPv6 socket, proceeding in IPv4 only mode Jan 13 18:46:13 so1905-9644 systemd[1]: Started Volumio Log Rotation Service. Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: socket() failed: Address family not supported by protocol Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: New relevant interface lo.IPv4 for mDNS. Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: Network interface enumeration completed. Jan 13 18:46:13 so1905-9644 avahi-daemon[2120]: Registering new address record for 127.0.0.1 on lo.IPv4. Jan 13 18:46:13 so1905-9644 volumio_first_start.sh[2142]: no find file Jan 13 18:46:13 so1905-9644 systemd[1]: Started Volumio SSH enabler. Jan 13 18:46:13 so1905-9644 systemd[1]: Starting Wireless Services... Jan 13 18:46:13 so1905-9644 kernel: random: crng init done Jan 13 18:46:13 so1905-9644 kernel: random: 7 urandom warning(s) missed due to ratelimiting Jan 13 18:46:13 so1905-9644 systemd[1]: Started Network Name Resolution. Jan 13 18:46:13 so1905-9644 systemd[1]: applysysctl.service: Main process exited, code=exited, status=255/EXCEPTION Jan 13 18:46:13 so1905-9644 systemd[1]: applysysctl.service: Failed with result 'exit-code'. Jan 13 18:46:13 so1905-9644 systemd[1]: Failed to start Apply sysctl settings. Jan 13 18:46:13 so1905-9644 dbus-daemon[2121]: [system] Activating systemd to hand-off: service name='org.freedesktop.UDisks' unit='udisks.service' requested by ':1.4' (uid=1000 pid=2135 comm="/usr/bin/udisks-glue --foreground ") Jan 13 18:46:13 so1905-9644 systemd[1]: Finished Load/Save Random Seed. Jan 13 18:46:13 so1905-9644 systemd[1]: e2scrub_reap.service: Succeeded. Jan 13 18:46:13 so1905-9644 systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots. Jan 13 18:46:13 so1905-9644 systemd[1]: iptables.service: Succeeded. Jan 13 18:46:13 so1905-9644 systemd[1]: Finished Server OTA Update Service. Jan 13 18:46:13 so1905-9644 systemd[1]: volumiossh.service: Succeeded. Jan 13 18:46:13 so1905-9644 systemd[1]: Finished Save/Restore Sound Card State. Jan 13 18:46:13 so1905-9644 sudo[2155]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Jan 13 18:46:13 so1905-9644 systemd[1]: Reached target Network. Jan 13 18:46:13 so1905-9644 dbus-daemon[2121]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 13 18:46:14 so1905-9644 systemd[1]: Reached target Network is Online. Jan 13 18:46:14 so1905-9644 sudo[2155]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:14 so1905-9644 systemd[1]: Reached target Sound Card. Jan 13 18:46:14 so1905-9644 systemd-logind[2134]: New seat seat0. Jan 13 18:46:14 so1905-9644 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 13 18:46:14 so1905-9644 systemd[1]: Starting Music Player Daemon... Jan 13 18:46:14 so1905-9644 wifistart.sh[2176]: command failed: No such device (-19) Jan 13 18:46:14 so1905-9644 sudo[2155]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:14 so1905-9644 systemd[1]: Starting Samba NMB Daemon... Jan 13 18:46:14 so1905-9644 systemd[1]: Started NQPTP -- Not Quite PTP. Jan 13 18:46:14 so1905-9644 dnsmasq[2187]: dnsmasq: syntax check OK. Jan 13 18:46:14 so1905-9644 systemd[1]: Starting /etc/rc.local Compatibility... Jan 13 18:46:14 so1905-9644 volumio[2139]: Could not open config: /tmp/upmpdcli.conf Jan 13 18:46:14 so1905-9644 avahi-daemon[2120]: Server startup complete. Host name is so1905-9644.local. Local service cookie is 255471446. Jan 13 18:46:14 so1905-9644 systemd[1]: Starting OpenBSD Secure Shell server... Jan 13 18:46:14 so1905-9644 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Jan 13 18:46:14 so1905-9644 systemd[1]: Starting Permit User Sessions... Jan 13 18:46:14 so1905-9644 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 13 18:46:14 so1905-9644 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 13 18:46:14 so1905-9644 systemd[1]: Started /etc/rc.local Compatibility. Jan 13 18:46:14 so1905-9644 sshd[2217]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Jan 13 18:46:14 so1905-9644 sshd[2217]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Jan 13 18:46:14 so1905-9644 sshd[2217]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Jan 13 18:46:14 so1905-9644 sshd[2217]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Jan 13 18:46:14 so1905-9644 sshd[2217]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Jan 13 18:46:14 so1905-9644 systemd[1]: Finished Permit User Sessions. Jan 13 18:46:14 so1905-9644 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 13 18:46:14 so1905-9644 systemd-networkd[2022]: eth0: DHCPv4 address 192.168.23.110/24 via 192.168.23.1 Jan 13 18:46:14 so1905-9644 avahi-daemon[2120]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.23.110. Jan 13 18:46:14 so1905-9644 systemd-timesyncd[2113]: Network configuration changed, trying to establish connection. Jan 13 18:46:14 so1905-9644 avahi-daemon[2120]: New relevant interface eth0.IPv4 for mDNS. Jan 13 18:46:14 so1905-9644 avahi-daemon[2120]: Registering new address record for 192.168.23.110 on eth0.IPv4. Jan 13 18:46:14 so1905-9644 systemd[1]: Started Login Service. Jan 13 18:46:14 so1905-9644 systemd[1]: Started Getty on tty1. Jan 13 18:46:14 so1905-9644 systemd[1]: Started Serial Getty on ttyAS0. Jan 13 18:46:14 so1905-9644 systemd[1]: Reached target Login Prompts. Jan 13 18:46:14 so1905-9644 display[2136]: 初始化FreeType... Jan 13 18:46:14 so1905-9644 display[2136]: FreeType初始化失败 Jan 13 18:46:14 so1905-9644 display[2136]: [HeartbeatMonitor] 初始化完成 Jan 13 18:46:14 so1905-9644 systemd[1]: Starting Disk Manager (legacy version)... Jan 13 18:46:29 so1905-9644 systemd-timesyncd[2113]: Initial synchronization to time server 185.125.190.58:123 (ntp.ubuntu.com). Jan 13 18:46:29 so1905-9644 dbus-daemon[2121]: [system] Successfully activated service 'org.freedesktop.UDisks' Jan 13 18:46:29 so1905-9644 sshd[2256]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Jan 13 18:46:29 so1905-9644 sshd[2256]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Jan 13 18:46:29 so1905-9644 sshd[2256]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Jan 13 18:46:29 so1905-9644 sshd[2256]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Jan 13 18:46:29 so1905-9644 sshd[2256]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Jan 13 18:46:29 so1905-9644 systemd[1]: Started Disk Manager (legacy version). Jan 13 18:46:29 so1905-9644 sshd[2256]: Server listening on 0.0.0.0 port 22. Jan 13 18:46:29 so1905-9644 dbus-daemon[2121]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.8' (uid=0 pid=2249 comm="/usr/lib/udisks/udisks-daemon --no-debug ") Jan 13 18:46:29 so1905-9644 systemd[1]: Started OpenBSD Secure Shell server. Jan 13 18:46:29 so1905-9644 dnsmasq[2229]: dnsmasq: unknown interface wlan0 Jan 13 18:46:29 so1905-9644 dnsmasq[2229]: unknown interface wlan0 Jan 13 18:46:29 so1905-9644 dnsmasq[2229]: FAILED to start up Jan 13 18:46:29 so1905-9644 systemd[1]: Starting Authorization Manager... Jan 13 18:46:29 so1905-9644 systemd[1]: dnsmasq.service: Control process exited, code=exited, status=2/INVALIDARGUMENT Jan 13 18:46:29 so1905-9644 systemd[1]: dnsmasq.service: Failed with result 'exit-code'. Jan 13 18:46:29 so1905-9644 systemd[1]: Failed to start dnsmasq - A lightweight DHCP and caching DNS server. Jan 13 18:46:29 so1905-9644 avahi-daemon[2120]: Service "SO1905-9644" (/services/volumio.service) successfully established. Jan 13 18:46:29 so1905-9644 systemd[1]: Reached target Host and Network Name Lookups. Jan 13 18:46:29 so1905-9644 polkitd[2268]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 13 18:46:29 so1905-9644 dbus-daemon[2121]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 13 18:46:29 so1905-9644 systemd[1]: Started Authorization Manager. Jan 13 18:46:30 so1905-9644 display[2136]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Jan 13 18:46:30 so1905-9644 display[2136]: 文件大小: 11364916 字节 Jan 13 18:46:30 so1905-9644 nmbd[2203]: [2026/01/13 18:46:30.046690, 0] ../../source3/nmbd/nmbd.c:901(main) Jan 13 18:46:30 so1905-9644 nmbd[2203]: nmbd version 4.15.13-Ubuntu started. Jan 13 18:46:30 so1905-9644 nmbd[2203]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Jan 13 18:46:30 so1905-9644 udisks-glue[2135]: Device file /dev/mmcblk0p6 inserted Jan 13 18:46:30 so1905-9644 display[2136]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 50px) Jan 13 18:46:30 so1905-9644 udisks-glue[2135]: Device file /dev/mmcblk0 inserted Jan 13 18:46:30 so1905-9644 display[2136]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Jan 13 18:46:30 so1905-9644 display[2136]: 文件大小: 11364916 字节 Jan 13 18:46:30 so1905-9644 display[2136]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 36px) Jan 13 18:46:30 so1905-9644 nmbd[2203]: [2026/01/13 18:46:30.141209, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 13 18:46:30 so1905-9644 nmbd[2203]: started asyncdns process 2278 Jan 13 18:46:30 so1905-9644 systemd[1]: Started Samba NMB Daemon. Jan 13 18:46:30 so1905-9644 udisks-glue[2135]: Device file /dev/mmcblk0p4 inserted Jan 13 18:46:30 so1905-9644 systemd[1]: Starting Samba SMB Daemon... Jan 13 18:46:30 so1905-9644 display[2136]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Jan 13 18:46:30 so1905-9644 display[2136]: 文件大小: 11364916 字节 Jan 13 18:46:30 so1905-9644 display[2136]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 40px) Jan 13 18:46:30 so1905-9644 udisks-glue[2135]: Device file /dev/mmcblk0p3 inserted Jan 13 18:46:30 so1905-9644 nmbd[2203]: [2026/01/13 18:46:30.282834, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 13 18:46:30 so1905-9644 nmbd[2203]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.110 for name WORKGROUP<1d>. Jan 13 18:46:30 so1905-9644 nmbd[2203]: This response was from IP 192.168.23.83, reporting an IP address of 192.168.23.83. Jan 13 18:46:30 so1905-9644 udisks-glue[2135]: Device file /dev/mmcblk0p2 inserted Jan 13 18:46:30 so1905-9644 udisks-glue[2135]: Device file /dev/mmcblk0p8 inserted Jan 13 18:46:30 so1905-9644 udisks-glue[2135]: Device file /dev/mmcblk0p1 inserted Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2feb278, str=/volumio/display/assets/source-icon/streaming.png Jan 13 18:46:30 so1905-9644 udisks-glue[2135]: Device file /dev/mmcblk0p7 inserted Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2fef810, str=/volumio/display/assets/source-icon/bt.png Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2fef940, str=/volumio/display/assets/source-icon/aux.png Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/aux.png, size=10326, buffer[0]=89 Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2fefdf0, str=/volumio/display/assets/source-icon/opt.png Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2ff0098, str=/volumio/display/assets/source-icon/opt.png Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2ff03b0, str=/volumio/display/assets/source-icon/hdmi-arc.png Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/hdmi-arc.png, size=7908, buffer[0]=89 Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2ff0c80, str=/volumio/display/assets/source-icon/usb.png Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/usb.png, size=7652, buffer[0]=89 Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2febd98, str=/volumio/display/assets/source-icon/music-library.png Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/music-library.png, size=22848, buffer[0]=89 Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2fec280, str=/volumio/display/assets/source-icon/bt.png Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Jan 13 18:46:30 so1905-9644 display[2136]: setImageSrc: this=0x2fec850, str=/volumio/display/assets/albumart.jpg Jan 13 18:46:30 so1905-9644 display[2136]: read file /volumio/display/assets/albumart.jpg, size=77672, buffer[0]=ff Jan 13 18:46:30 so1905-9644 display[2136]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Jan 13 18:46:30 so1905-9644 display[2136]: Attempting to process with stb_image... Jan 13 18:46:31 so1905-9644 display[2136]: stb_image successfully loaded: 855x855, channels=3 Jan 13 18:46:31 so1905-9644 display[2136]: Successfully processed with stb_image Jan 13 18:46:31 so1905-9644 display[2136]: Loading default image: /volumio/display/assets/albumart.jpg Jan 13 18:46:31 so1905-9644 display[2136]: Loaded default image from file: /volumio/display/assets/albumart.jpg, size=77672 Jan 13 18:46:31 so1905-9644 display[2136]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Jan 13 18:46:31 so1905-9644 display[2136]: Attempting to process with stb_image... Jan 13 18:46:31 so1905-9644 systemd[1]: Started Dispatcher daemon for systemd-networkd. Jan 13 18:46:31 so1905-9644 nmbd[2203]: [2026/01/13 18:46:31.213733, 0] ../../source3/nmbd/nmbd_mynames.c:104(my_name_register_failed) Jan 13 18:46:31 so1905-9644 nmbd[2203]: my_name_register_failed: Failed to register my name VOLUMIO<20> on subnet 192.168.23.110. Jan 13 18:46:31 so1905-9644 nmbd[2203]: [2026/01/13 18:46:31.214322, 0] ../../source3/nmbd/nmbd_namelistdb.c:319(standard_fail_register) Jan 13 18:46:31 so1905-9644 nmbd[2203]: standard_fail_register: Failed to register/refresh name VOLUMIO<20> on subnet 192.168.23.110 Jan 13 18:46:31 so1905-9644 nmbd[2203]: [2026/01/13 18:46:31.214732, 0] ../../source3/nmbd/nmbd_mynames.c:104(my_name_register_failed) Jan 13 18:46:31 so1905-9644 nmbd[2203]: my_name_register_failed: Failed to register my name VOLUMIO<03> on subnet 192.168.23.110. Jan 13 18:46:31 so1905-9644 nmbd[2203]: [2026/01/13 18:46:31.215074, 0] ../../source3/nmbd/nmbd_namelistdb.c:319(standard_fail_register) Jan 13 18:46:31 so1905-9644 nmbd[2203]: standard_fail_register: Failed to register/refresh name VOLUMIO<03> on subnet 192.168.23.110 Jan 13 18:46:31 so1905-9644 nmbd[2203]: [2026/01/13 18:46:31.215360, 0] ../../source3/nmbd/nmbd_mynames.c:104(my_name_register_failed) Jan 13 18:46:31 so1905-9644 nmbd[2203]: my_name_register_failed: Failed to register my name VOLUMIO<00> on subnet 192.168.23.110. Jan 13 18:46:31 so1905-9644 nmbd[2203]: [2026/01/13 18:46:31.215643, 0] ../../source3/nmbd/nmbd_namelistdb.c:319(standard_fail_register) Jan 13 18:46:31 so1905-9644 nmbd[2203]: standard_fail_register: Failed to register/refresh name VOLUMIO<00> on subnet 192.168.23.110 Jan 13 18:46:31 so1905-9644 display[2136]: stb_image successfully loaded: 855x855, channels=3 Jan 13 18:46:31 so1905-9644 display[2136]: Successfully processed with stb_image Jan 13 18:46:31 so1905-9644 display[2136]: Default image loaded successfully Jan 13 18:46:31 so1905-9644 display[2136]: setImageSrc: this=0x2feca20, str=/volumio/display/assets/status-icon/wifi-error.png Jan 13 18:46:31 so1905-9644 display[2136]: read file /volumio/display/assets/status-icon/wifi-error.png, size=1494, buffer[0]=89 Jan 13 18:46:31 so1905-9644 display[2136]: setImageSrc: this=0x2fed860, str=/volumio/display/assets/status-icon/eth-error.png Jan 13 18:46:31 so1905-9644 display[2136]: read file /volumio/display/assets/status-icon/eth-error.png, size=936, buffer[0]=89 Jan 13 18:46:31 so1905-9644 display[2136]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Jan 13 18:46:31 so1905-9644 display[2136]: 文件大小: 6344 字节 Jan 13 18:46:31 so1905-9644 display[2136]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 40px) Jan 13 18:46:31 so1905-9644 display[2136]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf Jan 13 18:46:31 so1905-9644 display[2136]: 文件大小: 11495876 字节 Jan 13 18:46:31 so1905-9644 display[2136]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf (大小: 40px) Jan 13 18:46:31 so1905-9644 display[2136]: setInnerAlign: image_desc is null Jan 13 18:46:31 so1905-9644 display[2136]: setImageSrc: this=0x2ff1ec8, str=/volumio/display/assets/source-icon/streaming.png Jan 13 18:46:31 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Jan 13 18:46:31 so1905-9644 smbd[2281]: [2026/01/13 18:46:31.976678, 0] ../../source3/smbd/server.c:1734(main) Jan 13 18:46:31 so1905-9644 smbd[2281]: smbd version 4.15.13-Ubuntu started. Jan 13 18:46:31 so1905-9644 smbd[2281]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Jan 13 18:46:32 so1905-9644 display[2136]: setImageSrc: this=0x30044c8, str=/volumio/display/assets/source-icon/streaming.png Jan 13 18:46:32 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Jan 13 18:46:32 so1905-9644 display[2136]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Jan 13 18:46:32 so1905-9644 display[2136]: 文件大小: 6344 字节 Jan 13 18:46:32 so1905-9644 display[2136]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 100px) Jan 13 18:46:32 so1905-9644 display[2136]: setImageSrc: this=0x3004bf0, str=/volumio/display/assets/source-icon/streaming.png Jan 13 18:46:32 so1905-9644 systemd[1]: Started Samba SMB Daemon. Jan 13 18:46:32 so1905-9644 display[2136]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Jan 13 18:46:32 so1905-9644 set-cpufreq[2130]: Setting ondemand scheduler for all CPUs Jan 13 18:46:32 so1905-9644 systemd[1]: ondemand.service: Succeeded. Jan 13 18:46:33 so1905-9644 smbd[2281]: [2026/01/13 18:46:33.072611, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Jan 13 18:46:33 so1905-9644 smbd[2281]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Jan 13 18:46:33 so1905-9644 smbd[2281]: [2026/01/13 18:46:33.091774, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Jan 13 18:46:33 so1905-9644 smbd[2281]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Jan 13 18:46:33 so1905-9644 node[2226]: WIRELESS: No wireless interface, exiting Jan 13 18:46:33 so1905-9644 systemd[1]: wireless.service: Control process exited, code=exited, status=1/FAILURE Jan 13 18:46:33 so1905-9644 systemd[1]: wireless.service: Failed with result 'exit-code'. Jan 13 18:46:33 so1905-9644 systemd[1]: Failed to start Wireless Services. Jan 13 18:46:33 so1905-9644 systemd[1]: Started Server OTA Download Service. Jan 13 18:46:33 so1905-9644 systemd[1]: Started Volumio Backend Module. Jan 13 18:46:35 so1905-9644 mpd[2199]: Jan 13 18:46 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 18:46:35 so1905-9644 mpd[2199]: Jan 13 18:46 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 13 18:46:35 so1905-9644 mpd[2199]: Jan 13 18:46 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 13 18:46:35 so1905-9644 systemd[1]: Started Music Player Daemon. Jan 13 18:46:35 so1905-9644 systemd[1]: Reached target Multi-User System. Jan 13 18:46:35 so1905-9644 systemd[1]: Reached target Graphical Interface. Jan 13 18:46:35 so1905-9644 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 13 18:46:35 so1905-9644 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 13 18:46:35 so1905-9644 systemd[1]: Finished Update UTMP about System Runlevel Changes. Jan 13 18:46:35 so1905-9644 systemd[1]: Startup finished in 3.717s (kernel) + 15.011s (userspace) = 18.728s. Jan 13 18:46:38 so1905-9644 node[2297]: Remote version data: { serverVersion: '1.5.6.30', Jan 13 18:46:38 so1905-9644 node[2297]: serverUrl: Jan 13 18:46:38 so1905-9644 node[2297]: 'https://ota.synaudio.org/server/1905/volumio_1.5.6.30.tar.gz', Jan 13 18:46:38 so1905-9644 node[2297]: md5: '5ebcc31243490bcc26b2f86378ca6c09' } Jan 13 18:46:38 so1905-9644 node[2297]: No update required. Jan 13 18:46:39 so1905-9644 volumio[2298]: info: ------------------------------------------- Jan 13 18:46:39 so1905-9644 volumio[2298]: info: ----- Synaudio ---- Jan 13 18:46:39 so1905-9644 volumio[2298]: info: ------------------------------------------- Jan 13 18:46:39 so1905-9644 volumio[2298]: info: ----- System startup ---- Jan 13 18:46:39 so1905-9644 volumio[2298]: info: ------------------------------------------- Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Found new core plugin music_service/cdplayer. Adding it Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Configured plugin music_service/cdplayer cannot be loaded. Removing from configuration Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Plugin folders cleanup Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Scanning into folder /volumio/app/plugins/ Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Scanning category audio_interface Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Scanning category miscellanea Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Scanning category music_service Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Scanning category plugins.json Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Scanning category system_controller Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Scanning category user_interface Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Scanning into folder /data/plugins/ Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Plugin folders cleanup completed Jan 13 18:46:40 so1905-9644 volumio[2298]: info: ------------------------------------------- Jan 13 18:46:40 so1905-9644 volumio[2298]: info: ----- Core plugins startup ---- Jan 13 18:46:40 so1905-9644 volumio[2298]: info: ------------------------------------------- Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Adding plugin upnp to MyMusic Plugins Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Loading plugins from folder /data/plugins/ Jan 13 18:46:40 so1905-9644 volumio[2298]: info: Loading plugin "system"... Jan 13 18:46:40 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/system_controller/system/index.js Jan 13 18:46:41 so1905-9644 volumio[2298]: info: Loading plugin "appearance"... Jan 13 18:46:41 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js Jan 13 18:46:41 so1905-9644 volumio[2298]: info: Loading plugin "network"... Jan 13 18:46:41 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/system_controller/network/index.js Jan 13 18:46:41 so1905-9644 volumio[2298]: [network] Refreshing Cached IP Addresses Jan 13 18:46:41 so1905-9644 volumio[2298]: ControllerNetwork autoStartNetworkConfig false Jan 13 18:46:41 so1905-9644 volumio[2298]: ControllerNetwork getNetworkHardwares Jan 13 18:46:41 so1905-9644 volumio[2298]: info: Loading plugin "services"... Jan 13 18:46:41 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/system_controller/services/index.js Jan 13 18:46:41 so1905-9644 volumio[2298]: info: Loading plugin "alsa_controller"... Jan 13 18:46:41 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Jan 13 18:46:41 so1905-9644 volumio[2298]: info: Applying required configuration parameters for plugin alsa_controller Jan 13 18:46:42 so1905-9644 sudo[2333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 18:46:42 so1905-9644 sudo[2333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:42 so1905-9644 sudo[2335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 18:46:42 so1905-9644 sudo[2338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 18:46:42 so1905-9644 sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 18:46:42 so1905-9644 sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:42 so1905-9644 sudo[2333]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:42 so1905-9644 sudo[2339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:42 so1905-9644 sudo[2338]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:42 so1905-9644 sudo[2338]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:42 so1905-9644 sudo[2335]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:42 so1905-9644 sudo[2339]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:42 so1905-9644 volumio[2298]: [alsa_controller] Creating shared var alsa.outputdevice='2' Jan 13 18:46:42 so1905-9644 volumio[2298]: info: Loading plugin "wizard"... Jan 13 18:46:42 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Jan 13 18:46:42 so1905-9644 volumio[2298]: info: Loading plugin "volumio_command_line_client"... Jan 13 18:46:42 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Jan 13 18:46:42 so1905-9644 volumio[2298]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Jan 13 18:46:42 so1905-9644 volumio[2298]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Jan 13 18:46:42 so1905-9644 volumio[2298]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Jan 13 18:46:42 so1905-9644 volumio[2298]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Jan 13 18:46:42 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:42 so1905-9644 volumio[2298]: info: Loading plugin "upnp"... Jan 13 18:46:42 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Jan 13 18:46:42 so1905-9644 volumio[2298]: info: [1768301202661] Starting Upmpd Daemon Jan 13 18:46:42 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 18:46:42 so1905-9644 volumio[2298]: info: Loading plugin "my_music"... Jan 13 18:46:42 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Jan 13 18:46:42 so1905-9644 volumio[2298]: info: Loading plugin "mpd"... Jan 13 18:46:42 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Jan 13 18:46:43 so1905-9644 volumio[2298]: [mpd] MPD: starting udev watcher for USB partitions Jan 13 18:46:43 so1905-9644 volumio[2298]: [mpd] Creating MPD Configuration file Jan 13 18:46:43 so1905-9644 sudo[2386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 18:46:43 so1905-9644 volumio[2298]: info: Loading plugin "upnp_browser"... Jan 13 18:46:43 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Jan 13 18:46:43 so1905-9644 sudo[2386]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:43 so1905-9644 sudo[2386]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:43 so1905-9644 sudo[2390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 18:46:43 so1905-9644 sudo[2390]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:43 so1905-9644 systemd[1]: Stopping Music Player Daemon... Jan 13 18:46:43 so1905-9644 systemd[1]: mpd.service: Succeeded. Jan 13 18:46:43 so1905-9644 systemd[1]: Stopped Music Player Daemon. Jan 13 18:46:43 so1905-9644 systemd[1]: Starting Music Player Daemon... Jan 13 18:46:44 so1905-9644 volumio[2298]: info: Loading plugin "networkfs"... Jan 13 18:46:44 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Jan 13 18:46:44 so1905-9644 volumio[2298]: [networkfs] Starting IP address watcher Jan 13 18:46:44 so1905-9644 volumio[2298]: [networkfs] Started network interface ip monitor watcher Jan 13 18:46:44 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 18:46:44 so1905-9644 volumio[2298]: info: Loading plugin "alarm-clock"... Jan 13 18:46:44 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Jan 13 18:46:45 so1905-9644 volumio[2298]: info: Loading plugin "airplay_emulation"... Jan 13 18:46:45 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Jan 13 18:46:45 so1905-9644 volumio[2298]: 执行命令出错: 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 Jan 13 18:46:45 so1905-9644 volumio[2298]: [airplay_emulation] Starting Shairport Sync Jan 13 18:46:45 so1905-9644 volumio[2298]: [airplay_emulation] [AirPlay] Started coverart directory watcher Jan 13 18:46:45 so1905-9644 volumio[2298]: info: Loading plugin "last_100"... Jan 13 18:46:45 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Jan 13 18:46:45 so1905-9644 volumio[2298]: info: Loading plugin "spop"... Jan 13 18:46:45 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/spop/index.js Jan 13 18:46:45 so1905-9644 mpd[2402]: Jan 13 18:46 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 18:46:45 so1905-9644 mpd[2402]: Jan 13 18:46 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 13 18:46:45 so1905-9644 mpd[2402]: Jan 13 18:46 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 13 18:46:45 so1905-9644 systemd[1]: Started Music Player Daemon. Jan 13 18:46:45 so1905-9644 sudo[2390]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:45 so1905-9644 volumio[2298]: info: Loading plugin "webradio"... Jan 13 18:46:45 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Jan 13 18:46:48 so1905-9644 volumio[2298]: info: Loading plugin "yandex_browser"... Jan 13 18:46:48 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/yandex_browser/index.js Jan 13 18:46:48 so1905-9644 volumio[2298]: [yandex_browser] Yandex plugin started Jan 13 18:46:48 so1905-9644 volumio[2298]: error: ATTENTION!!!: Plugin yandex_browser does not return adequate promise from onVolumioStart: please update! Jan 13 18:46:48 so1905-9644 volumio[2298]: info: Loading plugin "i2s_dacs"... Jan 13 18:46:48 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Jan 13 18:46:48 so1905-9644 volumio[2298]: [i2s_dacs] I2S DAC not set, start Auto-detection Jan 13 18:46:48 so1905-9644 volumio[2298]: info: Loading plugin "volumiodiscovery"... Jan 13 18:46:48 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Jan 13 18:46:48 so1905-9644 volumio[2298]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 18:46:48 so1905-9644 volumio[2298]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 18:46:48 so1905-9644 node[2298]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 18:46:48 so1905-9644 volumio[2298]: *** WARNING *** For more information see Jan 13 18:46:48 so1905-9644 volumio[2298]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 18:46:48 so1905-9644 volumio[2298]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 18:46:48 so1905-9644 volumio[2298]: *** WARNING *** For more information see Jan 13 18:46:48 so1905-9644 node[2298]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 18:46:48 so1905-9644 node[2298]: *** WARNING *** For more information see Jan 13 18:46:48 so1905-9644 node[2298]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 18:46:48 so1905-9644 node[2298]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 18:46:48 so1905-9644 node[2298]: *** WARNING *** For more information see Jan 13 18:46:48 so1905-9644 volumio[2298]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 18:46:48 so1905-9644 volumio[2298]: [volumiodiscovery] Discovery: Started advertising with name: SO1905-9644 Jan 13 18:46:49 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Loading plugin "outputs"... Jan 13 18:46:49 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Loading plugin "albumart"... Jan 13 18:46:49 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Plugin example_plugin is not enabled Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Loading plugin "inputs"... Jan 13 18:46:49 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Loading plugin "pandora"... Jan 13 18:46:49 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Applying required configuration parameters for plugin pandora Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Loading plugin "podcast"... Jan 13 18:46:49 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Jan 13 18:46:49 so1905-9644 volumio[2298]: [podcast] ControllerPodcast::constructor Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Loading plugin "qobuz"... Jan 13 18:46:49 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Jan 13 18:46:49 so1905-9644 volumio[2298]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Loading plugin "tidal"... Jan 13 18:46:49 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Jan 13 18:46:49 so1905-9644 volumio[2298]: [tidal] [1768301209938] ControllerTidalPlugin::constructor Jan 13 18:46:49 so1905-9644 volumio[2298]: info: [1768301209940] ControllerTidalPlugin::onVolumioStart Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Plugin volspotconnect2 is not enabled Jan 13 18:46:49 so1905-9644 volumio[2298]: info: Loading plugin "updater_comm"... Jan 13 18:46:49 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Jan 13 18:46:50 so1905-9644 volumio[2298]: info: Plugin mpdemulation is not enabled Jan 13 18:46:50 so1905-9644 volumio[2298]: info: Loading plugin "rest_api"... Jan 13 18:46:50 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Jan 13 18:46:50 so1905-9644 volumio[2298]: info: Loading plugin "websocket"... Jan 13 18:46:50 so1905-9644 volumio[2298]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Jan 13 18:46:50 so1905-9644 volumio[2298]: info: ___________ START PLUGINS ___________ Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210258] CoreMusicLibrary::Adding element Media Servers Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:50 so1905-9644 volumio[2298]: SSDP Client initialized. Jan 13 18:46:50 so1905-9644 volumio[2298]: Starting initial SSDP search... Jan 13 18:46:50 so1905-9644 volumio[2298]: Discovering on interface: lo Jan 13 18:46:50 so1905-9644 volumio[2298]: Discovering on interface: eth0 Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: [airplay_emulation] Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 18:46:50 so1905-9644 kernel: usb0-vbus: disabling Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210407] CoreMusicLibrary::Adding element Last_100 Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:50 so1905-9644 volumio[2298]: [spop] Creating Spotify config file Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210464] CoreMusicLibrary::Adding element Webradio Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 18:46:50 so1905-9644 volumio[2298]: [yandex_browser] Yandex plugin started Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210490] ControllerYandexPlugin::addToBrowseSources Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210492] CoreMusicLibrary::Adding element Yandex Music Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:printToastMessage Jan 13 18:46:50 so1905-9644 volumio[2298]: error: Plugin yandex_browser does not return adequate promise from onStart: please update! Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210509] CoreMusicLibrary::Adding element Pandora Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:50 so1905-9644 volumio[2298]: [pandora] [1768301210516] ControllerPandora::initializeMQTT Jan 13 18:46:50 so1905-9644 volumio[2298]: [pandora] [1768301210517] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210536] CoreMusicLibrary::Adding element Podcast Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210548] CoreMusicLibrary::Adding element Qobuz Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210568] ControllerTidalPlugin::onStart Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210579] ControllerTidalPlugin::addToBrowseSources Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 18:46:50 so1905-9644 volumio[2298]: info: [1768301210581] CoreMusicLibrary::Adding element Tidal Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:50 so1905-9644 volumio[2298]: info: Loading i18n strings for locale en Jan 13 18:46:50 so1905-9644 volumio[2298]: Updating browse sources language Jan 13 18:46:50 so1905-9644 volumio[2298]: Cannot find translation for sourceMedia Servers Jan 13 18:46:50 so1905-9644 volumio[2298]: Cannot find translation for sourceYandex Music Jan 13 18:46:50 so1905-9644 volumio[2298]: Cannot find translation for sourcePodcast Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 18:46:50 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::initPlayerControls Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 18:46:50 so1905-9644 volumio[2298]: info: BOOT COMPLETED Jan 13 18:46:50 so1905-9644 volumio[2298]: [Metrics] CommandRouter: 11s 492.66ms Jan 13 18:46:50 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 18:46:50 so1905-9644 kernel: sunxi-spdif 2036000.spdif: active: 1 Jan 13 18:46:56 so1905-9644 volumio[2298]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jan 13 18:46:56 so1905-9644 volumio[2298]: info: CoreCommandRouter::Close All Modals sent Jan 13 18:46:56 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:56 so1905-9644 volumio[2298]: info: CoreCommandRouter::Close All Modals sent Jan 13 18:46:56 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:56 so1905-9644 volumio[2298]: Express server listening on port 3000 Jan 13 18:46:56 so1905-9644 volumio[2298]: [Metrics] WebUI: 17s 672.00ms Jan 13 18:46:56 so1905-9644 volumio[2298]: (node:2298) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jan 13 18:46:56 so1905-9644 volumio[2298]: [pandora] [1768301216373] ControllerPandora::validateAndSetAccountOptions Jan 13 18:46:56 so1905-9644 volumio[2298]: [pandora] [1768301216375] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Jan 13 18:46:56 so1905-9644 volumio[2298]: info: CoreStateMachine::resetVolumioState Jan 13 18:46:56 so1905-9644 volumio[2298]: info: CoreStateMachine::getcurrentVolume Jan 13 18:46:56 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 18:46:56 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 18:46:56 so1905-9644 sudo[2440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 18:46:56 so1905-9644 sudo[2440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:56 so1905-9644 sudo[2440]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:56 so1905-9644 sudo[2445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 18:46:56 so1905-9644 volumio[2298]: [i2s_dacs] Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 13 18:46:56 so1905-9644 sudo[2445]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:56 so1905-9644 sudo[2445]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:57 so1905-9644 volumio[2298]: [networkfs] IP address changed from "" to "192.168.23.110" Jan 13 18:46:57 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 13 18:46:57 so1905-9644 sudo[2467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 13 18:46:57 so1905-9644 sudo[2467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:57 so1905-9644 sudo[2474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 13 18:46:57 so1905-9644 sudo[2474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:57 so1905-9644 sudo[2483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 13 18:46:57 so1905-9644 volumio[2298]: [networkfs] Connecting to wireless device: undefined Jan 13 18:46:57 so1905-9644 volumio[2298]: info: this.stateMachine.currentVolume: null, vol: 0 Jan 13 18:46:57 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:57 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:46:57 so1905-9644 volumio[2298]: [airplay_emulation] [AirPlay] Received volume update: {"vol":0,"mute":false,"disableVolumeControl":false} Jan 13 18:46:57 so1905-9644 sudo[2467]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:57 so1905-9644 volumio[2298]: [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 Jan 13 18:46:57 so1905-9644 sudo[2496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 18:46:57 so1905-9644 sudo[2483]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:57 so1905-9644 sudo[2474]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:57 so1905-9644 sudo[2496]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:57 so1905-9644 volumio[2298]: info: CoreStateMachine::pushState Jan 13 18:46:57 so1905-9644 sudo[2506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 18:46:57 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 18:46:57 so1905-9644 volumio[2298]: [rest_api] API:pushState Jan 13 18:46:57 so1905-9644 volumio[2298]: info: CoreStateMachine::updateTrackBlock Jan 13 18:46:57 so1905-9644 volumio[2298]: info: CorePlayQueue::getTrackBlock Jan 13 18:46:57 so1905-9644 volumio[2298]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 18:46:57 so1905-9644 sudo[2496]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:57 so1905-9644 sudo[2483]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:57 so1905-9644 sudo[2506]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:58 so1905-9644 sudo[2506]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:58 so1905-9644 volumio[2298]: [mpd] MPD Permissions set Jan 13 18:46:58 so1905-9644 volumio[2298]: ControllerNetwork getNetworkHardwares wlan0 undefined Jan 13 18:46:58 so1905-9644 volumio[2298]: ControllerNetwork getNetworkHardwares eth0 { interface: 'eth0:', Jan 13 18:46:58 so1905-9644 volumio[2298]: address: '52:d4:b9:4c:0a:fc', Jan 13 18:46:58 so1905-9644 volumio[2298]: ipv4_address: '192.168.23.110', Jan 13 18:46:58 so1905-9644 volumio[2298]: ipv4_broadcast: '192.168.23.255', Jan 13 18:46:58 so1905-9644 volumio[2298]: ipv4_subnet_mask: '255.255.255.0', Jan 13 18:46:58 so1905-9644 volumio[2298]: up: true, Jan 13 18:46:58 so1905-9644 volumio[2298]: broadcast: true, Jan 13 18:46:58 so1905-9644 volumio[2298]: running: true, Jan 13 18:46:58 so1905-9644 volumio[2298]: multicast: true } Jan 13 18:46:58 so1905-9644 volumio[2298]: [network] ControllerNetwork onWireless start Jan 13 18:46:58 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 13 18:46:58 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 13 18:46:58 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 13 18:46:58 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 13 18:46:58 so1905-9644 volumio[2298]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 13 18:46:58 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 13 18:46:58 so1905-9644 volumio[2298]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 13 18:46:59 so1905-9644 volumio[2298]: [mpd] MPD running with PID2402 Jan 13 18:46:59 so1905-9644 volumio[2298]: ,establishing connection Jan 13 18:46:59 so1905-9644 volumio[2298]: [i2s_dacs] Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jan 13 18:46:59 so1905-9644 volumio[2298]: sudo: /usr/sbin/i2cdetect: command not found Jan 13 18:46:59 so1905-9644 volumio[2298]: [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 Jan 13 18:46:59 so1905-9644 volumio[2298]: Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files Jan 13 18:46:59 so1905-9644 volumio[2298]: { stdout: '', Jan 13 18:46:59 so1905-9644 volumio[2298]: stderr: Jan 13 18:46:59 so1905-9644 volumio[2298]: 'Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files\n' } Jan 13 18:46:59 so1905-9644 volumio[2298]: [rest_api] API:emitFavourites Jan 13 18:46:59 so1905-9644 volumio[2544]: Forking 1 albumart workers Jan 13 18:46:59 so1905-9644 volumio[2298]: [spop] Spotify config file written Jan 13 18:46:59 so1905-9644 sudo[2577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Jan 13 18:46:59 so1905-9644 sudo[2577]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:46:59 so1905-9644 systemd[1]: Started go-librespot Daemon. Jan 13 18:46:59 so1905-9644 sudo[2577]: pam_unix(sudo:session): session closed for user root Jan 13 18:46:59 so1905-9644 go-librespot[2585]: go-librespot daemon starting... Jan 13 18:46:59 so1905-9644 volumio[2298]: sendCommand: Command { name: 'playlistinfo', args: [] } Jan 13 18:46:59 so1905-9644 volumio[2298]: [mpd] MPD error: false == true Jan 13 18:46:59 so1905-9644 volumio[2298]: [mpd] false == true Jan 13 18:46:59 so1905-9644 volumio[2298]: sendCommand: Command { name: 'search album ""', args: [] } Jan 13 18:46:59 so1905-9644 volumio[2298]: [mpd] MPD error: false == true Jan 13 18:46:59 so1905-9644 volumio[2298]: [mpd] false == true Jan 13 18:46:59 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 13 18:46:59 so1905-9644 volumio[2298]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 13 18:46:59 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 13 18:46:59 so1905-9644 volumio[2298]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 13 18:47:00 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 18:47:00 so1905-9644 volumio[2298]: [spop] No need to fix Spotify hosts Jan 13 18:47:00 so1905-9644 go-librespot[2600]: time="2026-01-13T18:47:00+08:00" level=info msg="running go-librespot 0.0.0" Jan 13 18:47:00 so1905-9644 go-librespot[2600]: time="2026-01-13T18:47:00+08:00" level=debug msg="app state loaded" Jan 13 18:47:00 so1905-9644 go-librespot[2600]: time="2026-01-13T18:47:00+08:00" level=debug msg="stored credentials not found" Jan 13 18:47:00 so1905-9644 go-librespot[2600]: time="2026-01-13T18:47:00+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 13 18:47:01 so1905-9644 go-librespot[2600]: time="2026-01-13T18:47:01+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 13 18:47:01 so1905-9644 go-librespot[2600]: time="2026-01-13T18:47:01+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 13 18:47:01 so1905-9644 go-librespot[2600]: time="2026-01-13T18:47:01+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 13 18:47:01 so1905-9644 go-librespot[2600]: time="2026-01-13T18:47:01+08:00" level=info msg="zeroconf server listening on port 36485" Jan 13 18:47:01 so1905-9644 go-librespot[2600]: 2026/01/13 18:47:01 [zeroconf] no suitable IPv6 interface: listen udp6 [ff02::]:5353: socket: address family not supported by protocol Jan 13 18:47:01 so1905-9644 volumio[2298]: sendCommand: Command { name: 'playlistinfo', args: [] } Jan 13 18:47:01 so1905-9644 volumio[2298]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Jan 13 18:47:02 so1905-9644 volumio[2298]: [rest_api] API:printToastMessage Jan 13 18:47:02 so1905-9644 display[2136]: setImageSrc: this=0x2fed860, str=/volumio/display/assets/status-icon/eth_ok.png Jan 13 18:47:02 so1905-9644 display[2136]: read file /volumio/display/assets/status-icon/eth_ok.png, size=990, buffer[0]=89 Jan 13 18:47:02 so1905-9644 volumio[2298]: info: CoreStateMachine::setRepeat null single undefined Jan 13 18:47:02 so1905-9644 volumio[2298]: info: CoreStateMachine::pushState Jan 13 18:47:02 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 18:47:02 so1905-9644 volumio[2298]: [rest_api] API:pushState Jan 13 18:47:02 so1905-9644 volumio[2298]: info: CoreStateMachine::setRandom null Jan 13 18:47:02 so1905-9644 volumio[2570]: Starting albumart workers Jan 13 18:47:02 so1905-9644 volumio[2298]: info: CoreStateMachine::pushState Jan 13 18:47:02 so1905-9644 volumio[2298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 18:47:02 so1905-9644 volumio[2298]: [rest_api] API:pushState Jan 13 18:47:02 so1905-9644 volumio[2298]: [mpd] updateQueue error: null Jan 13 18:47:02 so1905-9644 volumio[2298]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 18:47:02 so1905-9644 volumio[2298]: { Error: ENOENT: no such file or directory, stat '/mnt/USB/68CA-E5A7' Jan 13 18:47:02 so1905-9644 volumio[2298]: at Object.statSync (fs.js:855:3) Jan 13 18:47:02 so1905-9644 volumio[2298]: at eval (webpack://Volumio2/./app/plugins/music_service/mpd/index.js?:1586:23) Jan 13 18:47:02 so1905-9644 volumio[2298]: at MpdClient.handleMessage (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:77:3) Jan 13 18:47:02 so1905-9644 volumio[2298]: at MpdClient.receive (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:68:12) Jan 13 18:47:02 so1905-9644 volumio[2298]: at Socket.eval (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:43:12) Jan 13 18:47:02 so1905-9644 volumio[2298]: at Socket.emit (events.js:198:13) Jan 13 18:47:02 so1905-9644 volumio[2298]: at addChunk (_stream_readable.js:288:12) Jan 13 18:47:02 so1905-9644 volumio[2298]: at readableAddChunk (_stream_readable.js:265:13) Jan 13 18:47:02 so1905-9644 volumio[2298]: at Socket.Readable.push (_stream_readable.js:224:10) Jan 13 18:47:02 so1905-9644 volumio[2298]: at Pipe.onStreamRead [as onread] (internal/stream_base_commons.js:94:17) Jan 13 18:47:02 so1905-9644 volumio[2298]: errno: -2, Jan 13 18:47:02 so1905-9644 volumio[2298]: syscall: 'stat', Jan 13 18:47:02 so1905-9644 volumio[2298]: code: 'ENOENT', Jan 13 18:47:02 so1905-9644 volumio[2298]: path: '/mnt/USB/68CA-E5A7' } Jan 13 18:47:02 so1905-9644 volumio[2298]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 18:47:03 so1905-9644 sudo[2631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-13 18:46 Jan 13 18:47:03 so1905-9644 sudo[2631]: 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.6.30 VOLUMIO_BUILD_DATE=2025-12-25 11:00:29 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system