Feb 18 16:14:42 shanling volumio[1147]: info: ------------------------------------------- Feb 18 16:14:42 shanling systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 18 16:14:42 shanling systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 18 16:14:42 shanling volumio[1147]: info: ----- Volumio3 ---- Feb 18 16:14:42 shanling volumio[1147]: info: ------------------------------------------- Feb 18 16:14:42 shanling volumio[1147]: info: ----- System startup ---- Feb 18 16:14:42 shanling volumio[1147]: info: ------------------------------------------- Feb 18 16:14:42 shanling ntpd[1068]: IO: Listen normally on 3 eth0 192.168.0.26:123 Feb 18 16:14:42 shanling ntpd[1068]: IO: new interface(s) found: waking up resolver Feb 18 16:14:42 shanling ntpd[1068]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 18 16:14:42 shanling ntpd[1068]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 18 16:14:42 shanling ntpd[1068]: DNS: Pool taking: 91.206.8.36 Feb 18 16:14:42 shanling ntpd[1068]: DNS: Pool taking: 94.199.174.89 Feb 18 16:14:42 shanling ntpd[1068]: DNS: Pool taking: 46.102.157.67 Feb 18 16:14:42 shanling ntpd[1068]: DNS: Pool taking: 86.59.113.124 Feb 18 16:14:42 shanling ntpd[1068]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 18 16:14:42 shanling smbd[1239]: [2026/02/18 16:14:42.436284, 0] ../../source3/smbd/server.c:1741(main) Feb 18 16:14:42 shanling smbd[1239]: smbd version 4.17.8-Raspbian started. Feb 18 16:14:42 shanling smbd[1239]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Feb 18 16:14:42 shanling winbindd[1209]: [2026/02/18 16:14:42.575107, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Feb 18 16:14:42 shanling winbindd[1209]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'TEST' failed 'NT_STATUS_IO_TIMEOUT' Feb 18 16:14:42 shanling systemd[1]: Started smbd.service - Samba SMB Daemon. Feb 18 16:14:42 shanling systemd[1]: Reached target multi-user.target - Multi-User System. Feb 18 16:14:42 shanling systemd[1]: Reached target graphical.target - Graphical Interface. Feb 18 16:14:42 shanling systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Feb 18 16:14:42 shanling systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 18 16:14:42 shanling systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Feb 18 16:14:42 shanling systemd[1]: Startup finished in 13.090s (kernel) + 11.869s (userspace) = 24.960s. Feb 18 16:14:43 shanling ntpd[1068]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 18 16:14:43 shanling ntpd[1068]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Feb 18 16:14:43 shanling ntpd[1068]: DNS: Pool taking: 162.159.200.123 Feb 18 16:14:43 shanling ntpd[1068]: DNS: Pool taking: 83.215.130.11 Feb 18 16:14:43 shanling ntpd[1068]: DNS: Pool taking: 151.236.30.71 Feb 18 16:14:43 shanling ntpd[1068]: DNS: Pool taking: 152.53.100.131 Feb 18 16:14:43 shanling ntpd[1068]: DNS: Pool taking: 2a0a:4cc0:c0:3d9b:280b:78ff:fe62:8a2e Feb 18 16:14:43 shanling ntpd[1068]: DNS: Pool taking: 2606:4700:f1::123 Feb 18 16:14:43 shanling ntpd[1068]: DNS: Pool taking: 2a02:1748:0:1500:1::8002 Feb 18 16:14:43 shanling ntpd[1068]: DNS: Pool taking: 2a02:1748:0:1900:1::82 Feb 18 16:14:43 shanling ntpd[1068]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Feb 18 16:14:43 shanling winbindd[1218]: [2026/02/18 16:14:43.241444, 0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response) Feb 18 16:14:43 shanling winbindd[1218]: Could not write result Feb 18 16:14:43 shanling volumio[1147]: info: MYVOLUMIO Environment detected Feb 18 16:14:43 shanling volumio[1147]: info: Plugin folders cleanup Feb 18 16:14:43 shanling volumio[1147]: info: Scanning into folder /volumio/app/plugins/ Feb 18 16:14:43 shanling volumio[1147]: info: Scanning category audio_interface Feb 18 16:14:43 shanling volumio[1147]: info: Scanning category miscellanea Feb 18 16:14:43 shanling volumio[1147]: info: Scanning category music_service Feb 18 16:14:43 shanling volumio[1147]: info: Scanning category plugins.json Feb 18 16:14:43 shanling volumio[1147]: info: Scanning category system_controller Feb 18 16:14:43 shanling volumio[1147]: info: Scanning category user_interface Feb 18 16:14:43 shanling volumio[1147]: info: Scanning into folder /data/plugins/ Feb 18 16:14:43 shanling volumio[1147]: info: Scanning category audio_interface Feb 18 16:14:43 shanling volumio[1147]: info: Scanning category music_service Feb 18 16:14:43 shanling volumio[1147]: info: Plugin folders cleanup completed Feb 18 16:14:43 shanling volumio[1147]: info: ------------------------------------------- Feb 18 16:14:43 shanling volumio[1147]: info: ----- Core plugins startup ---- Feb 18 16:14:43 shanling volumio[1147]: info: ------------------------------------------- Feb 18 16:14:43 shanling volumio[1147]: info: Loading plugins from folder /volumio/app/plugins/ Feb 18 16:14:43 shanling volumio[1147]: info: Adding plugin upnp to MyMusic Plugins Feb 18 16:14:43 shanling volumio[1147]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 18 16:14:43 shanling volumio[1147]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 18 16:14:43 shanling volumio[1147]: info: Loading plugins from folder /data/plugins/ Feb 18 16:14:43 shanling volumio[1147]: info: Loading plugin "system"... Feb 18 16:14:43 shanling volumio[1147]: info: Loading plugin "appearance"... Feb 18 16:14:44 shanling ntpd[1068]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 18 16:14:44 shanling ntpd[1068]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 18 16:14:44 shanling ntpd[1068]: DNS: Pool taking: 78.41.116.149 Feb 18 16:14:44 shanling ntpd[1068]: DNS: Pool taking: 91.206.8.34 Feb 18 16:14:44 shanling ntpd[1068]: DNS: Pool taking: 152.53.15.127 Feb 18 16:14:44 shanling ntpd[1068]: DNS: Pool taking: 152.53.51.182 Feb 18 16:14:44 shanling ntpd[1068]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "network"... Feb 18 16:14:45 shanling volumio[1147]: info: Refreshing Cached IP Addresses Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "services"... Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "volumio5onboarding"... Feb 18 16:14:45 shanling sudo[1258]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 16:14:45 shanling sudo[1258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:45 shanling ntpd[1068]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 18 16:14:45 shanling sudo[1258]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "alsa_controller"... Feb 18 16:14:45 shanling sudo[1261]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 16:14:45 shanling sudo[1261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:45 shanling sudo[1261]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:45 shanling ntpd[1068]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 18 16:14:45 shanling ntpd[1068]: DNS: Pool taking: 152.53.119.76 Feb 18 16:14:45 shanling ntpd[1068]: DNS: Pool taking: 162.159.200.1 Feb 18 16:14:45 shanling ntpd[1068]: DNS: Pool taking: 178.251.64.52 Feb 18 16:14:45 shanling ntpd[1068]: DNS: Pool taking: 90.146.77.249 Feb 18 16:14:45 shanling ntpd[1068]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 18 16:14:45 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "wizard"... Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "networkfs"... Feb 18 16:14:45 shanling volumio[1147]: info: Starting Udev Watcher for removable devices Feb 18 16:14:45 shanling sudo[1287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=VOLUMIO,password=(VOLUMIO-SERVER1),ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.43/18tera-musik /mnt/NAS/Laptop_Musik Feb 18 16:14:45 shanling sudo[1287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:45 shanling volumio[1147]: info: Ignoring mount for partition: boot Feb 18 16:14:45 shanling volumio[1147]: info: Ignoring mount for partition: volumio Feb 18 16:14:45 shanling volumio[1147]: info: Ignoring mount for partition: volumio_data Feb 18 16:14:45 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "volumio_command_line_client"... Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "upnp"... Feb 18 16:14:45 shanling volumio[1147]: info: [1771427685381] Starting Upmpd Daemon Feb 18 16:14:45 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "my_music"... Feb 18 16:14:45 shanling volumio[1147]: info: Loading plugin "mpd"... Feb 18 16:14:45 shanling kernel: netfs: FS-Cache loaded Feb 18 16:14:45 shanling kernel: Key type cifs.spnego registered Feb 18 16:14:45 shanling kernel: Key type cifs.idmap registered Feb 18 16:14:45 shanling kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 18 16:14:45 shanling kernel: CIFS: Attempting to mount //192.168.0.43/18tera-musik Feb 18 16:14:45 shanling sudo[1287]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:46 shanling volumio[1147]: info: Loading plugin "upnp_browser"... Feb 18 16:14:47 shanling volumio-remote-updater[695]: [2026-02-18 16:14:47] [connect] Successful connection Feb 18 16:14:47 shanling volumio[1147]: info: Starting UPNP Browser Feb 18 16:14:47 shanling volumio[1147]: info: Loading plugin "alarm-clock"... Feb 18 16:14:47 shanling volumio[1147]: info: Loading plugin "airplay_emulation"... Feb 18 16:14:47 shanling volumio[1147]: info: Starting Shairport Sync Feb 18 16:14:47 shanling volumio[1147]: info: Loading plugin "last_100"... Feb 18 16:14:47 shanling volumio[1147]: info: Loading plugin "webradio"... Feb 18 16:14:47 shanling volumio[1147]: info: Loading plugin "i2s_dacs"... Feb 18 16:14:47 shanling volumio[1147]: info: I2S DAC not set, start Auto-detection Feb 18 16:14:47 shanling volumio[1147]: info: Loading plugin "volumiodiscovery"... Feb 18 16:14:47 shanling volumio[1147]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 16:14:47 shanling volumio[1147]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 16:14:47 shanling volumio[1147]: *** WARNING *** For more information see Feb 18 16:14:47 shanling volumio[1147]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 16:14:47 shanling volumio[1147]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 16:14:47 shanling node[1147]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 16:14:47 shanling volumio[1147]: *** WARNING *** For more information see Feb 18 16:14:47 shanling node[1147]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 16:14:47 shanling node[1147]: *** WARNING *** For more information see Feb 18 16:14:47 shanling node[1147]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 16:14:47 shanling node[1147]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 16:14:47 shanling node[1147]: *** WARNING *** For more information see Feb 18 16:14:47 shanling volumio[1147]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 16:14:47 shanling volumio[1147]: info: Discovery: Started advertising with name: SHANLING Feb 18 16:14:47 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 16:14:47 shanling volumio[1147]: info: Loading plugin "spop"... Feb 18 16:14:48 shanling ntpd[1068]: CLOCK: time stepped by 0.679989 Feb 18 16:14:48 shanling ntpd[1068]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 18 16:14:50 shanling volumio[1147]: info: Loading plugin "outputs"... Feb 18 16:14:50 shanling volumio[1147]: info: Loading plugin "albumart"... Feb 18 16:14:50 shanling volumio[1147]: info: Plugin example_plugin is not enabled Feb 18 16:14:50 shanling volumio[1147]: info: Loading plugin "inputs"... Feb 18 16:14:50 shanling volumio[1147]: info: Loading plugin "updater_comm"... Feb 18 16:14:50 shanling volumio[1147]: info: Plugin mpdemulation is not enabled Feb 18 16:14:50 shanling volumio[1147]: info: Loading plugin "rest_api"... Feb 18 16:14:50 shanling volumio[1147]: info: Loading plugin "websocket"... Feb 18 16:14:50 shanling volumio[1147]: info: Starting Socket.io Server version 1.7.4 Feb 18 16:14:50 shanling volumio[1147]: info: Plugin fusiondsp is not enabled Feb 18 16:14:50 shanling volumio[1147]: info: Loading i18n strings for locale de Feb 18 16:14:50 shanling volumio[1147]: Updating browse sources language Feb 18 16:14:50 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 16:14:50 shanling systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Feb 18 16:14:50 shanling systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 16:14:50 shanling systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 16:14:51 shanling upmpdcli[1337]: Could not open config: /tmp/upmpdcli.conf Feb 18 16:14:51 shanling systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 16:14:51 shanling systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::initPlayerControls Feb 18 16:14:51 shanling volumio[1327]: Forking 3 albumart workers Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: Express server listening on port 3000 Feb 18 16:14:51 shanling volumio[1147]: [Metrics] WebUI: 9s 449.25ms Feb 18 16:14:51 shanling volumio[1147]: info: CoreStateMachine::resetVolumioState Feb 18 16:14:51 shanling volumio[1147]: info: CoreStateMachine::getcurrentVolume Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 16:14:51 shanling volumio[1147]: info: CoreStateMachine::pushState Feb 18 16:14:51 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::volumioPushState Feb 18 16:14:51 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 16:14:51 shanling volumio[1147]: info: Volumio Network Manager: Network status updated: 1 Feb 18 16:14:51 shanling volumio-remote-updater[695]: [2026-02-18 16:14:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771427687 101 Feb 18 16:14:51 shanling volumio[1147]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Feb 18 16:14:52 shanling volumio[1147]: verbose: New Socket.io Connection to 192.168.0.26:3000 from 192.168.0.27 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 18 16:14:52 shanling volumio[1147]: info: Reloading queue from file Feb 18 16:14:52 shanling volumio[1147]: verbose: New Socket.io Connection to 192.168.0.26:3000 from 192.168.0.136 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 18 16:14:52 shanling volumio[1147]: info: Setting Device type: Raspberry PI Feb 18 16:14:52 shanling volumio[1147]: info: CoreStateMachine::setRepeat null single undefined Feb 18 16:14:52 shanling volumio[1147]: info: CoreStateMachine::pushState Feb 18 16:14:52 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:14:52 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 16:14:52 shanling volumio[1147]: info: CoreCommandRouter::volumioPushState Feb 18 16:14:52 shanling volumio[1147]: info: CoreStateMachine::setRandom null Feb 18 16:14:52 shanling volumio[1147]: info: CoreStateMachine::pushState Feb 18 16:14:52 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:14:52 shanling volumio[1147]: info: CoreCommandRouter::volumioPushState Feb 18 16:14:52 shanling volumio[1147]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 18 16:14:52 shanling volumio[1147]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Feb 18 16:14:52 shanling volumio[1147]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 18 16:14:52 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 18 16:14:52 shanling volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 18 16:14:52 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:14:52 shanling volumio[1147]: info: Discovery: adding 9b43dc4c-8727-4063-afe1-da0ea25dac46 Feb 18 16:14:52 shanling volumio[1147]: info: Discovery: Found device MARANTZ Feb 18 16:14:52 shanling volumio[1147]: info: Discovery: Connecting to remote: 192.168.0.27 Feb 18 16:14:52 shanling volumio[1147]: info: Discovery: adding f5a1578b-3861-4829-8159-4257696ce347 Feb 18 16:14:52 shanling volumio[1147]: info: Discovery: Found device SERVER Feb 18 16:14:52 shanling volumio[1147]: info: Discovery: Connecting to remote: 192.168.0.136 Feb 18 16:14:53 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 18 16:14:53 shanling volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 18 16:14:53 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:14:53 shanling volumio[1147]: info: Discovery: Connected to remote: 192.168.0.27 Feb 18 16:14:53 shanling volumio[1147]: info: Completed loading Core Plugins Feb 18 16:14:53 shanling volumio[1147]: info: Preparing to generate the ALSA configuration file Feb 18 16:14:53 shanling volumio[1147]: info: Discovery: adding 22a97f97-54c4-4e04-be77-6806ba8769c9 Feb 18 16:14:53 shanling volumio[1147]: info: Discovery: Found device OPPO9038DSD Feb 18 16:14:53 shanling volumio[1147]: info: Discovery: Connecting to remote: 192.168.0.28 Feb 18 16:14:53 shanling volumio[1147]: info: Discovery: adding 33ba4d37-ab1b-49a8-a740-38a7d4ed1c59 Feb 18 16:14:53 shanling volumio[1147]: info: Discovery: Found device SHANLING Feb 18 16:14:53 shanling volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 18 16:14:53 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:14:53 shanling volumio[1147]: info: Discovery: Connected to remote: 192.168.0.136 Feb 18 16:14:54 shanling volumio[1147]: info: Discovery: this is already registered, 33ba4d37-ab1b-49a8-a740-38a7d4ed1c59 Feb 18 16:14:54 shanling volumio[1147]: info: Discovery: Found device SHANLING Feb 18 16:14:54 shanling volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 18 16:14:54 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:14:54 shanling volumio[1147]: info: Asound.conf file written Feb 18 16:14:54 shanling sudo[1386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 18 16:14:54 shanling sudo[1386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:54 shanling sudo[1386]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:54 shanling volumio[1147]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 18 16:14:54 shanling volumio[1147]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 18 16:14:54 shanling volumio[1147]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 18 16:14:54 shanling volumio[1147]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 18 16:14:54 shanling volumio[1147]: info: Output device has changed, restarting MPD Feb 18 16:14:54 shanling volumio[1147]: info: Output device has changed, restarting Shairport Sync Feb 18 16:14:54 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:54 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 16:14:54 shanling sudo[1392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 16:14:54 shanling sudo[1392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:54 shanling sudo[1394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 16:14:54 shanling volumio[1147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 16:14:54 shanling volumio[1147]: info: ___________ START PLUGINS ___________ Feb 18 16:14:54 shanling sudo[1392]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:54 shanling sudo[1394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:54 shanling volumio[1147]: info: ControllerMpd::onStart: Initializing MPD Feb 18 16:14:54 shanling volumio[1147]: info: Creating MPD Configuration file Feb 18 16:14:54 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 16:14:54 shanling volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 16:14:54 shanling volumio[1147]: info: [1771427694972] CoreMusicLibrary::Adding element Medienserver Feb 18 16:14:54 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 16:14:54 shanling sudo[1404]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 16:14:54 shanling sudo[1404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:55 shanling systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 16:14:55 shanling systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 16:14:55 shanling sudo[1404]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:55 shanling sudo[1402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 18 16:14:55 shanling sudo[1402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:55 shanling volumio[1147]: info: UPNP Browser: Client initialized successfully Feb 18 16:14:55 shanling sudo[1409]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 16:14:55 shanling sudo[1409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 16:14:55 shanling sudo[1411]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 18 16:14:55 shanling sudo[1409]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:55 shanling sudo[1407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 16:14:55 shanling sudo[1407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 16:14:55 shanling systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Feb 18 16:14:55 shanling sudo[1402]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:55 shanling systemd[1]: mpd.service: Deactivated successfully. Feb 18 16:14:55 shanling systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 16:14:55 shanling systemd[1]: mpd.socket: Deactivated successfully. Feb 18 16:14:55 shanling systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 16:14:55 shanling systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 16:14:55 shanling systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 16:14:55 shanling systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 16:14:55 shanling volumio[1147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 16:14:55 shanling sudo[1420]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 16:14:55 shanling sudo[1420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 16:14:55 shanling sudo[1428]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 18 16:14:55 shanling sudo[1420]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 16:14:55 shanling volumio[1147]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 16:14:55 shanling volumio[1147]: info: [1771427695579] CoreMusicLibrary::Adding element Last_100 Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 16:14:55 shanling volumio[1147]: info: [1771427695581] CoreMusicLibrary::Adding element Webradio Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 16:14:55 shanling volumio[1147]: info: Initializing BBC Radios Feb 18 16:14:55 shanling volumio5-onboarding[1414]: time=2026-02-18T16:14:55.756+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 16:14:55 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 16:14:55 shanling volumio[1338]: Starting albumart workers Feb 18 16:14:56 shanling volumio[1147]: info: Creating Spotify config file Feb 18 16:14:56 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:56 shanling volumio[1147]: info: Volumio Calling Home Feb 18 16:14:56 shanling volumio[1340]: Starting albumart workers Feb 18 16:14:57 shanling volumio[1341]: Starting albumart workers Feb 18 16:14:58 shanling sudo[1474]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 16:14:58 shanling sudo[1474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:58 shanling sudo[1472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 16:14:58 shanling volumio[1147]: info: MPD Permissions set Feb 18 16:14:58 shanling volumio[1147]: info: MPD Permissions set Feb 18 16:14:58 shanling sudo[1472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:58 shanling sudo[1472]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:58 shanling volumio[1147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 18 16:14:58 shanling volumio[1147]: info: Spotify config file written Feb 18 16:14:58 shanling sudo[1474]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:58 shanling sudo[1483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 16:14:58 shanling sudo[1483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:14:59 shanling systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 16:14:59 shanling systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 18 16:14:59 shanling systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 18 16:14:59 shanling go-librespot[1485]: go-librespot daemon starting... Feb 18 16:14:59 shanling sudo[1483]: pam_unix(sudo:session): session closed for user root Feb 18 16:14:59 shanling volumio[1147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 18 16:14:59 shanling volumio[1147]: info: Received Get System Info Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 16:14:59 shanling volumio[1147]: info: Discovery: Getting this device information Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 18 16:14:59 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 16:14:59 shanling volumio5-onboarding[1414]: time=2026-02-18T16:14:59.439+01:00 level=INFO msg="system info for 1e1345589b486d121008fa179079f321" deviceName=SHANLING deviceVariant=volumio deviceModel= softwareVersion=4.096 Feb 18 16:14:59 shanling volumio[1147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 18 16:14:59 shanling volumio[1147]: info: Volumio called home Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling go-librespot[1486]: time="2026-02-18T16:14:59+01:00" level=info msg="running go-librespot 0.6.2" Feb 18 16:14:59 shanling go-librespot[1486]: time="2026-02-18T16:14:59+01:00" level=debug msg="app state loaded" Feb 18 16:14:59 shanling go-librespot[1486]: time="2026-02-18T16:14:59+01:00" level=debug msg="stored credentials not found" Feb 18 16:14:59 shanling go-librespot[1486]: time="2026-02-18T16:14:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:14:59 shanling volumio[1147]: info: No need to fix Spotify hosts Feb 18 16:14:59 shanling mpd[1429]: 2026-02-18T16:14:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 16:15:00 shanling volumio[1147]: info: Received Get System Info Feb 18 16:15:00 shanling go-librespot[1486]: time="2026-02-18T16:15:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 18 16:15:00 shanling go-librespot[1486]: time="2026-02-18T16:15:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 18 16:15:00 shanling go-librespot[1486]: time="2026-02-18T16:15:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 16:15:00 shanling volumio[1147]: info: Discovery: Getting this device information Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 18 16:15:00 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:15:00 shanling go-librespot[1486]: time="2026-02-18T16:15:00+01:00" level=info msg="zeroconf server listening on port 33201" Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 16:15:00 shanling volumio5-onboarding[1414]: time=2026-02-18T16:15:00.677+01:00 level=INFO msg="enabling local network discovery" Feb 18 16:15:00 shanling volumio5-onboarding[1414]: time=2026-02-18T16:15:00.724+01:00 level=INFO msg="bootstrapping state" hasInternet=true Feb 18 16:15:00 shanling systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 16:15:00 shanling sudo[1407]: pam_unix(sudo:session): session closed for user root Feb 18 16:15:00 shanling sudo[1522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 16:15:00 shanling sudo[1522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:15:00 shanling sudo[1394]: pam_unix(sudo:session): session closed for user root Feb 18 16:15:00 shanling volumio[1147]: info: Starting Shairport Sync Feb 18 16:15:00 shanling volumio[1147]: info: Starting Shairport Sync Feb 18 16:15:00 shanling volumio[1147]: info: Starting Shairport Sync Feb 18 16:15:00 shanling volumio[1147]: info: Received Get System Info Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 16:15:00 shanling volumio[1147]: info: Discovery: Getting this device information Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 18 16:15:00 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:15:00 shanling sudo[1529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 16:15:00 shanling sudo[1529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:15:00 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 16:15:00 shanling volumio-remote-updater[695]: Test mode disabled Feb 18 16:15:00 shanling volumio-remote-updater[695]: Alpha mode disabled Feb 18 16:15:00 shanling volumio-remote-updater[695]: Alpha legacy test mode disabled Feb 18 16:15:00 shanling sudo[1528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 16:15:00 shanling sudo[1528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:15:00 shanling sudo[1531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 16:15:00 shanling sudo[1531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 16:15:00 shanling systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 16:15:00 shanling systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 16:15:00 shanling systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 16:15:00 shanling systemd[1]: shairport-sync.service: Consumed 1.633s CPU time. Feb 18 16:15:01 shanling volumio[1147]: info: Completed starting Core Plugins Feb 18 16:15:01 shanling volumio[1147]: info: ------------------------------------------- Feb 18 16:15:01 shanling volumio[1147]: info: ----- MyVolumio plugins startup ---- Feb 18 16:15:01 shanling volumio[1147]: info: ------------------------------------------- Feb 18 16:15:01 shanling volumio[1147]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 16:15:01 shanling systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 16:15:01 shanling sudo[1528]: pam_unix(sudo:session): session closed for user root Feb 18 16:15:01 shanling sudo[1529]: pam_unix(sudo:session): session closed for user root Feb 18 16:15:01 shanling sudo[1531]: pam_unix(sudo:session): session closed for user root Feb 18 16:15:01 shanling volumio[1147]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 18 16:15:01 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 18 16:15:01 shanling volumio[1147]: info: MPD running with PID1429 Feb 18 16:15:01 shanling volumio[1147]: ,establishing connection Feb 18 16:15:01 shanling volumio[1147]: info: Shairport-Sync Started Feb 18 16:15:01 shanling volumio[1147]: Error adding Membership: Error: addMembership EINVAL Feb 18 16:15:01 shanling volumio[1147]: info: Shairport-Sync Started Feb 18 16:15:01 shanling volumio[1147]: info: Shairport-Sync Started Feb 18 16:15:01 shanling volumio[1147]: error: MPD error: The expression evaluated to a falsy value: Feb 18 16:15:01 shanling volumio[1147]: assert.ok(self.idling) Feb 18 16:15:01 shanling volumio[1147]: error: The expression evaluated to a falsy value: Feb 18 16:15:01 shanling volumio[1147]: assert.ok(self.idling) Feb 18 16:15:01 shanling volumio[1147]: error: MPD error: The expression evaluated to a falsy value: Feb 18 16:15:01 shanling volumio[1147]: assert.ok(self.idling) Feb 18 16:15:01 shanling volumio[1147]: error: The expression evaluated to a falsy value: Feb 18 16:15:01 shanling volumio[1147]: assert.ok(self.idling) Feb 18 16:15:01 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 16:15:01 shanling volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 18 16:15:01 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:15:01 shanling volumio[1147]: error: updateQueue error: null Feb 18 16:15:01 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 16:15:01 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 16:15:02 shanling volumio[1147]: verbose: New Socket.io Connection to 192.168.0.26:3000 from 192.168.0.28 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 18 16:15:03 shanling volumio[1147]: info: go-librespot daemon successfully initialized Feb 18 16:15:03 shanling systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 18 16:15:05 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 18 16:15:06 shanling systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 18 16:15:06 shanling systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 16:15:06 shanling systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 16:15:06 shanling sudo[1522]: pam_unix(sudo:session): session closed for user root Feb 18 16:15:06 shanling systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 18 16:15:06 shanling volumio[1147]: info: Upmpdcli Daemon Started Feb 18 16:15:06 shanling volumio[1147]: info: Discovery: Connected to remote: 192.168.0.28 Feb 18 16:15:06 shanling volumio[1147]: info: Initializing connection to go-librespot Websocket Feb 18 16:15:06 shanling volumio[1147]: info: CoreCommandRouter::volumioGetState Feb 18 16:15:06 shanling volumio[1147]: info: CorePlayQueue::getTrack 0 Feb 18 16:15:06 shanling go-librespot[1486]: time="2026-02-18T16:15:06+01:00" level=debug msg="new websocket client" Feb 18 16:15:06 shanling volumio[1147]: info: Connection to go-librespot Websocket established Feb 18 16:15:08 shanling volumio[1147]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 16:15:10 shanling volumio[1147]: info: Getting Spotify volume Feb 18 16:15:20 shanling systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 18 16:15:21 shanling systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 18 16:15:21 shanling systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 18 16:15:22 shanling volumio[1147]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 18 16:15:22 shanling volumio[1147]: error: MyVolumio Plugin failed to start in a timely fashion Feb 18 16:15:22 shanling volumio[1147]: [Metrics] CommandRouter: 40s 9.77ms Feb 18 16:15:22 shanling volumio[1147]: info: CoreCommandRouter::volumiosetStartupVolume Feb 18 16:15:22 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 16:15:22 shanling volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 16:15:22 shanling volumio[1147]: info: CoreCommandRouter::Close All Modals sent Feb 18 16:15:22 shanling volumio[1147]: info: CoreCommandRouter::Close All Modals sent Feb 18 16:15:22 shanling volumio[1147]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 16:15:22 shanling volumio[1147]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Feb 18 16:15:22 shanling volumio[1147]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Feb 18 16:15:22 shanling volumio[1147]: at new NodeError (node:internal/errors:405:5) Feb 18 16:15:22 shanling volumio[1147]: at assert (node:internal/assert:14:11) Feb 18 16:15:22 shanling volumio[1147]: at internalConnectMultiple (node:net:1118:3) Feb 18 16:15:22 shanling volumio[1147]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Feb 18 16:15:22 shanling volumio[1147]: at listOnTimeout (node:internal/timers:575:11) Feb 18 16:15:22 shanling volumio[1147]: at process.processTimers (node:internal/timers:514:7) { Feb 18 16:15:22 shanling volumio[1147]: code: 'ERR_INTERNAL_ASSERTION' Feb 18 16:15:22 shanling volumio[1147]: } Feb 18 16:15:22 shanling volumio[1147]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 16:15:26 shanling sudo[1638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 16:14' Feb 18 16:15:26 shanling sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"