-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Tue 2025-12-23 10:10:30 CST. -- Dec 23 10:10:04 so1905-b6d5 systemd-timesyncd[2155]: Initial synchronization to time server 185.125.190.56:123 (ntp.ubuntu.com). Dec 23 10:10:04 so1905-b6d5 sudo[2516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r wlan0 Dec 23 10:10:04 so1905-b6d5 sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:04 so1905-b6d5 sudo[2516]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:04 so1905-b6d5 node[2207]: Connected to: ----FORTEX Dec 23 10:10:04 so1905-b6d5 node[2207]: ---- Dec 23 10:10:04 so1905-b6d5 sudo[2526]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 10:10:04 so1905-b6d5 sudo[2526]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:04 so1905-b6d5 sudo[2526]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:04 so1905-b6d5 node[2207]: ... joined AP, wlan0 IPv4 is 192.168.23.129, ipV6 is undefined Dec 23 10:10:04 so1905-b6d5 node[2207]: It's done! AP Dec 23 10:10:04 so1905-b6d5 smbd[2514]: [2025/12/23 10:10:04.916470, 0] ../../source3/smbd/server.c:1734(main) Dec 23 10:10:04 so1905-b6d5 smbd[2514]: smbd version 4.15.13-Ubuntu started. Dec 23 10:10:04 so1905-b6d5 smbd[2514]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Dec 23 10:10:05 so1905-b6d5 volumio[2300]: info: Loading plugin "network"... Dec 23 10:10:05 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/system_controller/network/index.js Dec 23 10:10:05 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 10:10:05 so1905-b6d5 go-librespot[2545]: go-librespot daemon starting... Dec 23 10:10:05 so1905-b6d5 volumio[2300]: info: Refreshing Cached IP Addresses Dec 23 10:10:05 so1905-b6d5 nmbd[2267]: [2025/12/23 10:10:05.238076, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 23 10:10:05 so1905-b6d5 nmbd[2267]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.129 for name WORKGROUP<1d>. Dec 23 10:10:05 so1905-b6d5 nmbd[2267]: This response was from IP 192.168.23.83, reporting an IP address of 192.168.23.83. Dec 23 10:10:05 so1905-b6d5 sudo[2553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 10:10:05 so1905-b6d5 volumio[2300]: ControllerNetwork autoStartNetworkConfig true Dec 23 10:10:05 so1905-b6d5 sudo[2553]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:05 so1905-b6d5 volumio[2300]: info: Loading plugin "services"... Dec 23 10:10:05 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/system_controller/services/index.js Dec 23 10:10:05 so1905-b6d5 volumio[2300]: info: Loading plugin "alsa_controller"... Dec 23 10:10:05 so1905-b6d5 sudo[2553]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:05 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/audio_interface/alsa_controller/index.js Dec 23 10:10:05 so1905-b6d5 udhcpc[2570]: wlan0: bound: IP=192.168.23.129/255.255.255.0 router=192.168.23.1 domain="" dns="211.136.192.6 120.196.165.24" lease=7200 Dec 23 10:10:05 so1905-b6d5 sudo[2404]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:05 so1905-b6d5 sudo[2559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 10:10:05 so1905-b6d5 volumio[2300]: info: Applying required configuration parameters for plugin alsa_controller Dec 23 10:10:05 so1905-b6d5 systemd[1]: Started Samba SMB Daemon. Dec 23 10:10:05 so1905-b6d5 systemd[1]: Reached target Multi-User System. Dec 23 10:10:05 so1905-b6d5 systemd[1]: Reached target Graphical Interface. Dec 23 10:10:05 so1905-b6d5 sudo[2559]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:05 so1905-b6d5 sudo[2559]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:05 so1905-b6d5 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 23 10:10:05 so1905-b6d5 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 23 10:10:05 so1905-b6d5 systemd[1]: Finished Update UTMP about System Runlevel Changes. Dec 23 10:10:05 so1905-b6d5 volumio[2300]: info: Loading plugin "wizard"... Dec 23 10:10:05 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/miscellanea/wizard/index.js Dec 23 10:10:05 so1905-b6d5 volumio[2300]: info: Loading plugin "volumio_command_line_client"... Dec 23 10:10:05 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/system_controller/volumio_command_line_client/index.js Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolumedb.sh.template Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolumedb.sh.template Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/getvolume.sh.template Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: /volumio/app/plugins/system_controller/volumio_command_line_client/setvolume.sh.template Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:06 so1905-b6d5 kernel: [SNDCODEC][sunxi_check_hs_detect_status][191]:plugin --> switch:1 Dec 23 10:10:06 so1905-b6d5 go-librespot[2586]: time="2025-12-23T10:10:06+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 10:10:06 so1905-b6d5 go-librespot[2586]: time="2025-12-23T10:10:06+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 10:10:06 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:06 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 10:10:06 so1905-b6d5 smbd[2514]: [2025/12/23 10:10:06.280513, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Dec 23 10:10:06 so1905-b6d5 smbd[2514]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Dec 23 10:10:06 so1905-b6d5 smbd[2514]: [2025/12/23 10:10:06.298454, 0] ../../source3/smbd/server.c:1082(smbd_open_one_socket) Dec 23 10:10:06 so1905-b6d5 smbd[2514]: smbd_open_one_socket: open_socket_in failed: Address family not supported by protocol Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: Loading plugin "upnp"... Dec 23 10:10:06 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/audio_interface/upnp/index.js Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: [1766455806402] Starting Upmpd Daemon Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: Loading plugin "my_music"... Dec 23 10:10:06 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/miscellanea/my_music/index.js Dec 23 10:10:06 so1905-b6d5 volumio[2300]: info: Loading plugin "mpd"... Dec 23 10:10:06 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/mpd/index.js Dec 23 10:10:06 so1905-b6d5 display[2186]: setImageSrc: this=0x2c1ac30, str=/volumio/display/assets/source-icon/bt.png Dec 23 10:10:06 so1905-b6d5 display[2186]: read file /volumio/display/assets/source-icon/bt.png, size=11779, buffer[0]=89 Dec 23 10:10:06 so1905-b6d5 display[2186]: setImageSrc: this=0x2b21860, str=/volumio/display/assets/albumart.jpg Dec 23 10:10:06 so1905-b6d5 display[2186]: read file /volumio/display/assets/albumart.jpg, size=77672, buffer[0]=ff Dec 23 10:10:06 so1905-b6d5 display[2186]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Dec 23 10:10:06 so1905-b6d5 display[2186]: Attempting to process with stb_image... Dec 23 10:10:06 so1905-b6d5 kernel: [SNDCODEC][sunxi_check_hs_detect_status][222]:plugout --> switch:0 Dec 23 10:10:07 so1905-b6d5 display[2186]: stb_image successfully loaded: 855x855, channels=3 Dec 23 10:10:07 so1905-b6d5 display[2186]: Successfully processed with stb_image Dec 23 10:10:07 so1905-b6d5 display[2186]: Loading default image: /volumio/display/assets/albumart.jpg Dec 23 10:10:07 so1905-b6d5 display[2186]: Loaded default image from file: /volumio/display/assets/albumart.jpg, size=77672 Dec 23 10:10:07 so1905-b6d5 display[2186]: GetJPGDesc: Processing JPEG file, size=77672, buffer[0]=0xFF Dec 23 10:10:07 so1905-b6d5 display[2186]: Attempting to process with stb_image... Dec 23 10:10:07 so1905-b6d5 display[2186]: stb_image successfully loaded: 855x855, channels=3 Dec 23 10:10:07 so1905-b6d5 display[2186]: Successfully processed with stb_image Dec 23 10:10:07 so1905-b6d5 display[2186]: Default image loaded successfully Dec 23 10:10:07 so1905-b6d5 display[2186]: setImageSrc: this=0x2b20720, str=/volumio/display/assets/status-icon/wifi-error.png Dec 23 10:10:07 so1905-b6d5 display[2186]: read file /volumio/display/assets/status-icon/wifi-error.png, size=1494, buffer[0]=89 Dec 23 10:10:07 so1905-b6d5 display[2186]: setImageSrc: this=0x2b20918, str=/volumio/display/assets/status-icon/eth-error.png Dec 23 10:10:07 so1905-b6d5 display[2186]: read file /volumio/display/assets/status-icon/eth-error.png, size=936, buffer[0]=89 Dec 23 10:10:07 so1905-b6d5 volumio[2300]: info: Creating MPD Configuration file Dec 23 10:10:07 so1905-b6d5 display[2186]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Dec 23 10:10:07 so1905-b6d5 display[2186]: 文件大小: 6344 字节 Dec 23 10:10:07 so1905-b6d5 display[2186]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 40px) Dec 23 10:10:07 so1905-b6d5 display[2186]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf Dec 23 10:10:07 so1905-b6d5 display[2186]: 文件大小: 11495876 字节 Dec 23 10:10:07 so1905-b6d5 display[2186]: FreeType字体加载成功: /volumio/display/fonts/AlibabaPuHuiTi-3-45-Light.ttf (大小: 40px) Dec 23 10:10:07 so1905-b6d5 volumio[2300]: info: Loading plugin "upnp_browser"... Dec 23 10:10:07 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/upnp_browser/index.js Dec 23 10:10:07 so1905-b6d5 display[2186]: setInnerAlign: image_desc is null Dec 23 10:10:07 so1905-b6d5 sudo[2603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 10:10:07 so1905-b6d5 sudo[2606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 10:10:07 so1905-b6d5 sudo[2603]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:07 so1905-b6d5 sudo[2606]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:07 so1905-b6d5 sudo[2603]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:07 so1905-b6d5 systemd[1]: Starting Music Player Daemon... Dec 23 10:10:07 so1905-b6d5 mpd[2618]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.29' not found (required by /usr/bin/mpd) Dec 23 10:10:07 so1905-b6d5 mpd[2618]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.30' not found (required by /usr/bin/mpd) Dec 23 10:10:07 so1905-b6d5 mpd[2618]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `CXXABI_1.3.13' not found (required by /usr/bin/mpd) Dec 23 10:10:07 so1905-b6d5 mpd[2618]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.33' not found (required by /usr/bin/mpd) Dec 23 10:10:07 so1905-b6d5 mpd[2618]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.32' not found (required by /usr/bin/mpd) Dec 23 10:10:07 so1905-b6d5 mpd[2618]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.34' not found (required by /usr/bin/mpd) Dec 23 10:10:07 so1905-b6d5 systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:07 so1905-b6d5 systemd[1]: mpd.service: Failed with result 'exit-code'. Dec 23 10:10:07 so1905-b6d5 systemd[1]: Failed to start Music Player Daemon. Dec 23 10:10:07 so1905-b6d5 sudo[2606]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:07 so1905-b6d5 display[2186]: setImageSrc: this=0x2c1c6a0, str=/volumio/display/assets/source-icon/streaming.png Dec 23 10:10:07 so1905-b6d5 display[2186]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 23 10:10:07 so1905-b6d5 display[2186]: setImageSrc: this=0x2c222c8, str=/volumio/display/assets/source-icon/streaming.png Dec 23 10:10:07 so1905-b6d5 display[2186]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 23 10:10:07 so1905-b6d5 display[2186]: FreeType字体文件存在, 开始加载: /volumio/display/fonts/iconfont.ttf Dec 23 10:10:07 so1905-b6d5 display[2186]: 文件大小: 6344 字节 Dec 23 10:10:07 so1905-b6d5 display[2186]: FreeType字体加载成功: /volumio/display/fonts/iconfont.ttf (大小: 100px) Dec 23 10:10:08 so1905-b6d5 display[2186]: setImageSrc: this=0x2af9d90, str=/volumio/display/assets/source-icon/streaming.png Dec 23 10:10:08 so1905-b6d5 display[2186]: read file /volumio/display/assets/source-icon/streaming.png, size=12136, buffer[0]=89 Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: Loading plugin "networkfs"... Dec 23 10:10:08 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/system_controller/networkfs/index.js Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: Starting IP address watcher Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: Started network interface ip monitor watcher Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: Loading plugin "alarm-clock"... Dec 23 10:10:08 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/miscellanea/alarm-clock/index.js Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: Loading plugin "airplay_emulation"... Dec 23 10:10:08 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/airplay_emulation/index.js Dec 23 10:10:08 so1905-b6d5 volumio[2300]: 执行命令出错: Error: Command failed: /usr/bin/dbus-send --system --dest=org.freedesktop.DBus --type=method_call --print-reply /org/freedesktop/DBus org.freedesktop.DBus.ListNames | grep org.gnome.ShairportSync Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: Starting Shairport Sync Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: [AirPlay] Started coverart directory watcher Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: Loading plugin "last_100"... Dec 23 10:10:08 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/last_100/index.js Dec 23 10:10:08 so1905-b6d5 volumio[2300]: info: Loading plugin "spop"... Dec 23 10:10:08 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/spop/index.js Dec 23 10:10:09 so1905-b6d5 volumio[2300]: info: Loading plugin "webradio"... Dec 23 10:10:09 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/webradio/index.js Dec 23 10:10:09 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 23 10:10:09 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 10:10:09 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 10:10:09 so1905-b6d5 go-librespot[2627]: go-librespot daemon starting... Dec 23 10:10:09 so1905-b6d5 go-librespot[2629]: time="2025-12-23T10:10:09+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 10:10:09 so1905-b6d5 go-librespot[2629]: time="2025-12-23T10:10:09+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 10:10:09 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:09 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 10:10:11 so1905-b6d5 volumio[2300]: info: Loading plugin "yandex_browser"... Dec 23 10:10:11 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/yandex_browser/index.js Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Yandex plugin started Dec 23 10:10:12 so1905-b6d5 volumio[2300]: error: ATTENTION!!!: Plugin yandex_browser does not return adequate promise from onVolumioStart: please update! Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Loading plugin "i2s_dacs"... Dec 23 10:10:12 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/system_controller/i2s_dacs/index.js Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: I2S DAC not set, start Auto-detection Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Loading plugin "volumiodiscovery"... Dec 23 10:10:12 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/system_controller/volumiodiscovery/index.js Dec 23 10:10:12 so1905-b6d5 volumio[2300]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 23 10:10:12 so1905-b6d5 volumio[2300]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 10:10:12 so1905-b6d5 node[2300]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 23 10:10:12 so1905-b6d5 volumio[2300]: *** WARNING *** For more information see Dec 23 10:10:12 so1905-b6d5 volumio[2300]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 23 10:10:12 so1905-b6d5 volumio[2300]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 10:10:12 so1905-b6d5 volumio[2300]: *** WARNING *** For more information see Dec 23 10:10:12 so1905-b6d5 node[2300]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 10:10:12 so1905-b6d5 node[2300]: *** WARNING *** For more information see Dec 23 10:10:12 so1905-b6d5 node[2300]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 23 10:10:12 so1905-b6d5 node[2300]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 10:10:12 so1905-b6d5 node[2300]: *** WARNING *** For more information see Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 23 10:10:12 so1905-b6d5 systemd-networkd[2027]: eth0: Gained carrier Dec 23 10:10:12 so1905-b6d5 kernel: sunxi-gmac 4500000.eth eth0: Link is Up - 1Gbps/Full - flow control off Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Discovery: Started advertising with name: SO1905-b6d5 Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Loading plugin "outputs"... Dec 23 10:10:12 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/audio_interface/outputs/index.js Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Loading plugin "albumart"... Dec 23 10:10:12 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/miscellanea/albumart/index.js Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Plugin example_plugin is not enabled Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Loading plugin "inputs"... Dec 23 10:10:12 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/inputs/index.js Dec 23 10:10:12 so1905-b6d5 volumio[2300]: info: Loading plugin "pandora"... Dec 23 10:10:12 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/pandora/index.js Dec 23 10:10:12 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 23 10:10:12 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 10:10:12 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 10:10:13 so1905-b6d5 go-librespot[2641]: go-librespot daemon starting... Dec 23 10:10:13 so1905-b6d5 go-librespot[2643]: time="2025-12-23T10:10:13+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 10:10:13 so1905-b6d5 go-librespot[2643]: time="2025-12-23T10:10:13+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 10:10:13 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:13 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 10:10:13 so1905-b6d5 volumio[2300]: info: Applying required configuration parameters for plugin pandora Dec 23 10:10:13 so1905-b6d5 volumio[2300]: info: Loading plugin "podcast"... Dec 23 10:10:13 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/podcast/index.js Dec 23 10:10:13 so1905-b6d5 volumio[2300]: info: ControllerPodcast::constructor Dec 23 10:10:13 so1905-b6d5 volumio[2300]: info: Loading plugin "qobuz"... Dec 23 10:10:13 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/qobuz/index.js Dec 23 10:10:13 so1905-b6d5 volumio[2300]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Dec 23 10:10:13 so1905-b6d5 volumio[2300]: info: Loading plugin "tidal"... Dec 23 10:10:13 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/music_service/tidal/index.js Dec 23 10:10:13 so1905-b6d5 kernel: usb0-vbus: disabling Dec 23 10:10:13 so1905-b6d5 volumio[2300]: info: [1766455813888] ControllerTidalPlugin::constructor Dec 23 10:10:13 so1905-b6d5 volumio[2300]: info: [1766455813890] ControllerTidalPlugin::onVolumioStart Dec 23 10:10:13 so1905-b6d5 volumio[2300]: info: Plugin volspotconnect2 is not enabled Dec 23 10:10:13 so1905-b6d5 volumio[2300]: info: Loading plugin "updater_comm"... Dec 23 10:10:13 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/system_controller/updater_comm/index.js Dec 23 10:10:13 so1905-b6d5 systemd-networkd[2027]: eth0: DHCPv4 address 192.168.23.158/24 via 192.168.23.1 Dec 23 10:10:13 so1905-b6d5 avahi-daemon[2171]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.23.158. Dec 23 10:10:13 so1905-b6d5 avahi-daemon[2171]: New relevant interface eth0.IPv4 for mDNS. Dec 23 10:10:13 so1905-b6d5 avahi-daemon[2171]: Registering new address record for 192.168.23.158 on eth0.IPv4. Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: Plugin mpdemulation is not enabled Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: Loading plugin "rest_api"... Dec 23 10:10:14 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/user_interface/rest_api/index.js Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: Loading plugin "websocket"... Dec 23 10:10:14 so1905-b6d5 volumio[2300]: [ require ] /volumio/app/plugins/user_interface/websocket/index.js Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: ___________ START PLUGINS ___________ Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814234] CoreMusicLibrary::Adding element Media Servers Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: SSDP Client initialized. Dec 23 10:10:14 so1905-b6d5 volumio[2300]: Starting initial SSDP search... Dec 23 10:10:14 so1905-b6d5 volumio[2300]: Discovering on interface: lo Dec 23 10:10:14 so1905-b6d5 volumio[2300]: Discovering on interface: eth0 Dec 23 10:10:14 so1905-b6d5 volumio[2300]: Discovering on interface: wlan0 Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814410] CoreMusicLibrary::Adding element Last_100 Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: Creating Spotify config file Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814457] CoreMusicLibrary::Adding element Webradio Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: Yandex plugin started Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814480] ControllerYandexPlugin::addToBrowseSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814482] CoreMusicLibrary::Adding element Yandex Music Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: error: Plugin yandex_browser does not return adequate promise from onStart: please update! Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814499] CoreMusicLibrary::Adding element Pandora Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814507] ControllerPandora::initializeMQTT Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814508] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814527] CoreMusicLibrary::Adding element Podcast Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814535] CoreMusicLibrary::Adding element Qobuz Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814549] ControllerTidalPlugin::onStart Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814557] ControllerTidalPlugin::addToBrowseSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: [1766455814559] CoreMusicLibrary::Adding element Tidal Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: Loading i18n strings for locale en Dec 23 10:10:14 so1905-b6d5 volumio[2300]: Updating browse sources language Dec 23 10:10:14 so1905-b6d5 volumio[2300]: Cannot find translation for sourceMedia Servers Dec 23 10:10:14 so1905-b6d5 volumio[2300]: Cannot find translation for sourceYandex Music Dec 23 10:10:14 so1905-b6d5 volumio[2300]: Cannot find translation for sourcePodcast Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::initPlayerControls Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: BOOT COMPLETED Dec 23 10:10:14 so1905-b6d5 volumio[2300]: [Metrics] CommandRouter: 13s 70.15ms Dec 23 10:10:14 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 10:10:14 so1905-b6d5 kernel: sunxi-spdif 2036000.spdif: active: 1 Dec 23 10:10:16 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 23 10:10:16 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 10:10:16 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 10:10:16 so1905-b6d5 go-librespot[2659]: go-librespot daemon starting... Dec 23 10:10:16 so1905-b6d5 go-librespot[2661]: time="2025-12-23T10:10:16+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 10:10:16 so1905-b6d5 go-librespot[2661]: time="2025-12-23T10:10:16+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 10:10:16 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:16 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 10:10:19 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 23 10:10:19 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 10:10:19 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 10:10:19 so1905-b6d5 go-librespot[2667]: go-librespot daemon starting... Dec 23 10:10:20 so1905-b6d5 go-librespot[2669]: time="2025-12-23T10:10:20+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 10:10:20 so1905-b6d5 go-librespot[2669]: time="2025-12-23T10:10:20+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 10:10:20 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:20 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 10:10:20 so1905-b6d5 volumio[2300]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 23 10:10:20 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::Close All Modals sent Dec 23 10:10:20 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::Close All Modals sent Dec 23 10:10:20 so1905-b6d5 volumio[2300]: Express server listening on port 3000 Dec 23 10:10:20 so1905-b6d5 volumio[2300]: [Metrics] WebUI: 19s 362.41ms Dec 23 10:10:20 so1905-b6d5 volumio[2300]: (node:2300) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Dec 23 10:10:20 so1905-b6d5 volumio[2300]: info: [1766455820354] ControllerPandora::validateAndSetAccountOptions Dec 23 10:10:20 so1905-b6d5 volumio[2300]: error: [1766455820358] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Dec 23 10:10:20 so1905-b6d5 volumio[2300]: info: CoreStateMachine::resetVolumioState Dec 23 10:10:20 so1905-b6d5 volumio[2300]: info: CoreStateMachine::getcurrentVolume Dec 23 10:10:20 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 10:10:20 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 10:10:20 so1905-b6d5 sudo[2687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 10:10:20 so1905-b6d5 sudo[2687]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:20 so1905-b6d5 sudo[2687]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:20 so1905-b6d5 sudo[2689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 10:10:20 so1905-b6d5 sudo[2689]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:20 so1905-b6d5 volumio[2300]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 23 10:10:20 so1905-b6d5 sudo[2689]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:20 so1905-b6d5 volumio[2300]: info: eth0 IPv4 address event: 4: eth0 inet 192.168.23.158/24 brd 192.168.23.255 scope global dynamic eth0\ valid_lft 7200sec preferred_lft 7200sec Dec 23 10:10:20 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 23 10:10:21 so1905-b6d5 sudo[2714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 23 10:10:21 so1905-b6d5 sudo[2714]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:21 so1905-b6d5 sudo[2721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 23 10:10:21 so1905-b6d5 sudo[2714]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:21 so1905-b6d5 sudo[2721]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:21 so1905-b6d5 sudo[2728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 23 10:10:21 so1905-b6d5 sudo[2742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 10:10:21 so1905-b6d5 volumio[2300]: info: Connecting to wireless device: undefined Dec 23 10:10:21 so1905-b6d5 sudo[2721]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:21 so1905-b6d5 sudo[2742]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:21 so1905-b6d5 sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:21 so1905-b6d5 login[2335]: pam_unix(login:session): session opened for user volumio by LOGIN(uid=0) Dec 23 10:10:21 so1905-b6d5 sudo[2728]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:21 so1905-b6d5 sudo[2742]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:21 so1905-b6d5 sudo[2761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 10:10:21 so1905-b6d5 systemd-logind[2188]: New session c1 of user volumio. Dec 23 10:10:21 so1905-b6d5 sudo[2761]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:21 so1905-b6d5 sudo[2761]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:21 so1905-b6d5 systemd[1]: Created slice User Slice of UID 1000. Dec 23 10:10:21 so1905-b6d5 systemd[1]: Starting User Runtime Directory /run/user/1000... Dec 23 10:10:21 so1905-b6d5 systemd[1]: Finished User Runtime Directory /run/user/1000. Dec 23 10:10:21 so1905-b6d5 systemd[1]: Starting User Manager for UID 1000... Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: IP address changed from "" to "192.168.23.158 192.168.23.129" Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 23 10:10:22 so1905-b6d5 systemd[2786]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Dec 23 10:10:22 so1905-b6d5 sudo[2796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 23 10:10:22 so1905-b6d5 sudo[2796]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:22 so1905-b6d5 sudo[2808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 23 10:10:22 so1905-b6d5 sudo[2808]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:22 so1905-b6d5 sudo[2796]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: Connecting to wireless device: undefined Dec 23 10:10:22 so1905-b6d5 sudo[2814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 23 10:10:22 so1905-b6d5 sudo[2808]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: this.stateMachine.currentVolume: null, vol: 0 Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: [AirPlay] Received volume update: {"vol":0,"mute":false,"disableVolumeControl":false} Dec 23 10:10:22 so1905-b6d5 sudo[2823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 10:10:22 so1905-b6d5 sudo[2814]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:22 so1905-b6d5 sudo[2823]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:22 so1905-b6d5 sudo[2831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 10:10:22 so1905-b6d5 sudo[2814]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:22 so1905-b6d5 sudo[2831]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:22 so1905-b6d5 sudo[2823]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: CoreStateMachine::pushState Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 10:10:22 so1905-b6d5 sudo[2831]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: CoreStateMachine::updateTrackBlock Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: CorePlayQueue::getTrackBlock Dec 23 10:10:22 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 10:10:23 so1905-b6d5 volumio[2300]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 23 10:10:23 so1905-b6d5 volumio[2300]: Job for mpd.service failed because the control process exited with error code. Dec 23 10:10:23 so1905-b6d5 volumio[2300]: See "systemctl status mpd.service" and "journalctl -xe" for details. Dec 23 10:10:23 so1905-b6d5 volumio[2300]: info: MPD Permissions set Dec 23 10:10:23 so1905-b6d5 systemd[1]: Starting Music Player Daemon... Dec 23 10:10:23 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 23 10:10:23 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 23 10:10:23 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 10:10:23 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 10:10:23 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 10:10:23 so1905-b6d5 go-librespot[2879]: go-librespot daemon starting... Dec 23 10:10:23 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 23 10:10:23 so1905-b6d5 mpd[2877]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.29' not found (required by /usr/bin/mpd) Dec 23 10:10:23 so1905-b6d5 mpd[2877]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.30' not found (required by /usr/bin/mpd) Dec 23 10:10:23 so1905-b6d5 mpd[2877]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `CXXABI_1.3.13' not found (required by /usr/bin/mpd) Dec 23 10:10:23 so1905-b6d5 mpd[2877]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.33' not found (required by /usr/bin/mpd) Dec 23 10:10:23 so1905-b6d5 mpd[2877]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.32' not found (required by /usr/bin/mpd) Dec 23 10:10:23 so1905-b6d5 mpd[2877]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.34' not found (required by /usr/bin/mpd) Dec 23 10:10:23 so1905-b6d5 systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:23 so1905-b6d5 systemd[1]: mpd.service: Failed with result 'exit-code'. Dec 23 10:10:23 so1905-b6d5 systemd[1]: Failed to start Music Player Daemon. Dec 23 10:10:23 so1905-b6d5 systemd[1]: Starting Music Player Daemon... Dec 23 10:10:24 so1905-b6d5 go-librespot[2887]: time="2025-12-23T10:10:24+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 10:10:24 so1905-b6d5 go-librespot[2887]: time="2025-12-23T10:10:24+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 10:10:24 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:24 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 10:10:24 so1905-b6d5 mpd[2886]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.29' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2886]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.30' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2886]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `CXXABI_1.3.13' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2886]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.33' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2886]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.32' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2886]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.34' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:24 so1905-b6d5 systemd[1]: mpd.service: Failed with result 'exit-code'. Dec 23 10:10:24 so1905-b6d5 systemd[1]: Failed to start Music Player Daemon. Dec 23 10:10:24 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworkMode Dec 23 10:10:24 so1905-b6d5 systemd[1]: Starting Music Player Daemon... Dec 23 10:10:24 so1905-b6d5 volumio[2300]: info: [pushCmd] {"index":5,"getInfo":0} Dec 23 10:10:24 so1905-b6d5 mpd[2892]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.29' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2892]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.30' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2892]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `CXXABI_1.3.13' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2892]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.33' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2892]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.32' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2892]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.34' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:24 so1905-b6d5 systemd[1]: mpd.service: Failed with result 'exit-code'. Dec 23 10:10:24 so1905-b6d5 systemd[1]: Failed to start Music Player Daemon. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Reached target Paths. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Reached target Timers. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Listening on GnuPG network certificate management daemon. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Listening on GnuPG cryptographic agent and passphrase cache. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: mpd.socket: Failed to create listening socket (0.0.0.0:6600): Address already in use Dec 23 10:10:24 so1905-b6d5 systemd[2786]: mpd.socket: Failed to listen on sockets: Address already in use Dec 23 10:10:24 so1905-b6d5 systemd[2786]: mpd.socket: Failed with result 'resources'. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Failed to listen on mpd.socket. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Reached target Sockets. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Reached target Basic System. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Starting Music Player Daemon... Dec 23 10:10:24 so1905-b6d5 systemd[1]: Starting Music Player Daemon... Dec 23 10:10:24 so1905-b6d5 systemd[1]: Started User Manager for UID 1000. Dec 23 10:10:24 so1905-b6d5 systemd[1]: Started Session c1 of user volumio. Dec 23 10:10:24 so1905-b6d5 mpd[2896]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.29' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2896]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.30' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2896]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `CXXABI_1.3.13' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2896]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.33' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2896]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.32' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 mpd[2896]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.34' not found (required by /usr/bin/mpd) Dec 23 10:10:24 so1905-b6d5 systemd[2786]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:24 so1905-b6d5 systemd[2786]: mpd.service: Failed with result 'exit-code'. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Failed to start Music Player Daemon. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Reached target Main User Target. Dec 23 10:10:24 so1905-b6d5 systemd[2786]: Startup finished in 2.683s. Dec 23 10:10:25 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: network , pushNetworkConfiguredStatus Dec 23 10:10:25 so1905-b6d5 volumio[2300]: info: pushNetworkConfiguredResult ok {"status":"success","ssid":"FORTEX"} Dec 23 10:10:25 so1905-b6d5 volumio[2300]: info: Push network configured result Dec 23 10:10:25 so1905-b6d5 volumio[2300]: info: pushNetworkConfiguredResult Dec 23 10:10:25 so1905-b6d5 mpd[2895]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.29' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2895]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.30' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2895]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `CXXABI_1.3.13' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2895]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.33' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2895]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.32' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2895]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.34' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:25 so1905-b6d5 systemd[1]: mpd.service: Failed with result 'exit-code'. Dec 23 10:10:25 so1905-b6d5 systemd[1]: Failed to start Music Player Daemon. Dec 23 10:10:25 so1905-b6d5 systemd[1]: Starting Music Player Daemon... Dec 23 10:10:25 so1905-b6d5 mpd[2910]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.29' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2910]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `GLIBCXX_3.4.30' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2910]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libstdc++.so.6: version `CXXABI_1.3.13' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2910]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.33' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2910]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.32' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 mpd[2910]: /usr/bin/mpd: /lib/arm-linux-gnueabihf/libc.so.6: version `GLIBC_2.34' not found (required by /usr/bin/mpd) Dec 23 10:10:25 so1905-b6d5 systemd[1]: mpd.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:25 so1905-b6d5 systemd[1]: mpd.service: Failed with result 'exit-code'. Dec 23 10:10:25 so1905-b6d5 systemd[1]: Failed to start Music Player Daemon. Dec 23 10:10:25 so1905-b6d5 volumio[2300]: error: DBus command failed: Command failed: /usr/bin/dbus-send --system --print-reply --dest=org.gnome.ShairportSync "/org/gnome/ShairportSync" org.gnome.ShairportSync.RemoteControl.SetAirplayVolume double:-30.00 Dec 23 10:10:25 so1905-b6d5 volumio[2300]: Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files Dec 23 10:10:25 so1905-b6d5 volumio[2300]: {"stdout":"","stderr":"Error org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.ShairportSync was not provided by any .service files\n"} Dec 23 10:10:25 so1905-b6d5 systemd[1]: mpd.service: Start request repeated too quickly. Dec 23 10:10:25 so1905-b6d5 systemd[1]: mpd.service: Failed with result 'exit-code'. Dec 23 10:10:25 so1905-b6d5 systemd[1]: Failed to start Music Player Daemon. Dec 23 10:10:25 so1905-b6d5 systemd[1]: mpd.socket: Failed with result 'service-start-limit-hit'. Dec 23 10:10:25 so1905-b6d5 volumio[2870]: Forking 1 albumart workers Dec 23 10:10:25 so1905-b6d5 volumio[2300]: info: Spotify config file written Dec 23 10:10:26 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 10:10:26 so1905-b6d5 kernel: sunxi-spdif 2036000.spdif: active: 1 Dec 23 10:10:26 so1905-b6d5 sudo[2920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart go-librespot-daemon.service Dec 23 10:10:26 so1905-b6d5 sudo[2920]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 10:10:26 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 10:10:26 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 10:10:26 so1905-b6d5 sudo[2920]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:26 so1905-b6d5 go-librespot[2929]: go-librespot daemon starting... Dec 23 10:10:26 so1905-b6d5 go-librespot[2934]: time="2025-12-23T10:10:26+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 10:10:26 so1905-b6d5 go-librespot[2934]: time="2025-12-23T10:10:26+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 10:10:26 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:26 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 10:10:27 so1905-b6d5 sudo[2940]: volumio : TTY=ttyAS0 ; PWD=/home/volumio ; USER=root ; COMMAND=/sbin/ifconfig Dec 23 10:10:27 so1905-b6d5 sudo[2940]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 23 10:10:27 so1905-b6d5 sudo[2940]: pam_unix(sudo:session): session closed for user root Dec 23 10:10:27 so1905-b6d5 volumio[2913]: Starting albumart workers Dec 23 10:10:28 so1905-b6d5 volumio[2300]: Playing WAVE '/volumio/app/wifi_connected.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 23 10:10:28 so1905-b6d5 volumio[2300]: info: CoreCommandRouter::Close All Modals sent Dec 23 10:10:29 so1905-b6d5 volumio[2300]: error: MPD error: Error: read ECONNRESET Dec 23 10:10:29 so1905-b6d5 volumio[2300]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 10:10:29 so1905-b6d5 volumio[2300]: TypeError: err.includes is not a function Dec 23 10:10:29 so1905-b6d5 volumio[2300]: at MpdClient.eval (webpack://Volumio2/./app/plugins/music_service/mpd/index.js?:861:13) Dec 23 10:10:29 so1905-b6d5 volumio[2300]: at MpdClient.emit (events.js:198:13) Dec 23 10:10:29 so1905-b6d5 volumio[2300]: at Socket.eval (webpack://Volumio2/./app/plugins/music_service/mpd/lib/mpd.js?:49:12) Dec 23 10:10:29 so1905-b6d5 volumio[2300]: at Socket.emit (events.js:198:13) Dec 23 10:10:29 so1905-b6d5 volumio[2300]: at emitErrorNT (internal/streams/destroy.js:91:8) Dec 23 10:10:29 so1905-b6d5 volumio[2300]: at emitErrorAndCloseNT (internal/streams/destroy.js:59:3) Dec 23 10:10:29 so1905-b6d5 volumio[2300]: at process._tickCallback (internal/process/next_tick.js:63:19) Dec 23 10:10:29 so1905-b6d5 volumio[2300]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 10:10:29 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 23 10:10:29 so1905-b6d5 systemd[1]: Stopped go-librespot Daemon. Dec 23 10:10:29 so1905-b6d5 systemd[1]: Started go-librespot Daemon. Dec 23 10:10:29 so1905-b6d5 go-librespot[2966]: go-librespot daemon starting... Dec 23 10:10:30 so1905-b6d5 go-librespot[2968]: time="2025-12-23T10:10:30+08:00" level=info msg="running go-librespot 0.0.0" Dec 23 10:10:30 so1905-b6d5 go-librespot[2968]: time="2025-12-23T10:10:30+08:00" level=fatal msg="failed creating app" error="failed unmarshalling state file: unexpected end of JSON input" Dec 23 10:10:30 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 10:10:30 so1905-b6d5 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 10:10:30 so1905-b6d5 sudo[2975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 10:09 Dec 23 10:10:30 so1905-b6d5 sudo[2975]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal BRAND_NAME="PREMIERA" MODEL_NAME="FT1905" IMG_VERSION="1.100.006" VOLUMIO_BUILD_VERSION="" VOLUMIO_ARCH="armv7" VOLUMIO_VERSION=1.5.4.20 VOLUMIO_BUILD_DATE=2025-12-05 14:21:17 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system