-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Mon 2026-01-26 15:37:08 CST. -- Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Starting OpenBSD Secure Shell server... Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Starting Permit User Sessions... Jan 26 15:36:00 ft1905-6ee5 volumio[2231]: Could not open config: /tmp/upmpdcli.conf Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started /etc/rc.local Compatibility. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 15:36:00 ft1905-6ee5 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Finished Permit User Sessions. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 26 15:36:00 ft1905-6ee5 sshd[2323]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Jan 26 15:36:00 ft1905-6ee5 sshd[2323]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Jan 26 15:36:00 ft1905-6ee5 sshd[2323]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Jan 26 15:36:00 ft1905-6ee5 sshd[2323]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Jan 26 15:36:00 ft1905-6ee5 sshd[2323]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started Login Service. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started Getty on tty1. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started Serial Getty on ttyAS0. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Reached target Login Prompts. Jan 26 15:36:00 ft1905-6ee5 serverota.sh[2218]: chmod +x /volumio/**/*.sh Jan 26 15:36:00 ft1905-6ee5 serverota.sh[2218]: chmod +x /volumio/**/vollibrespot Jan 26 15:36:00 ft1905-6ee5 avahi-daemon[2203]: Service "FT1905-6ee5" (/services/volumio.service) successfully established. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Starting Disk Manager (legacy version)... Jan 26 15:36:00 ft1905-6ee5 serverota.sh[2218]: Upgrade file or version file missing. Exiting. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Finished Server OTA Update Service. Jan 26 15:36:00 ft1905-6ee5 sshd[2359]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Jan 26 15:36:00 ft1905-6ee5 sshd[2359]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Jan 26 15:36:00 ft1905-6ee5 sshd[2359]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Jan 26 15:36:00 ft1905-6ee5 sshd[2359]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Jan 26 15:36:00 ft1905-6ee5 sshd[2359]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started Volumio Backend Module. Jan 26 15:36:00 ft1905-6ee5 dbus-daemon[2204]: [system] Successfully activated service 'org.freedesktop.UDisks' Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started SocketIO Bridge Service. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started display Daemon. Jan 26 15:36:00 ft1905-6ee5 dbus-daemon[2204]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.8' (uid=0 pid=2358 comm="/usr/lib/udisks/udisks-daemon --no-debug ") Jan 26 15:36:00 ft1905-6ee5 sshd[2359]: Server listening on 0.0.0.0 port 22. Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started Disk Manager (legacy version). Jan 26 15:36:00 ft1905-6ee5 display[2365]: opt = r Jan 26 15:36:00 ft1905-6ee5 display[2365]: optarg = 90 Jan 26 15:36:00 ft1905-6ee5 display[2365]: optind = 3 Jan 26 15:36:00 ft1905-6ee5 display[2365]: argv[optind - 1] = 90 Jan 26 15:36:00 ft1905-6ee5 display[2365]: opt = t Jan 26 15:36:00 ft1905-6ee5 display[2365]: optarg = /dev/ttyAS2 Jan 26 15:36:00 ft1905-6ee5 display[2365]: optind = 5 Jan 26 15:36:00 ft1905-6ee5 display[2365]: argv[optind - 1] = /dev/ttyAS2 Jan 26 15:36:00 ft1905-6ee5 display[2365]: tty driver /dev/ttyAS2 Jan 26 15:36:00 ft1905-6ee5 socketio[2363]: websocket connect Jan 26 15:36:00 ft1905-6ee5 socketio[2363]: Error: No active session Jan 26 15:36:00 ft1905-6ee5 socketio[2363]: [2026-01-26 15:36:00] [info] asio async_connect error: asio.system:111 (Connection refused) Jan 26 15:36:00 ft1905-6ee5 socketio[2363]: [2026-01-26 15:36:00] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jan 26 15:36:00 ft1905-6ee5 socketio[2363]: [2026-01-26 15:36:00] [error] handle_connect error: Connection refused Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Started OpenBSD Secure Shell server. Jan 26 15:36:00 ft1905-6ee5 display[2365]: gui_screen_size_type_check 960 376 1 Jan 26 15:36:00 ft1905-6ee5 display[2365]: SCREEN_SIZE_960X376 Jan 26 15:36:00 ft1905-6ee5 systemd[1]: Starting Authorization Manager... Jan 26 15:36:01 ft1905-6ee5 dnsmasq[2337]: dnsmasq: unknown interface wlan0 Jan 26 15:36:01 ft1905-6ee5 dnsmasq[2337]: unknown interface wlan0 Jan 26 15:36:01 ft1905-6ee5 dnsmasq[2337]: FAILED to start up Jan 26 15:36:01 ft1905-6ee5 systemd[1]: dnsmasq.service: Control process exited, code=exited, status=2/INVALIDARGUMENT Jan 26 15:36:01 ft1905-6ee5 systemd[1]: dnsmasq.service: Failed with result 'exit-code'. Jan 26 15:36:01 ft1905-6ee5 systemd[1]: Failed to start dnsmasq - A lightweight DHCP and caching DNS server. Jan 26 15:36:01 ft1905-6ee5 kernel: sunxi-ehci 4200000.ehci1-controller: ehci_irq: highspeed device disconnect Jan 26 15:36:01 ft1905-6ee5 kernel: usb 1-1: USB disconnect, device number 2 Jan 26 15:36:01 ft1905-6ee5 polkitd[2384]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 26 15:36:01 ft1905-6ee5 dbus-daemon[2204]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 26 15:36:01 ft1905-6ee5 systemd[1]: Started Authorization Manager. Jan 26 15:36:01 ft1905-6ee5 systemd[1]: Reached target Host and Network Name Lookups. Jan 26 15:36:01 ft1905-6ee5 udisks-glue[2230]: Device file /dev/mmcblk0p6 inserted Jan 26 15:36:01 ft1905-6ee5 udisks-glue[2230]: Device file /dev/mmcblk0 inserted Jan 26 15:36:01 ft1905-6ee5 udisks-glue[2230]: Device file /dev/mmcblk0p4 inserted Jan 26 15:36:01 ft1905-6ee5 udisks-glue[2230]: Device file /dev/mmcblk0p3 inserted Jan 26 15:36:01 ft1905-6ee5 udisks-glue[2230]: Device file /dev/mmcblk0p2 inserted Jan 26 15:36:01 ft1905-6ee5 udisks-glue[2230]: Device file /dev/mmcblk0p8 inserted Jan 26 15:36:01 ft1905-6ee5 udisks-glue[2230]: Device file /dev/mmcblk0p1 inserted Jan 26 15:36:01 ft1905-6ee5 udisks-glue[2230]: Device file /dev/mmcblk0p7 inserted Jan 26 15:36:02 ft1905-6ee5 socketio[2363]: IP is 127.0.0.1 Jan 26 15:36:02 ft1905-6ee5 socketio[2363]: Port is 36450 Jan 26 15:36:02 ft1905-6ee5 socketio[2363]: 8 Jan 26 15:36:02 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:02 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:02 ft1905-6ee5 socketio[2363]: Jan 26 15:36:02 ft1905-6ee5 systemd[1]: systemd-rfkill.service: Succeeded. Jan 26 15:36:02 ft1905-6ee5 nmbd[2297]: [2026/01/26 15:36:02.387304, 0] ../../source3/nmbd/nmbd.c:901(main) Jan 26 15:36:02 ft1905-6ee5 nmbd[2297]: nmbd version 4.15.13-Ubuntu started. Jan 26 15:36:02 ft1905-6ee5 nmbd[2297]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Jan 26 15:36:02 ft1905-6ee5 nmbd[2297]: [2026/01/26 15:36:02.495671, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 26 15:36:02 ft1905-6ee5 nmbd[2297]: started asyncdns process 2426 Jan 26 15:36:02 ft1905-6ee5 nmbd[2297]: [2026/01/26 15:36:02.532462, 0] ../../lib/util/become_daemon.c:150(daemon_status) Jan 26 15:36:02 ft1905-6ee5 nmbd[2297]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 26 15:36:02 ft1905-6ee5 nmbd[2297]: [2026/01/26 15:36:02.539393, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Jan 26 15:36:02 ft1905-6ee5 nmbd[2297]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 26 15:36:02 ft1905-6ee5 systemd[1]: Started Dispatcher daemon for systemd-networkd. Jan 26 15:36:03 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:03 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:03 ft1905-6ee5 socketio[2363]: Jan 26 15:36:03 ft1905-6ee5 set-cpufreq[2215]: Setting ondemand scheduler for all CPUs Jan 26 15:36:03 ft1905-6ee5 systemd[1]: ondemand.service: Succeeded. Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: command_len 639 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: pushSrctotal Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "total": 7, Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "cur": 0, Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "curId": "stream:1", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "source": [{ Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "id": "stream:1", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "name": "Media Player", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "code": "stream", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "type": 0 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "id": "bt:1", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "name": "Bluetooth", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "code": "bt", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "type": 4 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "id": "cd:1", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "name": "CD", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "code": "cd", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "type": 1 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "id": "aux:1", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "name": "AUX", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "code": "aux", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "type": 6 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "id": "optic:1", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "name": "DIGITAL OPT1", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "code": "optic", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "id": "optic:2", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "name": "DIGITAL OPT2", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "code": "optic", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "id": "arc:1", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "name": "HDMI ARC", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "code": "arc", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: }] Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: command_len 56 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: pushToneInfo Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "volume": 45, Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "bass": 0, Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "treble": 0 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:04 ft1905-6ee5 kernel: [SNDCODEC][sunxi_check_hs_detect_status][222]:plugout --> switch:0 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: command_len 81 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "source": 0, Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "sourceCode": "stream", Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: "sourceId": "stream:1" Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:04 ft1905-6ee5 socketio[2363]: Jan 26 15:36:04 ft1905-6ee5 node[2236]: Cleaning previous... Jan 26 15:36:04 ft1905-6ee5 kernel: [SNDCODEC][sunxi_check_hs_detect_status][191]:plugin --> switch:1 Jan 26 15:36:05 ft1905-6ee5 node[2220]: Failed to retrieve remote version or perform update: { Error: getaddrinfo EBUSY ota.synaudio.org ota.synaudio.org:443 Jan 26 15:36:05 ft1905-6ee5 node[2220]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:56:26) Jan 26 15:36:05 ft1905-6ee5 node[2220]: errno: 'EBUSY', Jan 26 15:36:05 ft1905-6ee5 node[2220]: code: 'EBUSY', Jan 26 15:36:05 ft1905-6ee5 node[2220]: syscall: 'getaddrinfo', Jan 26 15:36:05 ft1905-6ee5 node[2220]: hostname: 'ota.synaudio.org', Jan 26 15:36:05 ft1905-6ee5 node[2220]: host: 'ota.synaudio.org', Jan 26 15:36:05 ft1905-6ee5 node[2220]: port: 443 } Jan 26 15:36:05 ft1905-6ee5 sudo[2440]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/ip addr flush dev wlan0 Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: command_len 297 Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "title": "中文字符测试", Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "artist": "This is Artist smmmxx ansnndnka ddldldldldd", Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "curtime": 26, Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "totaltime": 240, Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "curtrack": 100, Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: "traystatus": "Disc Loading" Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: Jan 26 15:36:05 ft1905-6ee5 sudo[2440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:05 ft1905-6ee5 sudo[2440]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:05 ft1905-6ee5 sudo[2449]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jan 26 15:36:05 ft1905-6ee5 sudo[2449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:05 ft1905-6ee5 systemd-networkd[2059]: wlan0: Link DOWN Jan 26 15:36:05 ft1905-6ee5 sudo[2449]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:05 ft1905-6ee5 kernel: [SNDCODEC][sunxi_check_hs_detect_status][222]:plugout --> switch:0 Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: [2026-01-26 15:36:05] [info] asio async_connect error: asio.system:111 (Connection refused) Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: [2026-01-26 15:36:05] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jan 26 15:36:05 ft1905-6ee5 socketio[2363]: [2026-01-26 15:36:05] [error] handle_connect error: Connection refused Jan 26 15:36:06 ft1905-6ee5 node[2236]: Stopped aP Jan 26 15:36:06 ft1905-6ee5 node[2236]: Start wireless flow Jan 26 15:36:06 ft1905-6ee5 node[2236]: Stopped hotspot (if there).. Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: command_len 292 Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "title": "中文字符测试", Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "artist": "This is Artist smmmxx ansnndnka ddldldldldd", Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "curtime": 26, Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "totaltime": 240, Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "curtrack": 100, Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: "traystatus": "No Disc" Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:06 ft1905-6ee5 socketio[2363]: Jan 26 15:36:06 ft1905-6ee5 sudo[2466]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/ip addr flush dev wlan0 Jan 26 15:36:06 ft1905-6ee5 sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:06 ft1905-6ee5 sudo[2466]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:06 ft1905-6ee5 sudo[2478]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jan 26 15:36:06 ft1905-6ee5 sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:06 ft1905-6ee5 sudo[2478]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:07 ft1905-6ee5 node[2236]: DHCP IP Jan 26 15:36:07 ft1905-6ee5 node[2236]: Start ap Jan 26 15:36:07 ft1905-6ee5 wpa_supplicant[2487]: Successfully initialized wpa_supplicant Jan 26 15:36:07 ft1905-6ee5 systemd-networkd[2059]: wlan0: Link UP Jan 26 15:36:07 ft1905-6ee5 sudo[2488]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/udhcpc -i wlan0 -v Jan 26 15:36:07 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:07 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:07 ft1905-6ee5 socketio[2363]: Jan 26 15:36:07 ft1905-6ee5 sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:07 ft1905-6ee5 mpd[2295]: Jan 26 15:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 15:36:07 ft1905-6ee5 mpd[2295]: Jan 26 15:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 26 15:36:07 ft1905-6ee5 mpd[2295]: Jan 26 15:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 26 15:36:07 ft1905-6ee5 udhcpc[2503]: wlan0: deconfigured Jan 26 15:36:07 ft1905-6ee5 systemd[1]: Started Music Player Daemon. Jan 26 15:36:08 ft1905-6ee5 node[2236]: trying... Jan 26 15:36:08 ft1905-6ee5 sudo[2505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 15:36:08 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:08 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:08 ft1905-6ee5 socketio[2363]: Jan 26 15:36:08 ft1905-6ee5 sudo[2505]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:08 ft1905-6ee5 sudo[2505]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:08 ft1905-6ee5 node[2236]: Connected to: ---- Jan 26 15:36:08 ft1905-6ee5 node[2236]: ---- Jan 26 15:36:08 ft1905-6ee5 sudo[2515]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 15:36:08 ft1905-6ee5 sudo[2515]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:08 ft1905-6ee5 sudo[2515]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:09 ft1905-6ee5 node[2236]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 15:36:09 ft1905-6ee5 node[2236]: trying... Jan 26 15:36:09 ft1905-6ee5 sudo[2526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 15:36:09 ft1905-6ee5 sudo[2526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:09 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:09 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:09 ft1905-6ee5 socketio[2363]: Jan 26 15:36:09 ft1905-6ee5 sudo[2526]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:09 ft1905-6ee5 node[2236]: Connected to: ---- Jan 26 15:36:09 ft1905-6ee5 node[2236]: ---- Jan 26 15:36:09 ft1905-6ee5 sudo[2536]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 15:36:09 ft1905-6ee5 sudo[2536]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:09 ft1905-6ee5 sudo[2536]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:09 ft1905-6ee5 node[2236]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 15:36:10 ft1905-6ee5 node[2236]: trying... Jan 26 15:36:10 ft1905-6ee5 volumio[2361]: info: ------------------------------------------- Jan 26 15:36:10 ft1905-6ee5 volumio[2361]: info: ----- Synaudio ---- Jan 26 15:36:10 ft1905-6ee5 volumio[2361]: info: ------------------------------------------- Jan 26 15:36:10 ft1905-6ee5 volumio[2361]: info: ----- System startup ---- Jan 26 15:36:10 ft1905-6ee5 volumio[2361]: info: ------------------------------------------- Jan 26 15:36:10 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:10 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:10 ft1905-6ee5 socketio[2363]: Jan 26 15:36:10 ft1905-6ee5 sudo[2554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 15:36:10 ft1905-6ee5 sudo[2554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:10 ft1905-6ee5 sudo[2554]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:10 ft1905-6ee5 node[2236]: Connected to: ---- Jan 26 15:36:10 ft1905-6ee5 node[2236]: ---- Jan 26 15:36:10 ft1905-6ee5 sudo[2564]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 15:36:10 ft1905-6ee5 sudo[2564]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:10 ft1905-6ee5 sudo[2564]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:10 ft1905-6ee5 node[2236]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 15:36:11 ft1905-6ee5 node[2236]: trying... Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: command_len 295 Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "title": "中文字符测试", Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "artist": "This is Artist smmmxx ansnndnka ddldldldldd", Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "curtime": 26, Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "totaltime": 240, Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "curtrack": 100, Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: "traystatus": "Closing..." Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:11 ft1905-6ee5 socketio[2363]: Jan 26 15:36:11 ft1905-6ee5 sudo[2574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 15:36:11 ft1905-6ee5 sudo[2574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:11 ft1905-6ee5 sudo[2574]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:11 ft1905-6ee5 wpa_supplicant[2494]: wlan0: Trying to associate with 14:d8:64:4c:f2:44 (SSID='FORTEX_5G' freq=5240 MHz) Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: rtw_set_802_11_connect(wlan0) fw_state=0x00000008 Jan 26 15:36:11 ft1905-6ee5 node[2236]: Connected to: ---- Jan 26 15:36:11 ft1905-6ee5 node[2236]: ---- Jan 26 15:36:11 ft1905-6ee5 sudo[2586]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 15:36:11 ft1905-6ee5 volumio[2361]: info: Found new core plugin music_service/cdplayer. Adding it Jan 26 15:36:11 ft1905-6ee5 wpa_supplicant[2494]: wlan0: Associated with 14:d8:64:4c:f2:44 Jan 26 15:36:11 ft1905-6ee5 wpa_supplicant[2494]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: start auth Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: auth success, start assoc Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: assoc success Jan 26 15:36:11 ft1905-6ee5 sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: recv eapol packet Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: send eapol packet Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: recv eapol packet Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: ============ STA [14:d8:64:4c:f2:44] =================== Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: mac_id : 0 Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: wireless_mode : 0x44 Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: mimo_type : 0 Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: bw_mode : 80MHz, ra_bw_mode : 80MHz Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: rate_id : 10 Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: rssi : 38 (%), rssi_level : 0 Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: is_support_sgi : Y, is_vht_enable : Y Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: disable_ra : N, disable_pt : N Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: is_noisy : N Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: txrx_state : 0 Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: curr_tx_rate : CCK_1M (L) Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: curr_tx_bw : 20MHz Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: curr_retry_ratio : 0 Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: ra_mask : 0x00000000003ffff0 Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: send eapol packet Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: set pairwise key camid:0, addr:14:d8:64:4c:f2:44, kid:0, type:AES Jan 26 15:36:11 ft1905-6ee5 wpa_supplicant[2494]: wlan0: WPA: Key negotiation completed with 14:d8:64:4c:f2:44 [PTK=CCMP GTK=CCMP] Jan 26 15:36:11 ft1905-6ee5 wpa_supplicant[2494]: wlan0: CTRL-EVENT-CONNECTED - Connection to 14:d8:64:4c:f2:44 completed [id=0 id_str=] Jan 26 15:36:11 ft1905-6ee5 systemd-networkd[2059]: wlan0: Gained carrier Jan 26 15:36:11 ft1905-6ee5 kernel: RTW: set group key camid:1, addr:14:d8:64:4c:f2:44, kid:2, type:AES Jan 26 15:36:11 ft1905-6ee5 systemd-networkd[2059]: wlan0: Connected WiFi access point: FORTEX_5G (00:00:00:00:00:00) Jan 26 15:36:11 ft1905-6ee5 systemd-networkd[2059]: wlan0: DHCPv4 address 192.168.23.145/24 via 192.168.23.1 Jan 26 15:36:11 ft1905-6ee5 avahi-daemon[2203]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.23.145. Jan 26 15:36:11 ft1905-6ee5 avahi-daemon[2203]: New relevant interface wlan0.IPv4 for mDNS. Jan 26 15:36:11 ft1905-6ee5 avahi-daemon[2203]: Registering new address record for 192.168.23.145 on wlan0.IPv4. Jan 26 15:36:11 ft1905-6ee5 systemd-timesyncd[2188]: Network configuration changed, trying to establish connection. Jan 26 15:36:11 ft1905-6ee5 sudo[2586]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:11 ft1905-6ee5 wpa_supplicant[2494]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Jan 26 15:36:11 ft1905-6ee5 volumio[2361]: info: Configured plugin music_service/cdplayer cannot be loaded. Removing from configuration Jan 26 15:36:11 ft1905-6ee5 systemd[1]: Started Samba NMB Daemon. Jan 26 15:36:11 ft1905-6ee5 node[2236]: ... joined AP, wlan0 IPv4 is 192.168.23.145, ipV6 is undefined Jan 26 15:36:11 ft1905-6ee5 node[2236]: It's done! AP Jan 26 15:36:11 ft1905-6ee5 nmbd[2297]: [2026/01/26 15:36:11.888035, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 26 15:36:11 ft1905-6ee5 nmbd[2297]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.145 for name WORKGROUP<1d>. Jan 26 15:36:11 ft1905-6ee5 nmbd[2297]: This response was from IP 192.168.23.83, reporting an IP address of 192.168.23.83. Jan 26 15:36:11 ft1905-6ee5 systemd[1]: Starting Samba SMB Daemon... Jan 26 15:36:42 ft1905-6ee5 systemd-timesyncd[2188]: Initial synchronization to time server 185.125.190.57:123 (ntp.ubuntu.com). Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Plugin folders cleanup Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Scanning into folder /volumio/app/plugins/ Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Scanning category audio_interface Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Scanning category miscellanea Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Scanning category music_service Jan 26 15:36:42 ft1905-6ee5 systemd[1]: Started go-librespot Daemon. Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Scanning category plugins.json Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Scanning category system_controller Jan 26 15:36:42 ft1905-6ee5 go-librespot[2610]: go-librespot daemon starting... Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Scanning category user_interface Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Scanning into folder /data/plugins/ Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Plugin folders cleanup completed Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: ------------------------------------------- Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: ----- Core plugins startup ---- Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: ------------------------------------------- Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Loading plugins from folder /volumio/app/plugins/ Jan 26 15:36:42 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:42 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:42 ft1905-6ee5 socketio[2363]: Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Adding plugin upnp to MyMusic Plugins Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Loading plugins from folder /data/plugins/ Jan 26 15:36:42 ft1905-6ee5 volumio[2361]: info: Loading plugin "system"... Jan 26 15:36:43 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/system_controller/system/index.js Jan 26 15:36:43 ft1905-6ee5 smbd[2605]: [2026/01/26 15:36:43.022063, 0] ../../source3/smbd/server.c:1734(main) Jan 26 15:36:43 ft1905-6ee5 smbd[2605]: smbd version 4.15.13-Ubuntu started. Jan 26 15:36:43 ft1905-6ee5 smbd[2605]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Jan 26 15:36:43 ft1905-6ee5 volumio[2361]: info: Loading plugin "appearance"... Jan 26 15:36:43 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: command_len 295 Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "title": "中文字符测试", Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "artist": "This is Artist smmmxx ansnndnka ddldldldldd", Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "curtime": 26, Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "totaltime": 240, Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "curtrack": 100, Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: Jan 26 15:36:43 ft1905-6ee5 kernel: [SNDCODEC][sunxi_check_hs_detect_status][191]:plugin --> switch:1 Jan 26 15:36:43 ft1905-6ee5 go-librespot[2618]: time="2026-01-26T15:36:43+08:00" level=info msg="running go-librespot 0.0.0" Jan 26 15:36:43 ft1905-6ee5 go-librespot[2618]: time="2026-01-26T15:36:43+08:00" level=debug msg="app state loaded" Jan 26 15:36:43 ft1905-6ee5 go-librespot[2618]: time="2026-01-26T15:36:43+08:00" level=debug msg="stored credentials not found" Jan 26 15:36:43 ft1905-6ee5 socketio[2363]: [2026-01-26 15:36:43] [connect] Successful connection Jan 26 15:36:43 ft1905-6ee5 go-librespot[2618]: time="2026-01-26T15:36:43+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 15:36:43 ft1905-6ee5 systemd[1]: Started Samba SMB Daemon. Jan 26 15:36:43 ft1905-6ee5 systemd[1]: Reached target Multi-User System. Jan 26 15:36:43 ft1905-6ee5 systemd[1]: Reached target Graphical Interface. Jan 26 15:36:43 ft1905-6ee5 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 26 15:36:43 ft1905-6ee5 udhcpc[2633]: wlan0: bound: IP=192.168.23.145/255.255.255.0 router=192.168.23.1 domain="" dns="211.136.192.6 120.196.165.24" lease=7200 Jan 26 15:36:43 ft1905-6ee5 sudo[2488]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:44 ft1905-6ee5 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 26 15:36:44 ft1905-6ee5 systemd[1]: Finished Update UTMP about System Runlevel Changes. Jan 26 15:36:44 ft1905-6ee5 smbd[2605]: [2026/01/26 15:36:44.448273, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Jan 26 15:36:44 ft1905-6ee5 smbd[2605]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Jan 26 15:36:44 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:44 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:44 ft1905-6ee5 socketio[2363]: Jan 26 15:36:44 ft1905-6ee5 smbd[2605]: [2026/01/26 15:36:44.478545, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Jan 26 15:36:44 ft1905-6ee5 smbd[2605]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Jan 26 15:36:44 ft1905-6ee5 kernel: [SNDCODEC][sunxi_check_hs_detect_status][222]:plugout --> switch:0 Jan 26 15:36:44 ft1905-6ee5 volumio[2361]: info: Loading plugin "network"... Jan 26 15:36:44 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/system_controller/network/index.js Jan 26 15:36:44 ft1905-6ee5 volumio[2361]: [network] Refreshing Cached IP Addresses Jan 26 15:36:44 ft1905-6ee5 go-librespot[2618]: time="2026-01-26T15:36:44+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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 26 15:36:44 ft1905-6ee5 go-librespot[2618]: time="2026-01-26T15:36:44+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 26 15:36:44 ft1905-6ee5 go-librespot[2618]: time="2026-01-26T15:36:44+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 26 15:36:44 ft1905-6ee5 go-librespot[2618]: time="2026-01-26T15:36:44+08:00" level=info msg="zeroconf server listening on port 34995" Jan 26 15:36:44 ft1905-6ee5 go-librespot[2618]: 2026/01/26 15:36:44 [zeroconf] no suitable IPv6 interface: listen udp6 [ff02::]:5353: socket: address family not supported by protocol Jan 26 15:36:44 ft1905-6ee5 volumio[2361]: ControllerNetwork autoStartNetworkConfig true Jan 26 15:36:44 ft1905-6ee5 volumio[2361]: info: Loading plugin "services"... Jan 26 15:36:44 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/system_controller/services/index.js Jan 26 15:36:44 ft1905-6ee5 volumio[2361]: info: Loading plugin "alsa_controller"... Jan 26 15:36:44 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Jan 26 15:36:44 ft1905-6ee5 sudo[2646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 15:36:45 ft1905-6ee5 sudo[2646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: info: Applying required configuration parameters for plugin alsa_controller Jan 26 15:36:45 ft1905-6ee5 sudo[2648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 15:36:45 ft1905-6ee5 sudo[2646]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:45 ft1905-6ee5 sudo[2648]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:45 ft1905-6ee5 sudo[2648]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [alsa_controller] Creating shared var alsa.outputdevice='2' Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: info: Loading plugin "wizard"... Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: info: Loading plugin "volumio_command_line_client"... Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:36:45 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:45 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:45 ft1905-6ee5 socketio[2363]: Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: info: Loading plugin "upnp"... Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: info: [1769413005581] Starting Upmpd Daemon Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: info: Loading plugin "my_music"... Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: info: Loading plugin "mpd"... Jan 26 15:36:45 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Jan 26 15:36:46 ft1905-6ee5 volumio[2361]: [mpd] MPD: starting udev watcher for USB partitions Jan 26 15:36:46 ft1905-6ee5 volumio[2361]: [mpd] Creating MPD Configuration file Jan 26 15:36:46 ft1905-6ee5 volumio[2361]: info: Loading plugin "upnp_browser"... Jan 26 15:36:46 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Jan 26 15:36:46 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:46 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:46 ft1905-6ee5 socketio[2363]: Jan 26 15:36:46 ft1905-6ee5 sudo[2674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 15:36:46 ft1905-6ee5 sudo[2676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 15:36:46 ft1905-6ee5 sudo[2674]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:46 ft1905-6ee5 sudo[2676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:36:46 ft1905-6ee5 sudo[2674]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:46 ft1905-6ee5 systemd[1]: Stopping Music Player Daemon... Jan 26 15:36:46 ft1905-6ee5 systemd[1]: mpd.service: Succeeded. Jan 26 15:36:46 ft1905-6ee5 systemd[1]: Stopped Music Player Daemon. Jan 26 15:36:46 ft1905-6ee5 systemd[1]: Starting Music Player Daemon... Jan 26 15:36:47 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:47 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:47 ft1905-6ee5 socketio[2363]: Jan 26 15:36:47 ft1905-6ee5 volumio[2361]: info: Loading plugin "networkfs"... Jan 26 15:36:47 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Jan 26 15:36:47 ft1905-6ee5 volumio[2361]: [networkfs] Starting IP address watcher Jan 26 15:36:47 ft1905-6ee5 volumio[2361]: [networkfs] Started network interface ip monitor watcher Jan 26 15:36:47 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 15:36:47 ft1905-6ee5 volumio[2361]: info: Loading plugin "alarm-clock"... Jan 26 15:36:47 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Jan 26 15:36:48 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:48 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:48 ft1905-6ee5 socketio[2363]: Jan 26 15:36:48 ft1905-6ee5 volumio[2361]: info: Loading plugin "airplay_emulation"... Jan 26 15:36:48 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Jan 26 15:36:48 ft1905-6ee5 volumio[2361]: 执行命令出错: 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 26 15:36:48 ft1905-6ee5 volumio[2361]: [airplay_emulation] Starting Shairport Sync Jan 26 15:36:48 ft1905-6ee5 volumio[2361]: [airplay_emulation] [AirPlay] Started coverart directory watcher Jan 26 15:36:48 ft1905-6ee5 volumio[2361]: info: Loading plugin "last_100"... Jan 26 15:36:48 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Jan 26 15:36:48 ft1905-6ee5 volumio[2361]: info: Loading plugin "spop"... Jan 26 15:36:48 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/spop/index.js Jan 26 15:36:49 ft1905-6ee5 mpd[2690]: Jan 26 15:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 15:36:49 ft1905-6ee5 mpd[2690]: Jan 26 15:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 26 15:36:49 ft1905-6ee5 mpd[2690]: Jan 26 15:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 26 15:36:49 ft1905-6ee5 systemd[1]: Started Music Player Daemon. Jan 26 15:36:49 ft1905-6ee5 sudo[2676]: pam_unix(sudo:session): session closed for user root Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: command_len 228 Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "title": "", Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "artist": "", Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "curtime": 0, Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:49 ft1905-6ee5 socketio[2363]: Jan 26 15:36:49 ft1905-6ee5 volumio[2361]: info: Loading plugin "webradio"... Jan 26 15:36:49 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: command_len 298 Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "curtime": 0, Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:50 ft1905-6ee5 socketio[2363]: Jan 26 15:36:51 ft1905-6ee5 kernel: usb0-vbus: disabling Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: command_len 298 Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "curtime": 1, Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:51 ft1905-6ee5 socketio[2363]: Jan 26 15:36:51 ft1905-6ee5 volumio[2361]: info: Loading plugin "yandex_browser"... Jan 26 15:36:51 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/yandex_browser/index.js Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: command_len 298 Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "curtime": 2, Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:52 ft1905-6ee5 socketio[2363]: Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: [yandex_browser] Yandex plugin started Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: error: ATTENTION!!!: Plugin yandex_browser does not return adequate promise from onVolumioStart: please update! Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: info: Loading plugin "i2s_dacs"... Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: [i2s_dacs] I2S DAC not set, start Auto-detection Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: info: Loading plugin "volumiodiscovery"... Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 15:36:52 ft1905-6ee5 node[2361]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: *** WARNING *** For more information see Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: *** WARNING *** For more information see Jan 26 15:36:52 ft1905-6ee5 node[2361]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 15:36:52 ft1905-6ee5 node[2361]: *** WARNING *** For more information see Jan 26 15:36:52 ft1905-6ee5 node[2361]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 15:36:52 ft1905-6ee5 node[2361]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 15:36:52 ft1905-6ee5 node[2361]: *** WARNING *** For more information see Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: [volumiodiscovery] Discovery: Started advertising with name: FT1905-6ee5 Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: info: Loading plugin "outputs"... Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: info: Loading plugin "albumart"... Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: info: Plugin example_plugin is not enabled Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: info: Loading plugin "inputs"... Jan 26 15:36:52 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: info: Loading plugin "pandora"... Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: info: Applying required configuration parameters for plugin pandora Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: info: Loading plugin "podcast"... Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: command_len 298 Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "curtime": 3, Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:53 ft1905-6ee5 socketio[2363]: Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: [podcast] ControllerPodcast::constructor Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: info: Loading plugin "qobuz"... Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: info: Loading plugin "tidal"... Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: [tidal] [1769413013895] ControllerTidalPlugin::constructor Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: info: [1769413013897] ControllerTidalPlugin::onVolumioStart Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: info: Plugin volspotconnect2 is not enabled Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: info: Loading plugin "updater_comm"... Jan 26 15:36:53 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: Plugin mpdemulation is not enabled Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: Loading plugin "rest_api"... Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: Loading plugin "websocket"... Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: ___________ START PLUGINS ___________ Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014230] CoreMusicLibrary::Adding element Media Servers Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: SSDP Client initialized. Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: Starting initial SSDP search... Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: Discovering on interface: lo Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: Discovering on interface: wlan0 Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [airplay_emulation] Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014383] CoreMusicLibrary::Adding element Last_100 Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [spop] Creating Spotify config file Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014427] CoreMusicLibrary::Adding element Webradio Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [yandex_browser] Yandex plugin started Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "curtime": 10, Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "playstate": 2, Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014452] ControllerYandexPlugin::addToBrowseSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014454] CoreMusicLibrary::Adding element Yandex Music Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:54 ft1905-6ee5 socketio[2363]: Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:printToastMessage Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: error: Plugin yandex_browser does not return adequate promise from onStart: please update! Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014472] CoreMusicLibrary::Adding element Pandora Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [pandora] [1769413014479] ControllerPandora::initializeMQTT Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [pandora] [1769413014480] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014508] CoreMusicLibrary::Adding element Podcast Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014523] CoreMusicLibrary::Adding element Qobuz Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014540] ControllerTidalPlugin::onStart Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014548] ControllerTidalPlugin::addToBrowseSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: [1769413014550] CoreMusicLibrary::Adding element Tidal Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [Tidal] Loading session from /data/configuration/music_service/tidal/tidal-session-oauth.json... Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [Tidal] { token_type: { data: 'Bearer' }, Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: session_id: { data: '1b8605f5-d734-4726-a225-32bdd4ba1d74' }, Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: access_token: Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: { data: Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: 'eyJraWQiOiJ2OU1GbFhqWSIsImFsZyI6IkVTMjU2In0.eyJ0eXBlIjoibzJfYWNjZXNzIiwidWlkIjoyMDY1NzM4MTgsInNjb3BlIjoid191c3Igcl91c3Igd19zdWIiLCJnVmVyIjowLCJzVmVyIjowLCJjaWQiOjEzMzE5LCJjYyI6IlVTIiwiYXQiOiJJTlRFUk5BTCIsImV4cCI6MTc2OTQwNDIzNiwic2lkIjoiMWI4NjA1ZjUtZDczNC00NzI2LWEyMjUtMzJiZGQ0YmExZDc0IiwiaXNzIjoiaHR0cHM6Ly9hdXRoLnRpZGFsLmNvbS92MSJ9.CFGTgt_J87zE6G7CtpLCaGLAhO2adTzGYBkwDL0pUqcwygKXOhSpP-hmbJS61s89xsX38OvmEAwvJFxHtkx0HA' }, Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: refresh_token: Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: { data: Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: 'eyJraWQiOiJoUzFKYTdVMCIsImFsZyI6IkVTNTEyIn0.eyJ0eXBlIjoibzJfcmVmcmVzaCIsInVpZCI6MjA2NTczODE4LCJzY29wZSI6IndfdXNyIHdfc3ViIHJfdXNyIiwiY2lkIjoxMzMxOSwic1ZlciI6MCwiZ1ZlciI6MCwiaXNzIjoiaHR0cHM6Ly9hdXRoLnRpZGFsLmNvbS92MSJ9.AKH9DTSuvwTJL50Ou_hRJETQDnIIvLdbt09aUNXx4cADiu_APzJDzGsDta5Yfujm-VwbDsZcTRYMtxaCFdfzvLjJADpdUxAW-9WZfcObEgYH3wT0ys_dBNXdzL3MezzZ3MUbDT1nBxWypdiOXsUAAfr2wjlzwas5HgxfW_aD15k-j3Aj' }, Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: is_pkce: { data: false }, Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: expiry_time: { data: 1769404238111 } } Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: Loading i18n strings for locale en Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: Updating browse sources language Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: Cannot find translation for sourceMedia Servers Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: Cannot find translation for sourceYandex Music Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: Cannot find translation for sourcePodcast Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::initPlayerControls Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: BOOT COMPLETED Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: [Metrics] CommandRouter: 14s 630.18ms Jan 26 15:36:54 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 15:36:55 ft1905-6ee5 kernel: sunxi-spdif 2036000.spdif: active: 1 Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "curtime": 16, Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "playstate": 2, Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:55 ft1905-6ee5 socketio[2363]: Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "curtime": 30, Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "playstate": 2, Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:56 ft1905-6ee5 socketio[2363]: Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "curtime": 45, Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "playstate": 2, Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:57 ft1905-6ee5 socketio[2363]: Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "curtime": 46, Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "playstate": 2, Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:58 ft1905-6ee5 socketio[2363]: Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: { Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "curtime": 60, Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "playstate": 2, Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: } Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: getState Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: Jan 26 15:36:59 ft1905-6ee5 socketio[2363]: [2026-01-26 15:36:59] [connect] Successful connection Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::Close All Modals sent Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::Close All Modals sent Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: Express server listening on port 3000 Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: [Metrics] WebUI: 21s 81.98ms Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: (node:2361) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: [pandora] [1769413020438] ControllerPandora::validateAndSetAccountOptions Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: [pandora] [1769413020441] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: info: CoreStateMachine::resetVolumioState Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: info: CoreStateMachine::getcurrentVolume Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "curtime": 68, Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: getState Jan 26 15:37:00 ft1905-6ee5 socketio[2363]: Jan 26 15:37:00 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 15:37:00 ft1905-6ee5 sudo[2728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 15:37:00 ft1905-6ee5 sudo[2730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 15:37:00 ft1905-6ee5 sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:37:00 ft1905-6ee5 sudo[2730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:37:00 ft1905-6ee5 sudo[2730]: pam_unix(sudo:session): session closed for user root Jan 26 15:37:00 ft1905-6ee5 sudo[2728]: pam_unix(sudo:session): session closed for user root Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: [i2s_dacs] Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: [networkfs] IP address changed from "" to "192.168.23.145" Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "curtime": 69, Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: getState Jan 26 15:37:01 ft1905-6ee5 socketio[2363]: Jan 26 15:37:01 ft1905-6ee5 sudo[2763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 15:37:01 ft1905-6ee5 sudo[2756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 15:37:01 ft1905-6ee5 sudo[2756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: [networkfs] Connecting to wireless device: undefined Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: info: this.stateMachine.currentVolume: null, vol: 0 Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: [airplay_emulation] [AirPlay] Received volume update: {"vol":0,"mute":false,"disableVolumeControl":false} Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: [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 26 15:37:01 ft1905-6ee5 sudo[2763]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:37:01 ft1905-6ee5 sudo[2775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 15:37:01 ft1905-6ee5 sudo[2766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 15:37:01 ft1905-6ee5 sudo[2756]: pam_unix(sudo:session): session closed for user root Jan 26 15:37:01 ft1905-6ee5 sudo[2763]: pam_unix(sudo:session): session closed for user root Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: info: CoreStateMachine::pushState Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 15:37:01 ft1905-6ee5 sudo[2766]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:37:01 ft1905-6ee5 sudo[2775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: [rest_api] API:pushState Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: info: CoreStateMachine::updateTrackBlock Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: info: CorePlayQueue::getTrackBlock Jan 26 15:37:01 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 15:37:01 ft1905-6ee5 sudo[2781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 15:37:02 ft1905-6ee5 sudo[2775]: pam_unix(sudo:session): session closed for user root Jan 26 15:37:02 ft1905-6ee5 sudo[2766]: pam_unix(sudo:session): session closed for user root Jan 26 15:37:02 ft1905-6ee5 sudo[2781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:37:02 ft1905-6ee5 sudo[2781]: pam_unix(sudo:session): session closed for user root Jan 26 15:37:02 ft1905-6ee5 volumio[2361]: [mpd] MPD Permissions set Jan 26 15:37:02 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 26 15:37:02 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "curtime": 70, Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: getState Jan 26 15:37:02 ft1905-6ee5 socketio[2363]: Jan 26 15:37:02 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 26 15:37:02 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: network , pushNetworkConfiguredStatus Jan 26 15:37:02 ft1905-6ee5 volumio[2361]: [network] pushNetworkConfiguredResult { status: 'success', message: 'ok', ssid: '' } Jan 26 15:37:02 ft1905-6ee5 volumio[2361]: [network] Push network configured result Jan 26 15:37:02 ft1905-6ee5 volumio[2361]: [network] pushNetworkConfiguredResult callbackIp: networkSsid: Jan 26 15:37:02 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: [2026-01-26 15:37:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769413019 101 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: sio connected Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "curtime": 71, Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: Send failed,reason:A payload contained invalid data Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: getState Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: command_len 635 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: pushSrctotal Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "total": 7, Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "cur": 2, Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "curId": "cd:1", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "source": [{ Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "id": "stream:1", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "name": "Media Player", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "code": "stream", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "type": 0 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "id": "bt:1", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "name": "Bluetooth", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "code": "bt", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "type": 4 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "id": "cd:1", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "name": "CD", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "code": "cd", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "type": 1 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "id": "aux:1", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "name": "AUX", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "code": "aux", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "type": 6 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "id": "optic:1", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "name": "DIGITAL OPT1", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "code": "optic", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "id": "optic:2", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "name": "DIGITAL OPT2", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "code": "optic", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "id": "arc:1", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "name": "HDMI ARC", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "code": "arc", Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: }] Jan 26 15:37:03 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: [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 26 15:37:03 ft1905-6ee5 volumio[2361]: Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: { stdout: '', Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: stderr: Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: 'Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files\n' } Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: [spop] Spotify config file written Jan 26 15:37:03 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 15:37:03 ft1905-6ee5 sudo[2849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Jan 26 15:37:03 ft1905-6ee5 kernel: sunxi-spdif 2036000.spdif: active: 1 Jan 26 15:37:03 ft1905-6ee5 sudo[2849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 15:37:03 ft1905-6ee5 volumio[2821]: Forking 1 albumart workers Jan 26 15:37:04 ft1905-6ee5 systemd[1]: Stopping go-librespot Daemon... Jan 26 15:37:04 ft1905-6ee5 systemd[1]: go-librespot-daemon.service: Succeeded. Jan 26 15:37:04 ft1905-6ee5 systemd[1]: Stopped go-librespot Daemon. Jan 26 15:37:04 ft1905-6ee5 systemd[1]: Started go-librespot Daemon. Jan 26 15:37:04 ft1905-6ee5 sudo[2849]: pam_unix(sudo:session): session closed for user root Jan 26 15:37:04 ft1905-6ee5 go-librespot[2863]: go-librespot daemon starting... Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "curtime": 72, Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: Send failed,reason:A payload contained invalid data Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: getState Jan 26 15:37:04 ft1905-6ee5 socketio[2363]: Jan 26 15:37:04 ft1905-6ee5 go-librespot[2869]: time="2026-01-26T15:37:04+08:00" level=info msg="running go-librespot 0.0.0" Jan 26 15:37:04 ft1905-6ee5 go-librespot[2869]: time="2026-01-26T15:37:04+08:00" level=debug msg="app state loaded" Jan 26 15:37:04 ft1905-6ee5 go-librespot[2869]: time="2026-01-26T15:37:04+08:00" level=debug msg="stored credentials not found" Jan 26 15:37:04 ft1905-6ee5 go-librespot[2869]: time="2026-01-26T15:37:04+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "curtime": 62, Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: Send failed,reason:A payload contained invalid data Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: getState Jan 26 15:37:05 ft1905-6ee5 socketio[2363]: Jan 26 15:37:05 ft1905-6ee5 go-librespot[2869]: time="2026-01-26T15:37:05+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 26 15:37:05 ft1905-6ee5 go-librespot[2869]: time="2026-01-26T15:37:05+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 26 15:37:05 ft1905-6ee5 go-librespot[2869]: time="2026-01-26T15:37:05+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 26 15:37:05 ft1905-6ee5 go-librespot[2869]: time="2026-01-26T15:37:05+08:00" level=info msg="zeroconf server listening on port 42271" Jan 26 15:37:05 ft1905-6ee5 go-librespot[2869]: 2026/01/26 15:37:05 [zeroconf] no suitable IPv6 interface: listen udp6 [ff02::]:5353: socket: address family not supported by protocol Jan 26 15:37:05 ft1905-6ee5 volumio[2361]: Playing WAVE '/volumio/app/wifi_connected.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jan 26 15:37:05 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::Close All Modals sent Jan 26 15:37:05 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:37:06 ft1905-6ee5 volumio[2857]: Starting albumart workers Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: [websocket] [pushSrctotal] {"cur":2,"curId":"cd:1","source":[{"code":"stream","id":"stream:1","name":"Media Player","type":0},{"code":"bt","id":"bt:1","name":"Bluetooth","type":4},{"code":"cd","id":"cd:1","name":"CD","type":1},{"code":"aux","id":"aux:1","name":"AUX","type":6},{"code":"optic","id":"optic:1","name":"DIGITAL OPT1","type":5},{"code":"optic","id":"optic:2","name":"DIGITAL OPT2","type":5},{"code":"arc","id":"arc:1","name":"HDMI ARC","type":5}],"total":7} Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 36 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 14 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 9 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 14 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 17 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 34 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: setDeviceName Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: {"name":"FT1905-6ee5"} Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: getDeviceName Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: getQueue Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: getDeviceName Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: getSystemVersion Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: setWizardAction Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: {"action":"close"} Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 36 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 14 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: setDeviceName Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: {"name":"FT1905-6ee5"} Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: getDeviceName Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "curtime": 64, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: Send failed,reason:A payload contained invalid data Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: sendCommand: Command { name: 'playlistinfo', args: [] } Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: sendCommand: Command { name: 'search album ""', args: [] } Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 635 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: pushSrctotal Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "total": 7, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "cur": 2, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "curId": "cd:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "source": [{ Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "stream:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "Media Player", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "stream", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 0 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "bt:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "Bluetooth", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "bt", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 4 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "cd:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "CD", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "cd", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 1 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "aux:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "AUX", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "aux", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 6 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "optic:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "DIGITAL OPT1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "optic", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "optic:2", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "DIGITAL OPT2", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "optic", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "arc:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "HDMI ARC", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "arc", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }] Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: command_len 635 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: pushSrctotal Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "total": 7, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "cur": 2, Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "curId": "cd:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "source": [{ Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "stream:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "Media Player", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "stream", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 0 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "bt:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "Bluetooth", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "bt", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 4 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "cd:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "CD", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "cd", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 1 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "aux:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "AUX", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "aux", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 6 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "optic:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "DIGITAL OPT1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "optic", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "optic:2", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "DIGITAL OPT2", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "optic", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }, { Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "id": "arc:1", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "name": "HDMI ARC", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "code": "arc", Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: "type": 5 Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: }] Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: [mpd] MPD running with PID2690 Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: ,establishing connection Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: [i2s_dacs] Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: sudo: /usr/sbin/i2cdetect: command not found Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: [rest_api] API:printToastMessage Jan 26 15:37:06 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: [spop] No need to fix Spotify hosts Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:06 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jan 26 15:37:07 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::volumioGetQueue Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreStateMachine::getQueue Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CorePlayQueue::getQueue Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: [websocket] Received Get System Version Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: [wizard] Wizard terminated Successfully Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: [rest_api] API:emitFavourites Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: [websocket] [pushSrctotal] {"cur":2,"curId":"cd:1","source":[{"code":"stream","id":"stream:1","name":"Media Player","type":0},{"code":"bt","id":"bt:1","name":"Bluetooth","type":4},{"code":"cd","id":"cd:1","name":"CD","type":1},{"code":"aux","id":"aux:1","name":"AUX","type":6},{"code":"optic","id":"optic:1","name":"DIGITAL OPT1","type":5},{"code":"optic","id":"optic:2","name":"DIGITAL OPT2","type":5},{"code":"arc","id":"arc:1","name":"HDMI ARC","type":5}],"total":7} Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: [websocket] [pushSrctotal] {"cur":2,"curId":"cd:1","source":[{"code":"stream","id":"stream:1","name":"Media Player","type":0},{"code":"bt","id":"bt:1","name":"Bluetooth","type":4},{"code":"cd","id":"cd:1","name":"CD","type":1},{"code":"aux","id":"aux:1","name":"AUX","type":6},{"code":"optic","id":"optic:1","name":"DIGITAL OPT1","type":5},{"code":"optic","id":"optic:2","name":"DIGITAL OPT2","type":5},{"code":"arc","id":"arc:1","name":"HDMI ARC","type":5}],"total":7} Jan 26 15:37:07 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:07 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:07 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:07 ft1905-6ee5 socketio[2363]: socket send Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: [mpd] updateQueue error: null Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: { Error: ENOENT: no such file or directory, stat '/mnt/USB/AA35-4D26' Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at Object.statSync (fs.js:855:3) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at eval (webpack://Volumio2/./app/plugins/music_service/mpd/index.js?:1586:23) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at MpdClient.handleMessage (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:77:3) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at MpdClient.receive (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:68:12) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at Socket.eval (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:43:12) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at Socket.emit (events.js:198:13) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at addChunk (_stream_readable.js:288:12) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at readableAddChunk (_stream_readable.js:265:13) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at Socket.Readable.push (_stream_readable.js:224:10) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: at Pipe.onStreamRead [as onread] (internal/stream_base_commons.js:94:17) Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: errno: -2, Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: syscall: 'stat', Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: code: 'ENOENT', Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: path: '/mnt/USB/AA35-4D26' } Jan 26 15:37:07 ft1905-6ee5 volumio[2361]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: command_len 299 Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: pushSourceInfo Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: { Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "source": 2, Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "sourceCode": "cd", Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "sourceId": "cd:1", Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: [50B blob data] Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "artist": "! ! ! ! @ @ @ @ (Ausrufezeichen / at-Zeichen)", Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "album": "", Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "curtime": 65, Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "totaltime": 230, Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "curtrack": 1, Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "playstate": 0, Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "playmode": 0, Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: "traystatus": "Loading..." Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: } Jan 26 15:37:08 ft1905-6ee5 socketio[2363]: Send failed,reason:A payload contained invalid data Jan 26 15:37:08 ft1905-6ee5 sudo[2922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-26 15:36 Jan 26 15:37:08 ft1905-6ee5 sudo[2922]: 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.7.40 VOLUMIO_BUILD_DATE=2026-01-21 07:29:33 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system