Jan 26 13:05:32 test winbindd[1100]: [2026/01/26 13:05:32.014501, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Jan 26 13:05:32 test winbindd[1100]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 26 13:05:32 test systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 26 13:05:32 test systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 26 13:05:32 test systemd[1]: setdatetime-helper.service: Consumed 1.528s CPU time. Jan 26 13:05:32 test systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jan 26 13:05:32 test systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jan 26 13:05:32 test systemd[1]: Started winbind.service - Samba Winbind Daemon. Jan 26 13:05:32 test systemd[1]: Starting smbd.service - Samba SMB Daemon... Jan 26 13:05:32 test systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jan 26 13:05:32 test systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jan 26 13:05:32 test systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jan 26 13:05:32 test ntpd[893]: IO: Listen normally on 3 eth0 192.168.0.165:123 Jan 26 13:05:32 test ntpd[893]: IO: new interface(s) found: waking up resolver Jan 26 13:05:32 test ntpd[893]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 26 13:05:32 test ntpd[893]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jan 26 13:05:32 test ntpd[893]: DNS: Pool taking: 162.159.200.123 Jan 26 13:05:32 test ntpd[893]: DNS: Pool taking: 152.53.119.76 Jan 26 13:05:32 test ntpd[893]: DNS: Pool taking: 152.53.44.250 Jan 26 13:05:32 test ntpd[893]: DNS: Pool taking: 185.144.161.170 Jan 26 13:05:32 test ntpd[893]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jan 26 13:05:32 test sudo[1035]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:32 test wireless.js[683]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE Jan 26 13:05:32 test smbd[1154]: [2026/01/26 13:05:32.817789, 0] ../../source3/smbd/server.c:1741(main) Jan 26 13:05:32 test smbd[1154]: smbd version 4.17.8-Raspbian started. Jan 26 13:05:32 test smbd[1154]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jan 26 13:05:32 test sudo[1161]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jan 26 13:05:32 test sudo[1161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:05:32 test sudo[1161]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:32 test wireless.js[683]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE Jan 26 13:05:32 test wireless.js[683]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Jan 26 13:05:32 test wireless.js[683]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Jan 26 13:05:32 test wireless.js[683]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Jan 26 13:05:32 test wireless.js[683]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Jan 26 13:05:33 test sudo[1173]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 26 13:05:33 test sudo[1173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 13:05:33 test sudo[1173]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:33 test sudo[1176]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 26 13:05:33 test sudo[1176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 13:05:33 test sudo[1176]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:33 test wpa_supplicant[1179]: Successfully initialized wpa_supplicant Jan 26 13:05:33 test ntpd[893]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 26 13:05:33 test ntpd[893]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jan 26 13:05:33 test ntpd[893]: DNS: Pool taking: 83.216.46.6 Jan 26 13:05:33 test ntpd[893]: DNS: Pool taking: 91.206.8.34 Jan 26 13:05:33 test ntpd[893]: DNS: Pool taking: 91.206.8.70 Jan 26 13:05:33 test ntpd[893]: DNS: Pool taking: 152.53.132.244 Jan 26 13:05:33 test ntpd[893]: DNS: Pool taking: 2606:4700:f1::1 Jan 26 13:05:33 test ntpd[893]: DNS: Pool taking: 2a0a:4cc0:80:23c2:48e4:2bff:fe2f:f56b Jan 26 13:05:33 test ntpd[893]: DNS: Pool taking: 2001:67c:1b70:80::80:29 Jan 26 13:05:33 test ntpd[893]: DNS: Pool taking: 2a02:1748:0:1500:1::8002 Jan 26 13:05:33 test ntpd[893]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jan 26 13:05:33 test wpa_supplicant[1179]: nl80211: kernel reports: Registration to specific type not supported Jan 26 13:05:33 test wireless.js[683]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 76029725ms Jan 26 13:05:33 test wireless.js[683]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Jan 26 13:05:33 test wireless.js[683]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Jan 26 13:05:33 test wireless.js[683]: WIRELESS.JS - INFO: Notified systemd about wireless ready Jan 26 13:05:33 test kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 26 13:05:33 test systemd[1]: Started wireless.service - Wireless Services. Jan 26 13:05:34 test systemd[1]: Started volumio.service - Volumio Backend Module. Jan 26 13:05:34 test systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Jan 26 13:05:34 test systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jan 26 13:05:34 test systemd[1]: Received SIGRTMIN+21 from PID 284 (plymouthd). Jan 26 13:05:34 test systemd[1]: Started smbd.service - Samba SMB Daemon. Jan 26 13:05:34 test systemd[1]: Received SIGRTMIN+21 from PID 284 (plymouthd). Jan 26 13:05:34 test systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Jan 26 13:05:34 test systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Jan 26 13:05:34 test systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 26 13:05:34 test systemd[1]: Reached target getty.target - Login Prompts. Jan 26 13:05:34 test systemd[1]: Reached target multi-user.target - Multi-User System. Jan 26 13:05:34 test systemd[1]: Reached target graphical.target - Graphical Interface. Jan 26 13:05:34 test volumio-cpu-tweak[1208]: Setting RT Priority for mpd Jan 26 13:05:34 test systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jan 26 13:05:34 test volumio-cpu-tweak[1223]: pid 35's current scheduling policy: SCHED_OTHER Jan 26 13:05:34 test volumio-cpu-tweak[1223]: pid 35's current scheduling priority: 0 Jan 26 13:05:34 test volumio-cpu-tweak[1208]: Setting MPD Affinity Jan 26 13:05:34 test volumio-cpu-tweak[1225]: pid 3's current affinity mask: f Jan 26 13:05:34 test volumio-cpu-tweak[1208]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 26 13:05:34 test volumio-cpu-tweak[1208]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 26 13:05:34 test systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jan 26 13:05:34 test systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jan 26 13:05:34 test systemd[1]: Startup finished in 15.377s (kernel) + 15.780s (userspace) = 31.158s. Jan 26 13:05:34 test systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jan 26 13:05:34 test ntpd[893]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 26 13:05:34 test ntpd[893]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jan 26 13:05:34 test ntpd[893]: DNS: Pool taking: 178.251.64.52 Jan 26 13:05:34 test ntpd[893]: DNS: Pool taking: 81.16.47.3 Jan 26 13:05:34 test ntpd[893]: DNS: Pool taking: 151.236.30.71 Jan 26 13:05:34 test ntpd[893]: DNS: Pool skipping: 162.159.200.123 Jan 26 13:05:34 test ntpd[893]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jan 26 13:05:35 test ntpd[893]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 26 13:05:35 test ntpd[893]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jan 26 13:05:35 test ntpd[893]: DNS: Pool taking: 62.40.143.228 Jan 26 13:05:35 test ntpd[893]: DNS: Pool taking: 37.252.189.117 Jan 26 13:05:35 test ntpd[893]: DNS: Pool taking: 91.206.237.2 Jan 26 13:05:35 test ntpd[893]: DNS: Pool taking: 152.53.15.127 Jan 26 13:05:35 test ntpd[893]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jan 26 13:05:37 test volumio-remote-updater[679]: [2026-01-26 13:05:37] [info] asio async_connect error: asio.system:111 (Connection refused) Jan 26 13:05:37 test volumio-remote-updater[679]: [2026-01-26 13:05:37] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jan 26 13:05:37 test volumio-remote-updater[679]: [2026-01-26 13:05:37] [error] handle_connect error: Connection refused Jan 26 13:05:39 test ntpd[893]: CLOCK: time stepped by 0.579127 Jan 26 13:05:39 test ntpd[893]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 26 13:05:39 test volumio[1205]: info: ------------------------------------------- Jan 26 13:05:39 test volumio[1205]: info: ----- Volumio3 ---- Jan 26 13:05:39 test volumio[1205]: info: ------------------------------------------- Jan 26 13:05:39 test volumio[1205]: info: ----- System startup ---- Jan 26 13:05:39 test volumio[1205]: info: ------------------------------------------- Jan 26 13:05:40 test systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Jan 26 13:05:40 test systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 13:05:40 test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 13:05:40 test upmpdcli[1238]: Could not open config: /tmp/upmpdcli.conf Jan 26 13:05:40 test systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 13:05:40 test systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 13:05:41 test volumio[1205]: info: MYVOLUMIO Environment detected Jan 26 13:05:41 test volumio[1205]: info: Plugin folders cleanup Jan 26 13:05:41 test volumio[1205]: info: Scanning into folder /volumio/app/plugins/ Jan 26 13:05:41 test volumio[1205]: info: Scanning category audio_interface Jan 26 13:05:41 test volumio[1205]: info: Scanning category miscellanea Jan 26 13:05:41 test volumio[1205]: info: Scanning category music_service Jan 26 13:05:41 test volumio[1205]: info: Scanning category plugins.json Jan 26 13:05:41 test volumio[1205]: info: Scanning category system_controller Jan 26 13:05:41 test volumio[1205]: info: Scanning category user_interface Jan 26 13:05:41 test volumio[1205]: info: Scanning into folder /data/plugins/ Jan 26 13:05:41 test volumio[1205]: info: Scanning category audio_interface Jan 26 13:05:41 test volumio[1205]: info: Scanning category music_service Jan 26 13:05:41 test volumio[1205]: info: Plugin folders cleanup completed Jan 26 13:05:41 test volumio[1205]: info: ------------------------------------------- Jan 26 13:05:41 test volumio[1205]: info: ----- Core plugins startup ---- Jan 26 13:05:41 test volumio[1205]: info: ------------------------------------------- Jan 26 13:05:41 test volumio[1205]: info: Loading plugins from folder /volumio/app/plugins/ Jan 26 13:05:41 test volumio[1205]: info: Adding plugin upnp to MyMusic Plugins Jan 26 13:05:41 test volumio[1205]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 26 13:05:41 test volumio[1205]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 26 13:05:41 test volumio[1205]: info: Loading plugins from folder /data/plugins/ Jan 26 13:05:41 test volumio[1205]: info: Loading plugin "system"... Jan 26 13:05:41 test volumio[1205]: info: Loading plugin "appearance"... Jan 26 13:05:44 test volumio[1205]: info: Loading plugin "network"... Jan 26 13:05:44 test volumio[1205]: info: Refreshing Cached IP Addresses Jan 26 13:05:44 test sudo[1246]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 13:05:44 test sudo[1246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:05:44 test sudo[1246]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:44 test volumio[1205]: info: Loading plugin "services"... Jan 26 13:05:44 test volumio[1205]: info: Loading plugin "alsa_controller"... Jan 26 13:05:44 test sudo[1249]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:05:44 test sudo[1249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:05:44 test sudo[1249]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:44 test sudo[1255]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 26 13:05:44 test sudo[1255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:05:44 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 13:05:44 test volumio[1205]: info: Loading plugin "wizard"... Jan 26 13:05:44 test volumio[1205]: info: Loading plugin "networkfs"... Jan 26 13:05:44 test volumio[1205]: info: Starting Udev Watcher for removable devices Jan 26 13:05:44 test sudo[1282]: 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 Jan 26 13:05:44 test sudo[1282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:05:44 test volumio[1205]: info: Ignoring mount for partition: boot Jan 26 13:05:44 test volumio[1205]: info: Ignoring mount for partition: volumio Jan 26 13:05:44 test volumio[1205]: info: Ignoring mount for partition: volumio_data Jan 26 13:05:44 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 13:05:44 test volumio[1205]: info: Loading plugin "volumio_command_line_client"... Jan 26 13:05:44 test volumio[1205]: info: Loading plugin "upnp"... Jan 26 13:05:44 test volumio[1205]: info: [1769429144629] Starting Upmpd Daemon Jan 26 13:05:44 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 13:05:44 test volumio[1205]: info: Loading plugin "my_music"... Jan 26 13:05:44 test volumio[1205]: info: Loading plugin "mpd"... Jan 26 13:05:44 test kernel: netfs: FS-Cache loaded Jan 26 13:05:44 test kernel: Key type cifs.spnego registered Jan 26 13:05:44 test kernel: Key type cifs.idmap registered Jan 26 13:05:44 test 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. Jan 26 13:05:44 test kernel: CIFS: Attempting to mount //192.168.0.43/18tera-musik Jan 26 13:05:45 test volumio[1205]: info: Loading plugin "upnp_browser"... Jan 26 13:05:46 test sudo[1255]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:47 test volumio[1205]: info: Starting UPNP Browser Jan 26 13:05:47 test volumio[1205]: info: Loading plugin "alarm-clock"... Jan 26 13:05:47 test volumio[1205]: info: Loading plugin "airplay_emulation"... Jan 26 13:05:47 test volumio[1205]: info: Starting Shairport Sync Jan 26 13:05:47 test volumio[1205]: info: Loading plugin "last_100"... Jan 26 13:05:47 test volumio[1205]: info: Loading plugin "webradio"... Jan 26 13:05:47 test volumio-remote-updater[679]: [2026-01-26 13:05:47] [connect] Successful connection Jan 26 13:05:47 test volumio[1205]: info: Loading plugin "i2s_dacs"... Jan 26 13:05:48 test volumio[1205]: info: I2S DAC not set, start Auto-detection Jan 26 13:05:48 test volumio[1205]: info: Loading plugin "volumiodiscovery"... Jan 26 13:05:48 test volumio[1205]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 13:05:48 test volumio[1205]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 13:05:48 test volumio[1205]: *** WARNING *** For more information see Jan 26 13:05:48 test volumio[1205]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 13:05:48 test volumio[1205]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 13:05:48 test volumio[1205]: *** WARNING *** For more information see Jan 26 13:05:48 test node[1205]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 13:05:48 test node[1205]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 13:05:48 test node[1205]: *** WARNING *** For more information see Jan 26 13:05:48 test node[1205]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 13:05:48 test node[1205]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 13:05:48 test node[1205]: *** WARNING *** For more information see Jan 26 13:05:48 test volumio[1205]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 13:05:48 test volumio[1205]: info: Discovery: Started advertising with name: TEST Jan 26 13:05:48 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 13:05:48 test volumio[1205]: info: Loading plugin "spop"... Jan 26 13:05:51 test volumio[1205]: info: Loading plugin "outputs"... Jan 26 13:05:51 test volumio[1205]: info: Loading plugin "albumart"... Jan 26 13:05:51 test kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 26 13:05:51 test kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 26 13:05:51 test sudo[1282]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:51 test volumio[1205]: info: Plugin example_plugin is not enabled Jan 26 13:05:51 test volumio[1205]: info: Loading plugin "inputs"... Jan 26 13:05:51 test volumio[1205]: info: Loading plugin "updater_comm"... Jan 26 13:05:51 test volumio[1205]: info: Plugin mpdemulation is not enabled Jan 26 13:05:51 test volumio[1205]: info: Loading plugin "rest_api"... Jan 26 13:05:51 test volumio[1205]: info: Loading plugin "websocket"... Jan 26 13:05:51 test volumio[1205]: info: Starting Socket.io Server version 1.7.4 Jan 26 13:05:51 test volumio[1205]: info: Plugin fusiondsp is not enabled Jan 26 13:05:51 test volumio[1205]: info: Loading i18n strings for locale de Jan 26 13:05:51 test volumio[1205]: Updating browse sources language Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::initPlayerControls Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 13:05:51 test volumio[1205]: Express server listening on port 3000 Jan 26 13:05:51 test volumio[1205]: [Metrics] WebUI: 13s 533.92ms Jan 26 13:05:51 test volumio[1205]: info: CoreStateMachine::resetVolumioState Jan 26 13:05:51 test volumio[1205]: info: CoreStateMachine::getcurrentVolume Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 13:05:51 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:05:51 test volumio[1205]: info: Volumio Network Manager: Network status updated: 1 Jan 26 13:05:51 test volumio[1205]: info: Cannot mount NAS Laptop_Musik at system boot, trial number 1 ,retrying in 5 seconds Jan 26 13:05:52 test volumio-remote-updater[679]: [2026-01-26 13:05:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1769429147 101 Jan 26 13:05:52 test volumio[1205]: 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 Jan 26 13:05:52 test systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 26 13:05:52 test volumio[1205]: verbose: New Socket.io Connection to 192.168.0.165:3000 from 192.168.0.27 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 26 13:05:52 test volumio[1205]: info: Reloading queue from file Jan 26 13:05:52 test volumio[1205]: info: VolumeController:: Volume=100 Mute =false Jan 26 13:05:52 test volumio[1205]: info: CoreStateMachine::pushState Jan 26 13:05:52 test volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 26 13:05:52 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 13:05:52 test volumio[1205]: info: CoreCommandRouter::volumioPushState Jan 26 13:05:52 test volumio[1205]: info: CoreStateMachine::updateTrackBlock Jan 26 13:05:52 test volumio[1205]: info: CorePlayQueue::getTrackBlock Jan 26 13:05:52 test volumio[1205]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 13:05:52 test volumio[1205]: info: CoreStateMachine::setRepeat null single undefined Jan 26 13:05:52 test volumio[1205]: info: CoreStateMachine::pushState Jan 26 13:05:52 test volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 26 13:05:52 test volumio[1205]: info: CoreCommandRouter::volumioPushState Jan 26 13:05:52 test volumio[1205]: info: CoreStateMachine::setRandom null Jan 26 13:05:52 test volumio[1205]: info: CoreStateMachine::pushState Jan 26 13:05:52 test volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 26 13:05:52 test volumio[1205]: info: CoreCommandRouter::volumioPushState Jan 26 13:05:52 test volumio[1205]: info: Setting Device type: Raspberry PI Jan 26 13:05:52 test volumio[1205]: info: Discovery: adding 9b43dc4c-8727-4063-afe1-da0ea25dac46 Jan 26 13:05:52 test volumio[1205]: info: Discovery: Found device MARANTZ Jan 26 13:05:52 test volumio[1205]: info: Discovery: Connecting to remote: 192.168.0.27 Jan 26 13:05:52 test volumio[1299]: Forking 3 albumart workers Jan 26 13:05:52 test volumio[1205]: info: Discovery: adding 33ba4d37-ab1b-49a8-a740-38a7d4ed1c59 Jan 26 13:05:52 test volumio[1205]: info: Discovery: Found device TEST Jan 26 13:05:52 test volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 26 13:05:52 test volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 26 13:05:52 test volumio[1205]: info: VolumeController:: Volume=100 Mute =false Jan 26 13:05:52 test volumio[1205]: info: CoreStateMachine::pushState Jan 26 13:05:52 test volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 26 13:05:52 test volumio[1205]: info: CoreCommandRouter::volumioPushState Jan 26 13:05:52 test volumio[1205]: info: Discovery: this is already registered, 33ba4d37-ab1b-49a8-a740-38a7d4ed1c59 Jan 26 13:05:52 test volumio[1205]: info: Discovery: Found device TEST Jan 26 13:05:52 test volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 26 13:05:52 test volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 26 13:05:53 test volumio[1205]: info: Completed loading Core Plugins Jan 26 13:05:53 test volumio[1205]: info: Preparing to generate the ALSA configuration file Jan 26 13:05:53 test volumio[1205]: info: Asound.conf file unchanged, so no further update is needed Jan 26 13:05:53 test volumio[1205]: info: Output device has changed, restarting MPD Jan 26 13:05:53 test volumio[1205]: info: Output device has changed, restarting Shairport Sync Jan 26 13:05:53 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:53 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:05:53 test sudo[1356]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 13:05:53 test sudo[1354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 13:05:53 test sudo[1354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:05:53 test sudo[1354]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:53 test sudo[1356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:05:53 test volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 13:05:53 test volumio[1205]: info: ___________ START PLUGINS ___________ Jan 26 13:05:53 test volumio[1205]: info: ControllerMpd::onStart: Initializing MPD Jan 26 13:05:53 test volumio[1205]: info: Creating MPD Configuration file Jan 26 13:05:53 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 13:05:53 test volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:05:53 test volumio[1205]: info: [1769429153762] CoreMusicLibrary::Adding element Medienserver Jan 26 13:05:53 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:05:53 test sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 13:05:53 test sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:05:53 test systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 13:05:53 test systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 13:05:53 test sudo[1367]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:53 test volumio[1205]: info: UPNP Browser: Client initialized successfully Jan 26 13:05:53 test sudo[1370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 13:05:53 test sudo[1370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:05:54 test sudo[1371]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 13:05:54 test sudo[1371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 13:05:54 test sudo[1374]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 26 13:05:54 test sudo[1371]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:54 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:54 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:05:54 test systemd[1]: mpd.service: Deactivated successfully. Jan 26 13:05:54 test systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 26 13:05:54 test systemd[1]: mpd.socket: Deactivated successfully. Jan 26 13:05:54 test systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 26 13:05:54 test systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 26 13:05:54 test volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 13:05:54 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:54 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:05:54 test systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 26 13:05:54 test systemd[1]: Starting mpd.service - Music Player Daemon... Jan 26 13:05:54 test sudo[1389]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 13:05:54 test sudo[1389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 26 13:05:54 test sudo[1399]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 26 13:05:54 test sudo[1389]: pam_unix(sudo:session): session closed for user root Jan 26 13:05:54 test volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 13:05:54 test volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:05:54 test volumio[1205]: info: [1769429154636] CoreMusicLibrary::Adding element Last_100 Jan 26 13:05:54 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:05:54 test volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 13:05:54 test volumio[1205]: info: [1769429154640] CoreMusicLibrary::Adding element Webradio Jan 26 13:05:54 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 13:05:54 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 13:05:54 test volumio[1205]: info: Initializing BBC Radios Jan 26 13:05:55 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 13:05:55 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:05:55 test volumio[1205]: info: Creating Spotify config file Jan 26 13:05:55 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:05:55 test volumio[1205]: info: Volumio Calling Home Jan 26 13:05:55 test systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 26 13:05:55 test systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 13:05:56 test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 13:05:56 test upmpdcli[1417]: Could not open config: /tmp/upmpdcli.conf Jan 26 13:05:56 test systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 13:05:56 test systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 13:05:57 test systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 26 13:06:01 test sudo[1425]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 13:06:01 test sudo[1427]: 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 Jan 26 13:06:01 test sudo[1427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:06:01 test kernel: CIFS: Attempting to mount //192.168.0.43/18tera-musik Jan 26 13:06:01 test sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 13:06:01 test sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:06:01 test sudo[1425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:06:01 test sudo[1425]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:01 test sudo[1423]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:01 test volumio[1205]: info: MPD Permissions set Jan 26 13:06:01 test volumio[1327]: Starting albumart workers Jan 26 13:06:01 test volumio[1205]: info: MPD Permissions set Jan 26 13:06:01 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 26 13:06:01 test mpd[1400]: 2026-01-26T13:06:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 13:06:02 test volumio[1205]: info: Volumio called home Jan 26 13:06:02 test volumio[1205]: info: Spotify config file written Jan 26 13:06:02 test sudo[1438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 26 13:06:02 test sudo[1438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:06:02 test 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. Jan 26 13:06:02 test 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. Jan 26 13:06:02 test volumio[1328]: Starting albumart workers Jan 26 13:06:02 test systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 26 13:06:02 test go-librespot[1440]: go-librespot daemon starting... Jan 26 13:06:02 test sudo[1438]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:03 test systemd[1]: Started mpd.service - Music Player Daemon. Jan 26 13:06:03 test sudo[1356]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:03 test sudo[1370]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test go-librespot[1441]: time="2026-01-26T13:06:03+01:00" level=info msg="running go-librespot 0.6.2" Jan 26 13:06:03 test go-librespot[1441]: time="2026-01-26T13:06:03+01:00" level=debug msg="app state loaded" Jan 26 13:06:03 test go-librespot[1441]: time="2026-01-26T13:06:03+01:00" level=debug msg="stored credentials not found" Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:06:03 test go-librespot[1441]: time="2026-01-26T13:06:03+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:03 test volumio[1205]: info: No need to fix Spotify hosts Jan 26 13:06:03 test volumio[1326]: Starting albumart workers Jan 26 13:06:04 test go-librespot[1441]: time="2026-01-26T13:06:04+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-gew1.spotify.com:80]" Jan 26 13:06:04 test go-librespot[1441]: time="2026-01-26T13:06:04+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 26 13:06:04 test go-librespot[1441]: time="2026-01-26T13:06:04+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 26 13:06:04 test go-librespot[1441]: time="2026-01-26T13:06:04+01:00" level=info msg="zeroconf server listening on port 41305" Jan 26 13:06:04 test volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 26 13:06:04 test volumio[1205]: info: Completed starting Core Plugins Jan 26 13:06:04 test volumio[1205]: info: ------------------------------------------- Jan 26 13:06:04 test volumio[1205]: info: ----- MyVolumio plugins startup ---- Jan 26 13:06:04 test volumio[1205]: info: ------------------------------------------- Jan 26 13:06:04 test volumio[1205]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 26 13:06:05 test volumio[1205]: error: MPD error: The expression evaluated to a falsy value: Jan 26 13:06:05 test volumio[1205]: assert.ok(self.idling) Jan 26 13:06:05 test volumio[1205]: error: The expression evaluated to a falsy value: Jan 26 13:06:05 test volumio[1205]: assert.ok(self.idling) Jan 26 13:06:05 test volumio[1205]: info: MPD running with PID1400 Jan 26 13:06:05 test volumio[1205]: ,establishing connection Jan 26 13:06:05 test volumio[1205]: info: Starting Shairport Sync Jan 26 13:06:05 test volumio[1205]: info: Starting Shairport Sync Jan 26 13:06:06 test volumio[1205]: info: Starting Shairport Sync Jan 26 13:06:06 test sudo[1480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 13:06:06 test volumio[1205]: error: MPD error: The expression evaluated to a falsy value: Jan 26 13:06:06 test volumio[1205]: assert.ok(self.idling) Jan 26 13:06:06 test volumio[1205]: error: The expression evaluated to a falsy value: Jan 26 13:06:06 test volumio[1205]: assert.ok(self.idling) Jan 26 13:06:06 test sudo[1480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:06:06 test sudo[1484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 13:06:06 test sudo[1486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 13:06:06 test sudo[1486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:06:06 test sudo[1482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 13:06:06 test sudo[1482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:06:06 test sudo[1484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:06:06 test volumio[1205]: info: Discovery: Connected to remote: 192.168.0.27 Jan 26 13:06:06 test systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 26 13:06:06 test systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 26 13:06:06 test systemd[1]: shairport-sync.service: Deactivated successfully. Jan 26 13:06:06 test systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 13:06:06 test systemd[1]: shairport-sync.service: Consumed 1.709s CPU time. Jan 26 13:06:06 test systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 26 13:06:06 test sudo[1480]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:06 test volumio[1205]: error: updateQueue error: null Jan 26 13:06:06 test sudo[1482]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:06 test sudo[1484]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:07 test volumio[1205]: info: Shairport-Sync Started Jan 26 13:06:07 test volumio[1205]: Error adding Membership: Error: addMembership EINVAL Jan 26 13:06:07 test volumio[1205]: info: Shairport-Sync Started Jan 26 13:06:07 test volumio[1205]: info: Shairport-Sync Started Jan 26 13:06:07 test volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 26 13:06:07 test volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 26 13:06:07 test volumio[1205]: info: go-librespot daemon successfully initialized Jan 26 13:06:07 test volumio[1205]: verbose: New Socket.io Connection to 192.168.0.165:3000 from 192.168.0.27 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 26 13:06:07 test kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 26 13:06:07 test kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 26 13:06:07 test sudo[1427]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:07 test volumio[1205]: info: Cannot mount NAS Laptop_Musik at system boot, trial number 2 ,retrying in 5 seconds Jan 26 13:06:08 test systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 26 13:06:08 test systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 26 13:06:10 test volumio[1205]: info: Discovery: Disconnected from remote: 192.168.0.27 Jan 26 13:06:10 test volumio[1205]: info: Initializing connection to go-librespot Websocket Jan 26 13:06:10 test go-librespot[1441]: time="2026-01-26T13:06:10+01:00" level=debug msg="new websocket client" Jan 26 13:06:10 test volumio[1205]: info: Connection to go-librespot Websocket established Jan 26 13:06:11 test systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 26 13:06:11 test systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 13:06:11 test systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 26 13:06:11 test sudo[1486]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:11 test volumio[1205]: info: Upmpdcli Daemon Started Jan 26 13:06:11 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 26 13:06:12 test volumio[1205]: info: Discovery: Connected to remote: 192.168.0.27 Jan 26 13:06:12 test volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 26 13:06:12 test volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 26 13:06:13 test volumio[1205]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 26 13:06:13 test sudo[1562]: 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 Jan 26 13:06:13 test sudo[1562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 13:06:13 test kernel: CIFS: Attempting to mount //192.168.0.43/18tera-musik Jan 26 13:06:17 test volumio[1205]: info: Getting Spotify volume Jan 26 13:06:19 test sudo[1562]: pam_unix(sudo:session): session closed for user root Jan 26 13:06:19 test kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 26 13:06:19 test kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jan 26 13:06:25 test volumio[1205]: info: Discovery: Disconnected from remote: 192.168.0.27 Jan 26 13:06:25 test volumio[1205]: info: Discovery: Disconnected from remote: 192.168.0.27 Jan 26 13:06:25 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 13:06:25 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:06:25 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 13:06:26 test volumio[1205]: error: MyVolumio Plugin failed to start in a timely fashion Jan 26 13:06:26 test volumio[1205]: [Metrics] CommandRouter: 46s 398.75ms Jan 26 13:06:26 test volumio[1205]: info: CoreCommandRouter::volumiosetStartupVolume Jan 26 13:06:26 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 13:06:26 test volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 13:06:26 test volumio[1205]: info: CoreCommandRouter::Close All Modals sent Jan 26 13:06:26 test volumio[1205]: info: CoreCommandRouter::Close All Modals sent Jan 26 13:06:26 test volumio[1205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 13:06:26 test volumio[1205]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Jan 26 13:06:26 test volumio[1205]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Jan 26 13:06:26 test volumio[1205]: at new NodeError (node:internal/errors:405:5) Jan 26 13:06:26 test volumio[1205]: at assert (node:internal/assert:14:11) Jan 26 13:06:26 test volumio[1205]: at internalConnectMultiple (node:net:1118:3) Jan 26 13:06:26 test volumio[1205]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Jan 26 13:06:26 test volumio[1205]: at listOnTimeout (node:internal/timers:575:11) Jan 26 13:06:26 test volumio[1205]: at process.processTimers (node:internal/timers:514:7) { Jan 26 13:06:26 test volumio[1205]: code: 'ERR_INTERNAL_ASSERTION' Jan 26 13:06:26 test volumio[1205]: } Jan 26 13:06:26 test volumio[1205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 13:06:31 test sudo[1613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 13:05' Jan 26 13:06:31 test sudo[1613]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"