-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Mon 2026-01-26 13:38:32 CST. -- Jan 26 13:37:52 so1905-5f47 systemd-timesyncd[2154]: Initial synchronization to time server 185.125.190.56:123 (ntp.ubuntu.com). Jan 26 13:37:52 so1905-5f47 systemd[1]: Starting Login Service... Jan 26 13:37:52 so1905-5f47 systemd[1]: Started mount disks automatically with standby. Jan 26 13:37:52 so1905-5f47 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 26 13:37:52 so1905-5f47 systemd[1]: Started Volumio First Start Script. Jan 26 13:37:52 so1905-5f47 serverota.sh[2186]: Upgrade file or version file missing. Exiting. Jan 26 13:37:52 so1905-5f47 systemd[1]: Started Volumio Log Rotation Service. Jan 26 13:37:52 so1905-5f47 volumio_first_start.sh[2194]: no find file Jan 26 13:37:52 so1905-5f47 systemd[1]: Started Volumio SSH enabler. Jan 26 13:37:52 so1905-5f47 systemd[1]: Started Network Name Resolution. Jan 26 13:37:52 so1905-5f47 systemd[1]: applysysctl.service: Main process exited, code=exited, status=255/EXCEPTION Jan 26 13:37:52 so1905-5f47 systemd[1]: applysysctl.service: Failed with result 'exit-code'. Jan 26 13:37:52 so1905-5f47 systemd[1]: Failed to start Apply sysctl settings. Jan 26 13:37:52 so1905-5f47 systemd[1]: e2scrub_reap.service: Succeeded. Jan 26 13:37:52 so1905-5f47 systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots. Jan 26 13:37:52 so1905-5f47 systemd[1]: iptables.service: Succeeded. Jan 26 13:37:52 so1905-5f47 systemd[1]: Finished Server OTA Update Service. Jan 26 13:37:52 so1905-5f47 systemd-logind[2189]: New seat seat0. Jan 26 13:37:52 so1905-5f47 dbus-daemon[2170]: [system] Activating systemd to hand-off: service name='org.freedesktop.UDisks' unit='udisks.service' requested by ':1.4' (uid=1000 pid=2190 comm="/usr/bin/udisks-glue --foreground ") Jan 26 13:37:52 so1905-5f47 systemd[1]: volumiossh.service: Succeeded. Jan 26 13:37:52 so1905-5f47 systemd[1]: Finished Save/Restore Sound Card State. Jan 26 13:37:52 so1905-5f47 systemd[1]: Reached target Network. Jan 26 13:37:52 so1905-5f47 dbus-daemon[2170]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 26 13:37:52 so1905-5f47 systemd[1]: Reached target Network is Online. Jan 26 13:37:52 so1905-5f47 systemd[1]: Reached target Sound Card. Jan 26 13:37:52 so1905-5f47 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jan 26 13:37:53 so1905-5f47 avahi-daemon[2169]: Server startup complete. Host name is so1905-5f47.local. Local service cookie is 3620513293. Jan 26 13:37:53 so1905-5f47 systemd[1]: Starting Music Player Daemon... Jan 26 13:37:53 so1905-5f47 systemd[1]: Starting Samba NMB Daemon... Jan 26 13:37:53 so1905-5f47 systemd[1]: Started NQPTP -- Not Quite PTP. Jan 26 13:37:53 so1905-5f47 dnsmasq[2254]: dnsmasq: syntax check OK. Jan 26 13:37:53 so1905-5f47 systemd[1]: Starting /etc/rc.local Compatibility... Jan 26 13:37:53 so1905-5f47 systemd[1]: Starting OpenBSD Secure Shell server... Jan 26 13:37:53 so1905-5f47 systemd[1]: Starting Permit User Sessions... Jan 26 13:37:53 so1905-5f47 volumio[2192]: Could not open config: /tmp/upmpdcli.conf Jan 26 13:37:53 so1905-5f47 systemd[1]: Started Volumio Backend Module. Jan 26 13:37:53 so1905-5f47 sshd[2284]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Jan 26 13:37:53 so1905-5f47 sshd[2284]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Jan 26 13:37:53 so1905-5f47 sshd[2284]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Jan 26 13:37:53 so1905-5f47 sshd[2284]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Jan 26 13:37:53 so1905-5f47 sshd[2284]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Jan 26 13:37:53 so1905-5f47 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 13:37:53 so1905-5f47 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 13:37:53 so1905-5f47 systemd[1]: Started /etc/rc.local Compatibility. Jan 26 13:37:53 so1905-5f47 systemd[1]: Finished Permit User Sessions. Jan 26 13:37:53 so1905-5f47 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 26 13:37:53 so1905-5f47 systemd[1]: Started Login Service. Jan 26 13:37:53 so1905-5f47 display[2188]: 初始化FreeType... Jan 26 13:37:53 so1905-5f47 display[2188]: FreeType初始化失败 Jan 26 13:37:53 so1905-5f47 display[2188]: [HeartbeatMonitor] 初始化完成 Jan 26 13:37:53 so1905-5f47 avahi-daemon[2169]: Service "SO1905-5f47" (/services/volumio.service) successfully established. Jan 26 13:37:53 so1905-5f47 sshd[2320]: /etc/ssh/sshd_config line 6: Deprecated option UsePrivilegeSeparation Jan 26 13:37:53 so1905-5f47 sshd[2320]: /etc/ssh/sshd_config line 7: Deprecated option KeyRegenerationInterval Jan 26 13:37:53 so1905-5f47 sshd[2320]: /etc/ssh/sshd_config line 8: Deprecated option ServerKeyBits Jan 26 13:37:53 so1905-5f47 sshd[2320]: /etc/ssh/sshd_config line 14: Deprecated option RSAAuthentication Jan 26 13:37:53 so1905-5f47 sshd[2320]: /etc/ssh/sshd_config line 17: Deprecated option RhostsRSAAuthentication Jan 26 13:37:53 so1905-5f47 systemd[1]: Started Getty on tty1. Jan 26 13:37:54 so1905-5f47 sshd[2320]: Server listening on 0.0.0.0 port 22. Jan 26 13:37:54 so1905-5f47 systemd[1]: Started Serial Getty on ttyAS0. Jan 26 13:37:54 so1905-5f47 dnsmasq[2312]: dnsmasq: unknown interface wlan0 Jan 26 13:37:54 so1905-5f47 dnsmasq[2312]: unknown interface wlan0 Jan 26 13:37:54 so1905-5f47 dnsmasq[2312]: FAILED to start up Jan 26 13:37:54 so1905-5f47 systemd[1]: Reached target Login Prompts. Jan 26 13:37:54 so1905-5f47 systemd[1]: Starting Disk Manager (legacy version)... Jan 26 13:37:54 so1905-5f47 systemd[1]: Started OpenBSD Secure Shell server. Jan 26 13:37:54 so1905-5f47 systemd[1]: dnsmasq.service: Control process exited, code=exited, status=2/INVALIDARGUMENT Jan 26 13:37:54 so1905-5f47 systemd[1]: dnsmasq.service: Failed with result 'exit-code'. Jan 26 13:37:54 so1905-5f47 systemd[1]: Failed to start dnsmasq - A lightweight DHCP and caching DNS server. Jan 26 13:37:54 so1905-5f47 systemd[1]: Reached target Host and Network Name Lookups. Jan 26 13:37:54 so1905-5f47 dbus-daemon[2170]: [system] Successfully activated service 'org.freedesktop.UDisks' Jan 26 13:37:54 so1905-5f47 systemd[1]: Started Disk Manager (legacy version). Jan 26 13:37:54 so1905-5f47 dbus-daemon[2170]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.8' (uid=0 pid=2325 comm="/usr/lib/udisks/udisks-daemon --no-debug ") Jan 26 13:37:54 so1905-5f47 systemd[1]: Starting Authorization Manager... Jan 26 13:37:54 so1905-5f47 display[2188]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Jan 26 13:37:54 so1905-5f47 display[2188]: 文件大小: 11364916 字节 Jan 26 13:37:54 so1905-5f47 display[2188]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 50px) Jan 26 13:37:54 so1905-5f47 display[2188]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Jan 26 13:37:54 so1905-5f47 display[2188]: 文件大小: 11364916 字节 Jan 26 13:37:54 so1905-5f47 display[2188]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 36px) Jan 26 13:37:54 so1905-5f47 polkitd[2332]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 26 13:37:54 so1905-5f47 dbus-daemon[2170]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 26 13:37:54 so1905-5f47 systemd[1]: Started Authorization Manager. Jan 26 13:37:54 so1905-5f47 display[2188]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf Jan 26 13:37:54 so1905-5f47 display[2188]: 文件大小: 11364916 字节 Jan 26 13:37:54 so1905-5f47 display[2188]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-65-Medium.ttf (大小: 40px) Jan 26 13:37:55 so1905-5f47 nmbd[2268]: [2026/01/26 13:37:55.267260, 0] ../../source3/nmbd/nmbd.c:901(main) Jan 26 13:37:55 so1905-5f47 nmbd[2268]: nmbd version 4.15.13-Ubuntu started. Jan 26 13:37:55 so1905-5f47 nmbd[2268]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Jan 26 13:37:55 so1905-5f47 nmbd[2268]: [2026/01/26 13:37:55.312490, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 26 13:37:55 so1905-5f47 nmbd[2268]: started asyncdns process 2337 Jan 26 13:37:55 so1905-5f47 systemd[1]: systemd-rfkill.service: Succeeded. Jan 26 13:37:55 so1905-5f47 udisks-glue[2190]: Device file /dev/mmcblk0p6 inserted Jan 26 13:37:55 so1905-5f47 systemd[1]: Started Samba NMB Daemon. Jan 26 13:37:55 so1905-5f47 nmbd[2268]: [2026/01/26 13:37:55.473603, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 26 13:37:55 so1905-5f47 udisks-glue[2190]: Device file /dev/mmcblk0 inserted Jan 26 13:37:55 so1905-5f47 nmbd[2268]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.166 for name WORKGROUP<1d>. Jan 26 13:37:55 so1905-5f47 display[2188]: setImageSrc: this=0x2abb278, str=/volumio/display/assets/source-icon/streaming.png Jan 26 13:37:55 so1905-5f47 nmbd[2268]: This response was from IP 192.168.23.83, reporting an IP address of 192.168.23.83. Jan 26 13:37:55 so1905-5f47 systemd[1]: Starting Samba SMB Daemon... Jan 26 13:37:55 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Jan 26 13:37:55 so1905-5f47 display[2188]: setImageSrc: this=0x2abf810, str=/volumio/display/assets/source-icon/bt.png Jan 26 13:37:55 so1905-5f47 udisks-glue[2190]: Device file /dev/mmcblk0p4 inserted Jan 26 13:37:55 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Jan 26 13:37:55 so1905-5f47 udisks-glue[2190]: Device file /dev/mmcblk0p3 inserted Jan 26 13:37:55 so1905-5f47 udisks-glue[2190]: Device file /dev/mmcblk0p2 inserted Jan 26 13:37:55 so1905-5f47 display[2188]: setImageSrc: this=0x2abf940, str=/volumio/display/assets/source-icon/aux.png Jan 26 13:37:55 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/aux.png, size=10326, buffer[0]=89 Jan 26 13:37:55 so1905-5f47 udisks-glue[2190]: Device file /dev/mmcblk0p8 inserted Jan 26 13:37:55 so1905-5f47 udisks-glue[2190]: Device file /dev/mmcblk0p1 inserted Jan 26 13:37:55 so1905-5f47 display[2188]: setImageSrc: this=0x2abfdf0, str=/volumio/display/assets/source-icon/opt.png Jan 26 13:37:55 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Jan 26 13:37:55 so1905-5f47 udisks-glue[2190]: Device file /dev/mmcblk0p7 inserted Jan 26 13:37:55 so1905-5f47 display[2188]: setImageSrc: this=0x2ac0098, str=/volumio/display/assets/source-icon/opt.png Jan 26 13:37:55 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/opt.png, size=8229, buffer[0]=89 Jan 26 13:37:56 so1905-5f47 display[2188]: setImageSrc: this=0x2ac03b0, str=/volumio/display/assets/source-icon/hdmi-arc.png Jan 26 13:37:56 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/hdmi-arc.png, size=7908, buffer[0]=89 Jan 26 13:37:56 so1905-5f47 display[2188]: setImageSrc: this=0x2ac0c80, str=/volumio/display/assets/source-icon/usb.png Jan 26 13:37:56 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/usb.png, size=7652, buffer[0]=89 Jan 26 13:37:56 so1905-5f47 display[2188]: setImageSrc: this=0x2abbd98, str=/volumio/display/assets/source-icon/music-library.png Jan 26 13:37:56 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/music-library.png, size=22848, buffer[0]=89 Jan 26 13:37:56 so1905-5f47 systemd[1]: Started Dispatcher daemon for systemd-networkd. Jan 26 13:37:57 so1905-5f47 set-cpufreq[2185]: Setting ondemand scheduler for all CPUs Jan 26 13:37:57 so1905-5f47 systemd[1]: ondemand.service: Succeeded. Jan 26 13:37:57 so1905-5f47 node[2207]: Cleaning previous... Jan 26 13:37:57 so1905-5f47 smbd[2340]: [2026/01/26 13:37:57.985287, 0] ../../source3/smbd/server.c:1734(main) Jan 26 13:37:57 so1905-5f47 smbd[2340]: smbd version 4.15.13-Ubuntu started. Jan 26 13:37:57 so1905-5f47 smbd[2340]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Jan 26 13:37:58 so1905-5f47 systemd[1]: Started Samba SMB Daemon. Jan 26 13:37:58 so1905-5f47 sudo[2355]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/ip addr flush dev wlan0 Jan 26 13:37:58 so1905-5f47 sudo[2355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:37:58 so1905-5f47 sudo[2355]: pam_unix(sudo:session): session closed for user root Jan 26 13:37:58 so1905-5f47 sudo[2364]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jan 26 13:37:58 so1905-5f47 sudo[2364]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:37:58 so1905-5f47 systemd-networkd[2024]: wlan0: Link DOWN Jan 26 13:37:58 so1905-5f47 sudo[2364]: pam_unix(sudo:session): session closed for user root Jan 26 13:37:59 so1905-5f47 node[2207]: Stopped aP Jan 26 13:37:59 so1905-5f47 node[2207]: Start wireless flow Jan 26 13:37:59 so1905-5f47 node[2207]: Stopped hotspot (if there).. Jan 26 13:37:59 so1905-5f47 sudo[2385]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/ip addr flush dev wlan0 Jan 26 13:37:59 so1905-5f47 sudo[2385]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:37:59 so1905-5f47 sudo[2385]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:00 so1905-5f47 smbd[2340]: [2026/01/26 13:38:00.146493, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Jan 26 13:38:00 so1905-5f47 smbd[2340]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Jan 26 13:38:00 so1905-5f47 smbd[2340]: [2026/01/26 13:38:00.171047, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Jan 26 13:38:00 so1905-5f47 smbd[2340]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Jan 26 13:38:00 so1905-5f47 sudo[2397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jan 26 13:38:00 so1905-5f47 mpd[2264]: Jan 26 13:38 : exception: bind to '/run/mpd/socket' failed (continuing anyway, because binding to '/run/mpd/socket' succeeded): Failed to bind socket: Address already in use Jan 26 13:38:00 so1905-5f47 mpd[2264]: Jan 26 13:38 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 13:38:00 so1905-5f47 sudo[2397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:00 so1905-5f47 systemd[1]: Started Music Player Daemon. Jan 26 13:38:00 so1905-5f47 systemd[1]: Reached target Multi-User System. Jan 26 13:38:00 so1905-5f47 systemd[1]: Reached target Graphical Interface. Jan 26 13:38:00 so1905-5f47 sudo[2397]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:00 so1905-5f47 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 26 13:38:00 so1905-5f47 node[2207]: DHCP IP Jan 26 13:38:00 so1905-5f47 node[2207]: Start ap Jan 26 13:38:00 so1905-5f47 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 26 13:38:00 so1905-5f47 systemd[1]: Finished Update UTMP about System Runlevel Changes. Jan 26 13:38:00 so1905-5f47 wpa_supplicant[2413]: Successfully initialized wpa_supplicant Jan 26 13:38:00 so1905-5f47 systemd-networkd[2024]: wlan0: Link UP Jan 26 13:38:00 so1905-5f47 sudo[2415]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/udhcpc -i wlan0 -v Jan 26 13:38:00 so1905-5f47 sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:00 so1905-5f47 udhcpc[2427]: wlan0: deconfigured Jan 26 13:38:01 so1905-5f47 node[2207]: trying... Jan 26 13:38:01 so1905-5f47 sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:01 so1905-5f47 sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:01 so1905-5f47 sudo[2429]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:01 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:01 so1905-5f47 node[2207]: ---- Jan 26 13:38:01 so1905-5f47 kernel: [SNDCODEC][sunxi_check_hs_detect_status][222]:plugout --> switch:0 Jan 26 13:38:02 so1905-5f47 sudo[2439]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:02 so1905-5f47 sudo[2439]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:02 so1905-5f47 sudo[2439]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:02 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:02 so1905-5f47 node[2207]: trying... Jan 26 13:38:02 so1905-5f47 sudo[2449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:02 so1905-5f47 sudo[2449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:02 so1905-5f47 sudo[2449]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:02 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:02 so1905-5f47 node[2207]: ---- Jan 26 13:38:02 so1905-5f47 sudo[2460]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:02 so1905-5f47 sudo[2460]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:02 so1905-5f47 sudo[2460]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:02 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:03 so1905-5f47 node[2187]: Remote version data: { serverVersion: '1.5.7.40', Jan 26 13:38:03 so1905-5f47 node[2187]: serverUrl: Jan 26 13:38:03 so1905-5f47 node[2187]: 'https://ota.synaudio.org/server/1905/volumio_1.5.7.40.tar.gz', Jan 26 13:38:03 so1905-5f47 node[2187]: md5: '76a08f0996a75f1578c3e5472fc4c298' } Jan 26 13:38:03 so1905-5f47 node[2187]: No update required. Jan 26 13:38:03 so1905-5f47 node[2207]: trying... Jan 26 13:38:03 so1905-5f47 sudo[2478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:03 so1905-5f47 sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:03 so1905-5f47 volumio[2304]: info: ------------------------------------------- Jan 26 13:38:03 so1905-5f47 volumio[2304]: info: ----- Synaudio ---- Jan 26 13:38:03 so1905-5f47 volumio[2304]: info: ------------------------------------------- Jan 26 13:38:03 so1905-5f47 volumio[2304]: info: ----- System startup ---- Jan 26 13:38:03 so1905-5f47 volumio[2304]: info: ------------------------------------------- Jan 26 13:38:03 so1905-5f47 sudo[2478]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:03 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:03 so1905-5f47 node[2207]: ---- Jan 26 13:38:03 so1905-5f47 sudo[2488]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:04 so1905-5f47 sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:04 so1905-5f47 sudo[2488]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:04 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:04 so1905-5f47 node[2207]: trying... Jan 26 13:38:04 so1905-5f47 sudo[2498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:04 so1905-5f47 sudo[2498]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:04 so1905-5f47 sudo[2498]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:04 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:04 so1905-5f47 node[2207]: ---- Jan 26 13:38:04 so1905-5f47 sudo[2508]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:04 so1905-5f47 sudo[2508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:05 so1905-5f47 sudo[2508]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:05 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Found new core plugin music_service/cdplayer. Adding it Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Configured plugin music_service/cdplayer cannot be loaded. Removing from configuration Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Plugin folders cleanup Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Scanning into folder /volumio/app/plugins/ Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Scanning category audio_interface Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Scanning category miscellanea Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Scanning category music_service Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Scanning category plugins.json Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Scanning category system_controller Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Scanning category user_interface Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Scanning into folder /data/plugins/ Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Plugin folders cleanup completed Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: ------------------------------------------- Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: ----- Core plugins startup ---- Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: ------------------------------------------- Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Loading plugins from folder /volumio/app/plugins/ Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Adding plugin upnp to MyMusic Plugins Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Loading plugins from folder /data/plugins/ Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Loading plugin "system"... Jan 26 13:38:05 so1905-5f47 node[2207]: trying... Jan 26 13:38:05 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/system_controller/system/index.js Jan 26 13:38:05 so1905-5f47 sudo[2520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:05 so1905-5f47 volumio[2304]: info: Loading plugin "appearance"... Jan 26 13:38:05 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/miscellanea/appearance/index.js Jan 26 13:38:05 so1905-5f47 sudo[2520]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:05 so1905-5f47 sudo[2520]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:05 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:05 so1905-5f47 node[2207]: ---- Jan 26 13:38:05 so1905-5f47 sudo[2530]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:05 so1905-5f47 sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:06 so1905-5f47 sudo[2530]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:06 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:06 so1905-5f47 display[2188]: setImageSrc: this=0x2be2ed0, str=/volumio/display/assets/source-icon/bt.png Jan 26 13:38:06 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Jan 26 13:38:06 so1905-5f47 display[2188]: setImageSrc: this=0x2ae9800, str=/volumio/display/assets/albumart.jpg Jan 26 13:38:06 so1905-5f47 volumio[2304]: info: Loading plugin "network"... Jan 26 13:38:06 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/system_controller/network/index.js Jan 26 13:38:06 so1905-5f47 display[2188]: read file /volumio/display/assets/albumart.jpg, size=77672, buffer[0]=ff Jan 26 13:38:06 so1905-5f47 display[2188]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Jan 26 13:38:06 so1905-5f47 display[2188]: Attempting to process with stb_image... Jan 26 13:38:06 so1905-5f47 node[2207]: trying... Jan 26 13:38:06 so1905-5f47 volumio[2304]: [network] Refreshing Cached IP Addresses Jan 26 13:38:06 so1905-5f47 sudo[2540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:06 so1905-5f47 sudo[2540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:06 so1905-5f47 volumio[2304]: ControllerNetwork autoStartNetworkConfig true Jan 26 13:38:06 so1905-5f47 volumio[2304]: info: Loading plugin "services"... Jan 26 13:38:06 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/system_controller/services/index.js Jan 26 13:38:06 so1905-5f47 volumio[2304]: info: Loading plugin "alsa_controller"... Jan 26 13:38:06 so1905-5f47 sudo[2543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 13:38:06 so1905-5f47 sudo[2540]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:06 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Jan 26 13:38:06 so1905-5f47 sudo[2543]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:06 so1905-5f47 display[2188]: stb_image successfully loaded: 855x855, channels=3 Jan 26 13:38:06 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:06 so1905-5f47 node[2207]: ---- Jan 26 13:38:06 so1905-5f47 sudo[2543]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:06 so1905-5f47 display[2188]: Successfully processed with stb_image Jan 26 13:38:06 so1905-5f47 display[2188]: Loading default image: /volumio/display/assets/albumart.jpg Jan 26 13:38:06 so1905-5f47 display[2188]: Loaded default image from file: /volumio/display/assets/albumart.jpg, size=77672 Jan 26 13:38:06 so1905-5f47 display[2188]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Jan 26 13:38:06 so1905-5f47 display[2188]: Attempting to process with stb_image... Jan 26 13:38:06 so1905-5f47 sudo[2548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:06 so1905-5f47 volumio[2304]: info: Applying required configuration parameters for plugin alsa_controller Jan 26 13:38:06 so1905-5f47 sudo[2559]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:06 so1905-5f47 sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:06 so1905-5f47 sudo[2559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:07 so1905-5f47 sudo[2548]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:07 so1905-5f47 sudo[2559]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:07 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:07 so1905-5f47 display[2188]: stb_image successfully loaded: 855x855, channels=3 Jan 26 13:38:07 so1905-5f47 display[2188]: Successfully processed with stb_image Jan 26 13:38:07 so1905-5f47 display[2188]: Default image loaded successfully Jan 26 13:38:07 so1905-5f47 display[2188]: setImageSrc: this=0x2ae86f8, str=/volumio/display/assets/status-icon/wifi-error.png Jan 26 13:38:07 so1905-5f47 display[2188]: read file /volumio/display/assets/status-icon/wifi-error.png, size=1494, buffer[0]=89 Jan 26 13:38:07 so1905-5f47 display[2188]: setImageSrc: this=0x2ae88f0, str=/volumio/display/assets/status-icon/eth-error.png Jan 26 13:38:07 so1905-5f47 display[2188]: read file /volumio/display/assets/status-icon/eth-error.png, size=936, buffer[0]=89 Jan 26 13:38:07 so1905-5f47 volumio[2304]: [alsa_controller] Creating shared var alsa.outputdevice='2' Jan 26 13:38:07 so1905-5f47 volumio[2304]: info: Loading plugin "wizard"... Jan 26 13:38:07 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Jan 26 13:38:07 so1905-5f47 display[2188]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Jan 26 13:38:07 so1905-5f47 display[2188]: 文件大小: 6344 字节 Jan 26 13:38:07 so1905-5f47 volumio[2304]: info: Loading plugin "volumio_command_line_client"... Jan 26 13:38:07 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Jan 26 13:38:07 so1905-5f47 display[2188]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 40px) Jan 26 13:38:07 so1905-5f47 display[2188]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf Jan 26 13:38:07 so1905-5f47 display[2188]: 文件大小: 11495876 字节 Jan 26 13:38:07 so1905-5f47 volumio[2304]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Jan 26 13:38:07 so1905-5f47 volumio[2304]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Jan 26 13:38:07 so1905-5f47 volumio[2304]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Jan 26 13:38:07 so1905-5f47 volumio[2304]: [volumio_command_line_client] /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Jan 26 13:38:07 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:07 so1905-5f47 display[2188]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf (大小: 40px) Jan 26 13:38:07 so1905-5f47 display[2188]: setInnerAlign: image_desc is null Jan 26 13:38:07 so1905-5f47 node[2207]: trying... Jan 26 13:38:07 so1905-5f47 volumio[2304]: info: Loading plugin "upnp"... Jan 26 13:38:07 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Jan 26 13:38:07 so1905-5f47 volumio[2304]: info: [1769405887556] Starting Upmpd Daemon Jan 26 13:38:07 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 13:38:07 so1905-5f47 display[2188]: setImageSrc: this=0x2be4a60, str=/volumio/display/assets/source-icon/streaming.png Jan 26 13:38:07 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Jan 26 13:38:07 so1905-5f47 volumio[2304]: info: Loading plugin "my_music"... Jan 26 13:38:07 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Jan 26 13:38:07 so1905-5f47 volumio[2304]: info: Loading plugin "mpd"... Jan 26 13:38:07 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Jan 26 13:38:07 so1905-5f47 sudo[2588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:07 so1905-5f47 display[2188]: setImageSrc: this=0x2beab00, str=/volumio/display/assets/source-icon/streaming.png Jan 26 13:38:07 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Jan 26 13:38:07 so1905-5f47 sudo[2588]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:07 so1905-5f47 display[2188]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Jan 26 13:38:07 so1905-5f47 display[2188]: 文件大小: 6344 字节 Jan 26 13:38:07 so1905-5f47 display[2188]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 100px) Jan 26 13:38:07 so1905-5f47 sudo[2588]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:07 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:07 so1905-5f47 node[2207]: ---- Jan 26 13:38:07 so1905-5f47 display[2188]: setImageSrc: this=0x2be5760, str=/volumio/display/assets/source-icon/streaming.png Jan 26 13:38:07 so1905-5f47 display[2188]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Jan 26 13:38:07 so1905-5f47 sudo[2598]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:07 so1905-5f47 sudo[2598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:07 so1905-5f47 sudo[2598]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:08 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:08 so1905-5f47 volumio[2304]: [mpd] MPD: starting udev watcher for USB partitions Jan 26 13:38:08 so1905-5f47 volumio[2304]: [mpd] Creating MPD Configuration file Jan 26 13:38:08 so1905-5f47 sudo[2608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 13:38:08 so1905-5f47 node[2207]: trying... Jan 26 13:38:08 so1905-5f47 volumio[2304]: info: Loading plugin "upnp_browser"... Jan 26 13:38:08 so1905-5f47 sudo[2608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:08 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Jan 26 13:38:08 so1905-5f47 sudo[2616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 13:38:08 so1905-5f47 sudo[2616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:08 so1905-5f47 sudo[2608]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:08 so1905-5f47 sudo[2614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:08 so1905-5f47 systemd[1]: Stopping Music Player Daemon... Jan 26 13:38:08 so1905-5f47 sudo[2614]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:08 so1905-5f47 sudo[2614]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:08 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:08 so1905-5f47 node[2207]: ---- Jan 26 13:38:08 so1905-5f47 sudo[2635]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:08 so1905-5f47 systemd[1]: mpd.service: Succeeded. Jan 26 13:38:08 so1905-5f47 systemd[1]: Stopped Music Player Daemon. Jan 26 13:38:08 so1905-5f47 systemd[1]: Starting Music Player Daemon... Jan 26 13:38:08 so1905-5f47 sudo[2635]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:08 so1905-5f47 sudo[2635]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:09 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:09 so1905-5f47 node[2207]: trying... Jan 26 13:38:09 so1905-5f47 sudo[2646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:09 so1905-5f47 sudo[2646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:09 so1905-5f47 sudo[2646]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:09 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:09 so1905-5f47 node[2207]: ---- Jan 26 13:38:09 so1905-5f47 sudo[2656]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:10 so1905-5f47 sudo[2656]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:10 so1905-5f47 volumio[2304]: info: Loading plugin "networkfs"... Jan 26 13:38:10 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Jan 26 13:38:10 so1905-5f47 volumio[2304]: [networkfs] Starting IP address watcher Jan 26 13:38:10 so1905-5f47 sudo[2656]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:10 so1905-5f47 volumio[2304]: [networkfs] Started network interface ip monitor watcher Jan 26 13:38:10 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 13:38:10 so1905-5f47 volumio[2304]: info: Loading plugin "alarm-clock"... Jan 26 13:38:10 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Jan 26 13:38:10 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:10 so1905-5f47 udhcpc[2667]: wlan0: configuration failed: leasefail: Jan 26 13:38:10 so1905-5f47 node[2207]: trying... Jan 26 13:38:10 so1905-5f47 sudo[2669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:10 so1905-5f47 sudo[2669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:10 so1905-5f47 sudo[2669]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:10 so1905-5f47 volumio[2304]: info: Loading plugin "airplay_emulation"... Jan 26 13:38:10 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Jan 26 13:38:10 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:10 so1905-5f47 node[2207]: ---- Jan 26 13:38:11 so1905-5f47 sudo[2679]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:11 so1905-5f47 volumio[2304]: 执行命令出错: 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 13:38:11 so1905-5f47 volumio[2304]: [airplay_emulation] Starting Shairport Sync Jan 26 13:38:11 so1905-5f47 sudo[2679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:11 so1905-5f47 volumio[2304]: [airplay_emulation] [AirPlay] Started coverart directory watcher Jan 26 13:38:11 so1905-5f47 volumio[2304]: info: Loading plugin "last_100"... Jan 26 13:38:11 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Jan 26 13:38:11 so1905-5f47 sudo[2679]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:11 so1905-5f47 volumio[2304]: info: Loading plugin "spop"... Jan 26 13:38:11 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/spop/index.js Jan 26 13:38:11 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:11 so1905-5f47 node[2207]: trying... Jan 26 13:38:11 so1905-5f47 mpd[2638]: Jan 26 13:38 : exception: bind to '/run/mpd/socket' failed (continuing anyway, because binding to '/run/mpd/socket' succeeded): Failed to bind socket: Address already in use Jan 26 13:38:11 so1905-5f47 mpd[2638]: Jan 26 13:38 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 13:38:11 so1905-5f47 systemd[1]: Started Music Player Daemon. Jan 26 13:38:11 so1905-5f47 sudo[2616]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:11 so1905-5f47 sudo[2692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:11 so1905-5f47 sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:11 so1905-5f47 sudo[2692]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:11 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:11 so1905-5f47 node[2207]: ---- Jan 26 13:38:11 so1905-5f47 sudo[2708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:11 so1905-5f47 volumio[2304]: info: Loading plugin "webradio"... Jan 26 13:38:11 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Jan 26 13:38:12 so1905-5f47 sudo[2708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:12 so1905-5f47 sudo[2708]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:12 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:12 so1905-5f47 node[2207]: trying... Jan 26 13:38:12 so1905-5f47 sudo[2718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:12 so1905-5f47 sudo[2718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:12 so1905-5f47 sudo[2718]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:12 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:12 so1905-5f47 node[2207]: ---- Jan 26 13:38:12 so1905-5f47 sudo[2728]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:12 so1905-5f47 sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:13 so1905-5f47 sudo[2728]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:13 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:13 so1905-5f47 kernel: usb0-vbus: disabling Jan 26 13:38:13 so1905-5f47 node[2207]: trying... Jan 26 13:38:13 so1905-5f47 sudo[2738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:13 so1905-5f47 sudo[2738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:13 so1905-5f47 sudo[2738]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:13 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:13 so1905-5f47 node[2207]: ---- Jan 26 13:38:13 so1905-5f47 sudo[2748]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:13 so1905-5f47 sudo[2748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:14 so1905-5f47 sudo[2748]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:14 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:14 so1905-5f47 volumio[2304]: info: Loading plugin "yandex_browser"... Jan 26 13:38:14 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/yandex_browser/index.js Jan 26 13:38:14 so1905-5f47 node[2207]: trying... Jan 26 13:38:14 so1905-5f47 sudo[2758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:14 so1905-5f47 sudo[2758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:14 so1905-5f47 sudo[2758]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:14 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:14 so1905-5f47 node[2207]: ---- Jan 26 13:38:14 so1905-5f47 sudo[2768]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:15 so1905-5f47 sudo[2768]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:15 so1905-5f47 sudo[2768]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:15 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:15 so1905-5f47 volumio[2304]: [yandex_browser] Yandex plugin started Jan 26 13:38:15 so1905-5f47 volumio[2304]: error: ATTENTION!!!: Plugin yandex_browser does not return adequate promise from onVolumioStart: please update! Jan 26 13:38:15 so1905-5f47 volumio[2304]: info: Loading plugin "i2s_dacs"... Jan 26 13:38:15 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Jan 26 13:38:15 so1905-5f47 volumio[2304]: [i2s_dacs] I2S DAC not set, start Auto-detection Jan 26 13:38:15 so1905-5f47 volumio[2304]: info: Loading plugin "volumiodiscovery"... Jan 26 13:38:15 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Jan 26 13:38:15 so1905-5f47 volumio[2304]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 13:38:15 so1905-5f47 volumio[2304]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 13:38:15 so1905-5f47 node[2304]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 13:38:15 so1905-5f47 volumio[2304]: *** WARNING *** For more information see Jan 26 13:38:15 so1905-5f47 volumio[2304]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 13:38:15 so1905-5f47 volumio[2304]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 13:38:15 so1905-5f47 volumio[2304]: *** WARNING *** For more information see Jan 26 13:38:15 so1905-5f47 node[2304]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 13:38:15 so1905-5f47 node[2304]: *** WARNING *** For more information see Jan 26 13:38:15 so1905-5f47 node[2304]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 13:38:15 so1905-5f47 node[2304]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 13:38:15 so1905-5f47 node[2304]: *** WARNING *** For more information see Jan 26 13:38:15 so1905-5f47 volumio[2304]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 13:38:15 so1905-5f47 volumio[2304]: [volumiodiscovery] Discovery: Started advertising with name: SO1905-5f47 Jan 26 13:38:15 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 13:38:15 so1905-5f47 volumio[2304]: info: Loading plugin "outputs"... Jan 26 13:38:15 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Jan 26 13:38:15 so1905-5f47 volumio[2304]: info: Loading plugin "albumart"... Jan 26 13:38:15 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Jan 26 13:38:15 so1905-5f47 node[2207]: trying... Jan 26 13:38:15 so1905-5f47 volumio[2304]: info: Plugin example_plugin is not enabled Jan 26 13:38:15 so1905-5f47 volumio[2304]: info: Loading plugin "inputs"... Jan 26 13:38:15 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Jan 26 13:38:15 so1905-5f47 volumio[2304]: info: Loading plugin "pandora"... Jan 26 13:38:15 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Jan 26 13:38:15 so1905-5f47 sudo[2784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:15 so1905-5f47 sudo[2784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:15 so1905-5f47 sudo[2784]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: Applying required configuration parameters for plugin pandora Jan 26 13:38:16 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:16 so1905-5f47 node[2207]: ---- Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: Loading plugin "podcast"... Jan 26 13:38:16 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Jan 26 13:38:16 so1905-5f47 sudo[2794]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:16 so1905-5f47 sudo[2794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:16 so1905-5f47 sudo[2794]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:16 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:16 so1905-5f47 volumio[2304]: [podcast] ControllerPodcast::constructor Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: Loading plugin "qobuz"... Jan 26 13:38:16 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Jan 26 13:38:16 so1905-5f47 volumio[2304]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: Loading plugin "tidal"... Jan 26 13:38:16 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Jan 26 13:38:16 so1905-5f47 volumio[2304]: [tidal] [1769405896511] ControllerTidalPlugin::constructor Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: [1769405896512] ControllerTidalPlugin::onVolumioStart Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: Plugin volspotconnect2 is not enabled Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: Loading plugin "updater_comm"... Jan 26 13:38:16 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Jan 26 13:38:16 so1905-5f47 node[2207]: trying... Jan 26 13:38:16 so1905-5f47 sudo[2804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:16 so1905-5f47 sudo[2804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:16 so1905-5f47 sudo[2804]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:16 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:16 so1905-5f47 node[2207]: ---- Jan 26 13:38:16 so1905-5f47 sudo[2814]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:16 so1905-5f47 sudo[2814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:16 so1905-5f47 sudo[2814]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:16 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: Plugin mpdemulation is not enabled Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: Loading plugin "rest_api"... Jan 26 13:38:16 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: Loading plugin "websocket"... Jan 26 13:38:16 so1905-5f47 volumio[2304]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Jan 26 13:38:16 so1905-5f47 volumio[2304]: info: ___________ START PLUGINS ___________ Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897008] CoreMusicLibrary::Adding element Media Servers Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:17 so1905-5f47 volumio[2304]: SSDP Client initialized. Jan 26 13:38:17 so1905-5f47 volumio[2304]: Starting initial SSDP search... Jan 26 13:38:17 so1905-5f47 volumio[2304]: Discovering on interface: lo Jan 26 13:38:17 so1905-5f47 volumio[2304]: Discovering on interface: eth0 Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: [airplay_emulation] Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897156] CoreMusicLibrary::Adding element Last_100 Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:17 so1905-5f47 volumio[2304]: [spop] Creating Spotify config file Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897204] CoreMusicLibrary::Adding element Webradio Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 13:38:17 so1905-5f47 volumio[2304]: [yandex_browser] Yandex plugin started Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897228] ControllerYandexPlugin::addToBrowseSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897230] CoreMusicLibrary::Adding element Yandex Music Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:printToastMessage Jan 26 13:38:17 so1905-5f47 volumio[2304]: error: Plugin yandex_browser does not return adequate promise from onStart: please update! Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897247] CoreMusicLibrary::Adding element Pandora Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:17 so1905-5f47 volumio[2304]: [pandora] [1769405897254] ControllerPandora::initializeMQTT Jan 26 13:38:17 so1905-5f47 volumio[2304]: [pandora] [1769405897256] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897274] CoreMusicLibrary::Adding element Podcast Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897282] CoreMusicLibrary::Adding element Qobuz Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897296] ControllerTidalPlugin::onStart Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897305] ControllerTidalPlugin::addToBrowseSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: [1769405897306] CoreMusicLibrary::Adding element Tidal Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:17 so1905-5f47 volumio[2304]: [Tidal] Loading session from /data/configuration/music_service/tidal/tidal-session-oauth.json... Jan 26 13:38:17 so1905-5f47 volumio[2304]: [Tidal] { token_type: { data: 'Bearer' }, Jan 26 13:38:17 so1905-5f47 volumio[2304]: session_id: { data: 'f6e9e73d-b968-42fa-bf37-a37b016f547e' }, Jan 26 13:38:17 so1905-5f47 volumio[2304]: access_token: Jan 26 13:38:17 so1905-5f47 volumio[2304]: { data: Jan 26 13:38:17 so1905-5f47 volumio[2304]: 'eyJraWQiOiJ2OU1GbFhqWSIsImFsZyI6IkVTMjU2In0.eyJ0eXBlIjoibzJfYWNjZXNzIiwidWlkIjoyMDY5ODkyNzQsInNjb3BlIjoid19zdWIgd191c3Igcl91c3IiLCJnVmVyIjowLCJzVmVyIjowLCJjaWQiOjEzMzE5LCJjYyI6IlVTIiwiYXQiOiJJTlRFUk5BTCIsImV4cCI6MTc2OTQwNTg0Nywic2lkIjoiZjZlOWU3M2QtYjk2OC00MmZhLWJmMzctYTM3YjAxNmY1NDdlIiwiaXNzIjoiaHR0cHM6Ly9hdXRoLnRpZGFsLmNvbS92MSJ9.38cUQO8bnRnkrbsG2XOvFW31PTYQY5oTlUFmS0JI7H_W_r0JnrlxIFZJqQmGfHiqRGqCkDFIs9ksPXDf5ORxmA' }, Jan 26 13:38:17 so1905-5f47 volumio[2304]: refresh_token: Jan 26 13:38:17 so1905-5f47 volumio[2304]: { data: Jan 26 13:38:17 so1905-5f47 volumio[2304]: 'eyJraWQiOiJoUzFKYTdVMCIsImFsZyI6IkVTNTEyIn0.eyJ0eXBlIjoibzJfcmVmcmVzaCIsInVpZCI6MjA2OTg5Mjc0LCJzY29wZSI6IndfdXNyIHJfdXNyIHdfc3ViIiwiY2lkIjoxMzMxOSwic1ZlciI6MCwiZ1ZlciI6MCwiaXNzIjoiaHR0cHM6Ly9hdXRoLnRpZGFsLmNvbS92MSJ9.Ab3Dq7P8OKN1g3SpoNPRQnIGKlMJskKlcft_FUFBQQ9E8qWM3RTeHfxzzkfKQweZEHrHxuQDTIpiG0V5A2HmzWTOAJnrsIMAyMZMjiJ0L2cl81fwvAgwlVxm6SSqxk6fBVUHLztm07Y-6yuiDOQ8NFNL8VAIhSFzixk4oPn6lr3x7Uvx' }, Jan 26 13:38:17 so1905-5f47 volumio[2304]: is_pkce: { data: false }, Jan 26 13:38:17 so1905-5f47 volumio[2304]: expiry_time: { data: 1769405848244 } } Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: Loading i18n strings for locale en Jan 26 13:38:17 so1905-5f47 volumio[2304]: Updating browse sources language Jan 26 13:38:17 so1905-5f47 volumio[2304]: Cannot find translation for sourceMedia Servers Jan 26 13:38:17 so1905-5f47 volumio[2304]: Cannot find translation for sourceYandex Music Jan 26 13:38:17 so1905-5f47 volumio[2304]: Cannot find translation for sourcePodcast Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:38:17 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::initPlayerControls Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 26 13:38:17 so1905-5f47 node[2207]: trying... Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:17 so1905-5f47 sudo[2830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: BOOT COMPLETED Jan 26 13:38:17 so1905-5f47 volumio[2304]: [Metrics] CommandRouter: 13s 993.28ms Jan 26 13:38:17 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:38:17 so1905-5f47 sudo[2830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:17 so1905-5f47 sudo[2830]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:17 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:17 so1905-5f47 node[2207]: ---- Jan 26 13:38:17 so1905-5f47 kernel: sunxi-spdif 2036000.spdif: active: 1 Jan 26 13:38:17 so1905-5f47 sudo[2843]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:17 so1905-5f47 sudo[2843]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:17 so1905-5f47 sudo[2843]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:17 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:18 so1905-5f47 node[2207]: trying... Jan 26 13:38:18 so1905-5f47 sudo[2853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:18 so1905-5f47 sudo[2853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:18 so1905-5f47 sudo[2853]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:18 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:18 so1905-5f47 node[2207]: ---- Jan 26 13:38:18 so1905-5f47 sudo[2863]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:18 so1905-5f47 sudo[2863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:18 so1905-5f47 sudo[2863]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:18 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:19 so1905-5f47 node[2207]: trying... Jan 26 13:38:19 so1905-5f47 sudo[2873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:19 so1905-5f47 sudo[2873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:19 so1905-5f47 sudo[2873]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:19 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:19 so1905-5f47 node[2207]: ---- Jan 26 13:38:19 so1905-5f47 sudo[2883]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:19 so1905-5f47 sudo[2883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:19 so1905-5f47 sudo[2883]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:19 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:20 so1905-5f47 node[2207]: trying... Jan 26 13:38:20 so1905-5f47 sudo[2893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:20 so1905-5f47 sudo[2893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:20 so1905-5f47 sudo[2893]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:20 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:20 so1905-5f47 node[2207]: ---- Jan 26 13:38:20 so1905-5f47 sudo[2903]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:20 so1905-5f47 sudo[2903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:20 so1905-5f47 sudo[2903]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:20 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:21 so1905-5f47 node[2207]: trying... Jan 26 13:38:21 so1905-5f47 sudo[2913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:21 so1905-5f47 sudo[2913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:21 so1905-5f47 sudo[2913]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:21 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:21 so1905-5f47 node[2207]: ---- Jan 26 13:38:21 so1905-5f47 sudo[2923]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:21 so1905-5f47 sudo[2923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:21 so1905-5f47 sudo[2923]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:21 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:22 so1905-5f47 node[2207]: trying... Jan 26 13:38:22 so1905-5f47 sudo[2933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:22 so1905-5f47 sudo[2933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:22 so1905-5f47 sudo[2933]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:22 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:22 so1905-5f47 node[2207]: ---- Jan 26 13:38:22 so1905-5f47 sudo[2943]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:22 so1905-5f47 sudo[2943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:22 so1905-5f47 sudo[2943]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:22 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:23 so1905-5f47 volumio[2304]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jan 26 13:38:23 so1905-5f47 volumio[2304]: info: CoreCommandRouter::Close All Modals sent Jan 26 13:38:23 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:23 so1905-5f47 volumio[2304]: info: CoreCommandRouter::Close All Modals sent Jan 26 13:38:23 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:23 so1905-5f47 volumio[2304]: Express server listening on port 3000 Jan 26 13:38:23 so1905-5f47 volumio[2304]: [Metrics] WebUI: 20s 356.77ms Jan 26 13:38:23 so1905-5f47 volumio[2304]: (node:2304) [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 13:38:23 so1905-5f47 volumio[2304]: [pandora] [1769405903184] ControllerPandora::validateAndSetAccountOptions Jan 26 13:38:23 so1905-5f47 volumio[2304]: [pandora] [1769405903186] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Jan 26 13:38:23 so1905-5f47 volumio[2304]: info: CoreStateMachine::resetVolumioState Jan 26 13:38:23 so1905-5f47 volumio[2304]: info: CoreStateMachine::getcurrentVolume Jan 26 13:38:23 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 13:38:23 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:38:23 so1905-5f47 node[2207]: trying... Jan 26 13:38:23 so1905-5f47 volumio[2304]: [i2s_dacs] Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 26 13:38:23 so1905-5f47 sudo[2964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 13:38:23 so1905-5f47 sudo[2966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:23 so1905-5f47 sudo[2964]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:23 so1905-5f47 sudo[2966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:23 so1905-5f47 sudo[2969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:23 so1905-5f47 sudo[2964]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:23 so1905-5f47 sudo[2966]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:23 so1905-5f47 volumio[2304]: [networkfs] IP address changed from "" to "192.168.23.166" Jan 26 13:38:23 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 26 13:38:23 so1905-5f47 sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:24 so1905-5f47 sudo[2969]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:24 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:24 so1905-5f47 node[2207]: ---- Jan 26 13:38:24 so1905-5f47 sudo[2998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 13:38:24 so1905-5f47 sudo[3005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 13:38:24 so1905-5f47 sudo[3013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 13:38:24 so1905-5f47 sudo[3005]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:24 so1905-5f47 sudo[2998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:24 so1905-5f47 sudo[3021]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:24 so1905-5f47 sudo[3013]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:24 so1905-5f47 volumio[2304]: [networkfs] Connecting to wireless device: undefined Jan 26 13:38:24 so1905-5f47 sudo[3005]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:24 so1905-5f47 volumio[2304]: info: this.stateMachine.currentVolume: null, vol: 0 Jan 26 13:38:24 so1905-5f47 sudo[2998]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:24 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:24 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:24 so1905-5f47 volumio[2304]: [airplay_emulation] [AirPlay] Received volume update: {"vol":0,"mute":false,"disableVolumeControl":false} Jan 26 13:38:24 so1905-5f47 volumio[2304]: [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 13:38:24 so1905-5f47 sudo[3021]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:24 so1905-5f47 sudo[3024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 13:38:24 so1905-5f47 sudo[3013]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:24 so1905-5f47 node[2207]: trying... Jan 26 13:38:24 so1905-5f47 sudo[3021]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:24 so1905-5f47 sudo[3024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:24 so1905-5f47 sudo[3037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:24 so1905-5f47 sudo[3037]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:24 so1905-5f47 sudo[3024]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:24 so1905-5f47 volumio[2304]: info: CoreStateMachine::pushState Jan 26 13:38:24 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 13:38:24 so1905-5f47 sudo[3037]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:24 so1905-5f47 volumio[2304]: [rest_api] API:pushState Jan 26 13:38:24 so1905-5f47 volumio[2304]: info: CoreStateMachine::updateTrackBlock Jan 26 13:38:24 so1905-5f47 volumio[2304]: info: CorePlayQueue::getTrackBlock Jan 26 13:38:24 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 13:38:24 so1905-5f47 sudo[3057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:24 so1905-5f47 sudo[3057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:24 so1905-5f47 sudo[3057]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:24 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:24 so1905-5f47 node[2207]: ---- Jan 26 13:38:25 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:25 so1905-5f47 volumio[2304]: [mpd] MPD Permissions set Jan 26 13:38:25 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 26 13:38:25 so1905-5f47 sudo[3100]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:25 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 13:38:25 so1905-5f47 sudo[3100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:25 so1905-5f47 sudo[3100]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:25 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 26 13:38:25 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:25 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 26 13:38:25 so1905-5f47 node[2207]: trying... Jan 26 13:38:25 so1905-5f47 volumio[2304]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 26 13:38:25 so1905-5f47 sudo[3118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:25 so1905-5f47 sudo[3118]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:25 so1905-5f47 sudo[3118]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:25 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:25 so1905-5f47 node[2207]: ---- Jan 26 13:38:25 so1905-5f47 volumio[2304]: [mpd] MPD running with PID2638 Jan 26 13:38:25 so1905-5f47 volumio[2304]: ,establishing connection Jan 26 13:38:25 so1905-5f47 volumio[2304]: [i2s_dacs] Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jan 26 13:38:25 so1905-5f47 volumio[2304]: sudo: /usr/sbin/i2cdetect: command not found Jan 26 13:38:25 so1905-5f47 volumio[2304]: [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 13:38:25 so1905-5f47 volumio[2304]: Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files Jan 26 13:38:25 so1905-5f47 volumio[2304]: { stdout: '', Jan 26 13:38:25 so1905-5f47 volumio[2304]: stderr: Jan 26 13:38:25 so1905-5f47 volumio[2304]: 'Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files\n' } Jan 26 13:38:25 so1905-5f47 volumio[2304]: [rest_api] API:emitFavourites Jan 26 13:38:26 so1905-5f47 volumio[2304]: [spop] Spotify config file written Jan 26 13:38:26 so1905-5f47 sudo[3129]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:26 so1905-5f47 sudo[3129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:26 so1905-5f47 sudo[3129]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:26 so1905-5f47 sudo[3136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Jan 26 13:38:26 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:26 so1905-5f47 sudo[3136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:26 so1905-5f47 systemd[1]: Started go-librespot Daemon. Jan 26 13:38:26 so1905-5f47 go-librespot[3145]: go-librespot daemon starting... Jan 26 13:38:26 so1905-5f47 sudo[3136]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:26 so1905-5f47 node[2207]: trying... Jan 26 13:38:26 so1905-5f47 sudo[3151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:26 so1905-5f47 volumio[2304]: sendCommand: Command { name: 'playlistinfo', args: [] } Jan 26 13:38:26 so1905-5f47 sudo[3151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:26 so1905-5f47 sudo[3151]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:26 so1905-5f47 volumio[2304]: [mpd] MPD error: false == true Jan 26 13:38:26 so1905-5f47 volumio[2304]: [mpd] false == true Jan 26 13:38:26 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:26 so1905-5f47 node[2207]: ---- Jan 26 13:38:26 so1905-5f47 volumio[2304]: sendCommand: Command { name: 'search album ""', args: [] } Jan 26 13:38:26 so1905-5f47 volumio[2304]: [mpd] MPD error: false == true Jan 26 13:38:26 so1905-5f47 volumio[2304]: [mpd] false == true Jan 26 13:38:26 so1905-5f47 sudo[3161]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:26 so1905-5f47 sudo[3161]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:26 so1905-5f47 sudo[3161]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:26 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:26 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 26 13:38:26 so1905-5f47 volumio[3086]: Forking 1 albumart workers Jan 26 13:38:26 so1905-5f47 volumio[2304]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Jan 26 13:38:27 so1905-5f47 volumio[2304]: [websocket] [pushCmd] {"index":5,"getInfo":0} Jan 26 13:38:27 so1905-5f47 volumio[2304]: [spop] No need to fix Spotify hosts Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:27 so1905-5f47 kernel: [SNDCODEC][sunxi_check_hs_detect_status][191]:plugin --> switch:1 Jan 26 13:38:27 so1905-5f47 go-librespot[3181]: time="2026-01-26T13:38:27+08:00" level=info msg="running go-librespot 0.0.0" Jan 26 13:38:27 so1905-5f47 go-librespot[3181]: time="2026-01-26T13:38:27+08:00" level=debug msg="app state loaded" Jan 26 13:38:27 so1905-5f47 go-librespot[3181]: time="2026-01-26T13:38:27+08:00" level=debug msg="stored credentials not found" Jan 26 13:38:27 so1905-5f47 go-librespot[3181]: time="2026-01-26T13:38:27+08:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 13:38:27 so1905-5f47 node[2207]: trying... Jan 26 13:38:27 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:38:27 so1905-5f47 sudo[3195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:27 so1905-5f47 sudo[3195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:27 so1905-5f47 sudo[3195]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:27 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:27 so1905-5f47 node[2207]: ---- Jan 26 13:38:27 so1905-5f47 sudo[3206]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:27 so1905-5f47 sudo[3206]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:28 so1905-5f47 sudo[3206]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:28 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:28 so1905-5f47 node[2207]: trying... Jan 26 13:38:28 so1905-5f47 kernel: [SNDCODEC][sunxi_check_hs_detect_status][222]:plugout --> switch:0 Jan 26 13:38:28 so1905-5f47 go-librespot[3181]: time="2026-01-26T13:38:28+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 13:38:28 so1905-5f47 go-librespot[3181]: time="2026-01-26T13:38:28+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 13:38:28 so1905-5f47 go-librespot[3181]: time="2026-01-26T13:38:28+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 13:38:28 so1905-5f47 sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:28 so1905-5f47 go-librespot[3181]: time="2026-01-26T13:38:28+08:00" level=info msg="zeroconf server listening on port 37189" Jan 26 13:38:28 so1905-5f47 sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:28 so1905-5f47 volumio[2304]: sendCommand: Command { name: 'playlistinfo', args: [] } Jan 26 13:38:29 so1905-5f47 go-librespot[3181]: 2026/01/26 13:38:29 [zeroconf] no suitable IPv6 interface: listen udp6 [ff02::]:5353: socket: address family not supported by protocol Jan 26 13:38:29 so1905-5f47 volumio[2304]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Jan 26 13:38:29 so1905-5f47 sudo[3216]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:29 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:29 so1905-5f47 node[2207]: ---- Jan 26 13:38:29 so1905-5f47 sudo[3229]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:29 so1905-5f47 display[2188]: setImageSrc: this=0x2ae88f0, str=/volumio/display/assets/status-icon/eth_ok.png Jan 26 13:38:29 so1905-5f47 volumio[2304]: [rest_api] API:printToastMessage Jan 26 13:38:29 so1905-5f47 display[2188]: read file /volumio/display/assets/status-icon/eth_ok.png, size=990, buffer[0]=89 Jan 26 13:38:29 so1905-5f47 sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:29 so1905-5f47 sudo[3229]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:29 so1905-5f47 volumio[2304]: info: CoreStateMachine::setRepeat null single undefined Jan 26 13:38:29 so1905-5f47 volumio[2304]: info: CoreStateMachine::pushState Jan 26 13:38:29 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 13:38:29 so1905-5f47 volumio[2304]: [rest_api] API:pushState Jan 26 13:38:29 so1905-5f47 volumio[2304]: info: CoreStateMachine::setRandom null Jan 26 13:38:29 so1905-5f47 volumio[2304]: info: CoreStateMachine::pushState Jan 26 13:38:29 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 13:38:29 so1905-5f47 volumio[2304]: [rest_api] API:pushState Jan 26 13:38:29 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:29 so1905-5f47 node[2207]: trying... Jan 26 13:38:29 so1905-5f47 display[2188]: setImageSrc: this=0x2ae9800, str=http://127.0.0.1:3000/albumart Jan 26 13:38:29 so1905-5f47 sudo[3242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:29 so1905-5f47 sudo[3242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:29 so1905-5f47 sudo[3242]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:29 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:29 so1905-5f47 node[2207]: ---- Jan 26 13:38:29 so1905-5f47 volumio[2304]: [websocket] Received Get System Version Jan 26 13:38:29 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 13:38:29 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 26 13:38:29 so1905-5f47 sudo[3253]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:29 so1905-5f47 sudo[3253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:29 so1905-5f47 sudo[3253]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:30 so1905-5f47 sudo[3265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 13:38:30 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:30 so1905-5f47 sudo[3265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:30 so1905-5f47 sudo[3279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 13:38:30 so1905-5f47 volumio[3171]: Starting albumart workers Jan 26 13:38:30 so1905-5f47 sudo[3265]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:30 so1905-5f47 sudo[3279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:30 so1905-5f47 sudo[3297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 13:38:30 so1905-5f47 volumio[2304]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 26 13:38:30 so1905-5f47 sudo[3279]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:30 so1905-5f47 node[2207]: trying... Jan 26 13:38:30 so1905-5f47 volumio[2304]: info: CoreCommandRouter::volumioGetQueue Jan 26 13:38:30 so1905-5f47 volumio[2304]: info: CoreStateMachine::getQueue Jan 26 13:38:30 so1905-5f47 sudo[3286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 13:38:30 so1905-5f47 volumio[2304]: info: CorePlayQueue::getQueue Jan 26 13:38:30 so1905-5f47 volumio[2304]: [websocket] [pushSrctotal] {"total":8,"cur":0,"curId":"stream:1","source":[{"id":"stream:1","name":"Streaming","code":"stream","type":0},{"id":"bt:1","name":"Bluetooth","code":"bt","type":4},{"id":"aux:1","name":"AUX","code":"aux","type":6},{"id":"optic:1","name":"DIGITAL OPT1","code":"optic","type":5},{"id":"optic:2","name":"DIGITAL OPT2","code":"optic","type":5},{"id":"arc:1","name":"HDMI ARC","code":"arc","type":5}]} Jan 26 13:38:30 so1905-5f47 sudo[3297]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:30 so1905-5f47 volumio[2304]: [websocket] [pushSrctotal] {"total":8,"cur":0,"curId":"stream:1","source":[{"id":"stream:1","name":"Streaming","code":"stream","type":0},{"id":"bt:1","name":"Bluetooth","code":"bt","type":4},{"id":"aux:1","name":"AUX","code":"aux","type":6},{"id":"optic:1","name":"DIGITAL OPT1","code":"optic","type":5},{"id":"optic:2","name":"DIGITAL OPT2","code":"optic","type":5},{"id":"arc:1","name":"HDMI ARC","code":"arc","type":5}]} Jan 26 13:38:30 so1905-5f47 sudo[3286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:30 so1905-5f47 volumio[2304]: [websocket] [pushSrctotal] {"total":8,"cur":0,"curId":"stream:1","source":[{"id":"stream:1","name":"Streaming","code":"stream","type":0},{"id":"bt:1","name":"Bluetooth","code":"bt","type":4},{"id":"aux:1","name":"AUX","code":"aux","type":6},{"id":"optic:1","name":"DIGITAL OPT1","code":"optic","type":5},{"id":"optic:2","name":"DIGITAL OPT2","code":"optic","type":5},{"id":"arc:1","name":"HDMI ARC","code":"arc","type":5}]} Jan 26 13:38:30 so1905-5f47 sudo[3297]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:30 so1905-5f47 sudo[3311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Jan 26 13:38:30 so1905-5f47 volumio[2304]: [websocket] [pushSrctotal] {"total":8,"cur":0,"curId":"stream:1","source":[{"id":"stream:1","name":"Streaming","code":"stream","type":0},{"id":"bt:1","name":"Bluetooth","code":"bt","type":4},{"id":"aux:1","name":"AUX","code":"aux","type":6},{"id":"optic:1","name":"DIGITAL OPT1","code":"optic","type":5},{"id":"optic:2","name":"DIGITAL OPT2","code":"optic","type":5},{"id":"arc:1","name":"HDMI ARC","code":"arc","type":5}]} Jan 26 13:38:30 so1905-5f47 sudo[3304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:30 so1905-5f47 sudo[3286]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:30 so1905-5f47 sudo[3311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:30 so1905-5f47 sudo[3304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:30 so1905-5f47 sudo[3311]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:30 so1905-5f47 volumio[2304]: [mpd] updateQueue error: null Jan 26 13:38:30 so1905-5f47 volumio[2304]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 13:38:30 so1905-5f47 sudo[3304]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:31 so1905-5f47 volumio[2304]: { Error: ENOENT: no such file or directory, stat '/mnt/USB/848E-C1AC_' Jan 26 13:38:31 so1905-5f47 volumio[2304]: at Object.statSync (fs.js:855:3) Jan 26 13:38:31 so1905-5f47 volumio[2304]: at eval (webpack://Volumio2/./app/plugins/music_service/mpd/index.js?:1586:23) Jan 26 13:38:31 so1905-5f47 volumio[2304]: at MpdClient.handleMessage (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:77:3) Jan 26 13:38:31 so1905-5f47 volumio[2304]: at MpdClient.receive (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:68:12) Jan 26 13:38:31 so1905-5f47 volumio[2304]: at Socket.eval (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:43:12) Jan 26 13:38:31 so1905-5f47 volumio[2304]: at Socket.emit (events.js:198:13) Jan 26 13:38:31 so1905-5f47 volumio[2304]: at addChunk (_stream_readable.js:288:12) Jan 26 13:38:31 so1905-5f47 volumio[2304]: at readableAddChunk (_stream_readable.js:265:13) Jan 26 13:38:31 so1905-5f47 volumio[2304]: at Socket.Readable.push (_stream_readable.js:224:10) Jan 26 13:38:31 so1905-5f47 volumio[2304]: at Pipe.onStreamRead [as onread] (internal/stream_base_commons.js:94:17) Jan 26 13:38:31 so1905-5f47 volumio[2304]: errno: -2, Jan 26 13:38:31 so1905-5f47 volumio[2304]: syscall: 'stat', Jan 26 13:38:31 so1905-5f47 volumio[2304]: code: 'ENOENT', Jan 26 13:38:31 so1905-5f47 volumio[2304]: path: '/mnt/USB/848E-C1AC_' } Jan 26 13:38:31 so1905-5f47 volumio[2304]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 13:38:31 so1905-5f47 node[2207]: Connected to: ---- Jan 26 13:38:31 so1905-5f47 node[2207]: ---- Jan 26 13:38:31 so1905-5f47 sudo[3343]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:38:31 so1905-5f47 sudo[3343]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 13:38:31 so1905-5f47 sudo[3343]: pam_unix(sudo:session): session closed for user root Jan 26 13:38:31 so1905-5f47 node[2207]: 连接失败: timeout Jan 26 13:38:31 so1905-5f47 node[2207]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Jan 26 13:38:32 so1905-5f47 sudo[3377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-26 13:37 Jan 26 13:38:32 so1905-5f47 sudo[3377]: 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