May 28 08:56:06 anneks ntpd[852]: CLOCK: time stepped by 1897839.934818 May 28 08:56:06 anneks ntpd[852]: CLOCK: time changed from 2026-05-06 to 2026-05-28 May 28 08:56:06 anneks ntpd[852]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 28 08:56:06 anneks systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 28 08:56:06 anneks systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 28 08:56:06 anneks systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 28 08:56:06 anneks sudo[1156]: pam_unix(sudo:session): session closed for user root May 28 08:56:06 anneks systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 28 08:56:06 anneks systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 28 08:56:06 anneks wireless.js[677]: WIRELESS.JS - INFO: Cleaning previous... May 28 08:56:06 anneks sudo[1169]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 28 08:56:06 anneks sudo[1169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 08:56:06 anneks sudo[1169]: pam_unix(sudo:session): session closed for user root May 28 08:56:06 anneks kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 28 08:56:07 anneks wireless.js[677]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 28 08:56:07 anneks wireless.js[677]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 9ms May 28 08:56:07 anneks wireless.js[677]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: b8:27:eb:ab:0f:dd) May 28 08:56:07 anneks sudo[1177]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 28 08:56:07 anneks sudo[1177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:56:07 anneks sudo[1177]: pam_unix(sudo:session): session closed for user root May 28 08:56:07 anneks sudo[1185]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 28 08:56:07 anneks sudo[1185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:56:07 anneks winbindd[1109]: [2026/05/28 08:56:07.563851, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) May 28 08:56:07 anneks winbindd[1109]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'ANNEKS' failed 'NT_STATUS_IO_TIMEOUT' May 28 08:56:07 anneks systemd[1]: Started smbd.service - Samba SMB Daemon. May 28 08:56:07 anneks volumio-remote-updater[676]: [2026-05-28 08:56:07] [info] asio async_connect error: asio.system:111 (Connection refused) May 28 08:56:07 anneks volumio-remote-updater[676]: [2026-05-28 08:56:07] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 28 08:56:07 anneks volumio-remote-updater[676]: [2026-05-28 08:56:07] [error] handle_connect error: Connection refused May 28 08:56:10 anneks sudo[1185]: pam_unix(sudo:session): session closed for user root May 28 08:56:10 anneks wireless.js[677]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: NO May 28 08:56:10 anneks sudo[1199]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set NO May 28 08:56:10 anneks sudo[1199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:56:10 anneks sudo[1199]: pam_unix(sudo:session): session closed for user root May 28 08:56:10 anneks wireless.js[677]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: NO May 28 08:56:10 anneks wireless.js[677]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot May 28 08:56:10 anneks wireless.js[677]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability May 28 08:56:10 anneks wireless.js[677]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) May 28 08:56:10 anneks wireless.js[677]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active May 28 08:56:10 anneks sudo[1208]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 28 08:56:10 anneks sudo[1208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 08:56:10 anneks sudo[1208]: pam_unix(sudo:session): session closed for user root May 28 08:56:10 anneks sudo[1211]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 28 08:56:10 anneks sudo[1211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 08:56:10 anneks sudo[1211]: pam_unix(sudo:session): session closed for user root May 28 08:56:10 anneks wpa_supplicant[1214]: Successfully initialized wpa_supplicant May 28 08:56:11 anneks systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. May 28 08:56:11 anneks systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:56:11 anneks wpa_supplicant[1214]: nl80211: kernel reports: Registration to specific type not supported May 28 08:56:11 anneks systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:56:11 anneks wireless.js[677]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 1897846766ms May 28 08:56:11 anneks wireless.js[677]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable May 28 08:56:11 anneks upmpdcli[1220]: Could not open config: /tmp/upmpdcli.conf May 28 08:56:11 anneks systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 08:56:11 anneks systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 08:56:11 anneks wireless.js[677]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory May 28 08:56:12 anneks wireless.js[677]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 28 08:56:12 anneks kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 28 08:56:12 anneks systemd[1]: Started wireless.service - Wireless Services. May 28 08:56:12 anneks systemd[1]: Started volumio.service - Volumio Backend Module. May 28 08:56:12 anneks systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... May 28 08:56:12 anneks systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. May 28 08:56:12 anneks systemd[1]: setdatetime-helper.service: Deactivated successfully. May 28 08:56:12 anneks systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 28 08:56:12 anneks systemd[1]: setdatetime-helper.service: Consumed 4.190s CPU time. May 28 08:56:12 anneks systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. May 28 08:56:12 anneks systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. May 28 08:56:12 anneks systemd[1]: Received SIGRTMIN+21 from PID 293 (n/a). May 28 08:56:12 anneks systemd[1]: Received SIGRTMIN+21 from PID 293 (n/a). May 28 08:56:12 anneks systemd[1]: Started getty@tty1.service - Getty on tty1. May 28 08:56:12 anneks systemd[1]: Reached target getty.target - Login Prompts. May 28 08:56:12 anneks systemd[1]: Reached target multi-user.target - Multi-User System. May 28 08:56:12 anneks systemd[1]: Reached target graphical.target - Graphical Interface. May 28 08:56:12 anneks systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 28 08:56:12 anneks volumio-cpu-tweak[1246]: Setting RT Priority for mpd May 28 08:56:12 anneks volumio-cpu-tweak[1262]: pid 35's current scheduling policy: SCHED_OTHER May 28 08:56:12 anneks volumio-cpu-tweak[1262]: pid 35's current scheduling priority: 0 May 28 08:56:12 anneks volumio-cpu-tweak[1246]: Setting MPD Affinity May 28 08:56:12 anneks volumio-cpu-tweak[1263]: pid 3's current affinity mask: f May 28 08:56:12 anneks volumio-cpu-tweak[1246]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 28 08:56:12 anneks volumio-cpu-tweak[1246]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 28 08:56:12 anneks systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 28 08:56:12 anneks systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 28 08:56:12 anneks systemd[1]: Startup finished in 15.565s (kernel) + 24.853s (userspace) = 40.419s. May 28 08:56:12 anneks systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. May 28 08:56:17 anneks volumio-remote-updater[676]: [2026-05-28 08:56:17] [info] asio async_connect error: asio.system:111 (Connection refused) May 28 08:56:17 anneks volumio-remote-updater[676]: [2026-05-28 08:56:17] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 28 08:56:17 anneks volumio-remote-updater[676]: [2026-05-28 08:56:17] [error] handle_connect error: Connection refused May 28 08:56:19 anneks volumio[1244]: info: ------------------------------------------- May 28 08:56:19 anneks volumio[1244]: info: ----- Volumio3 ---- May 28 08:56:19 anneks volumio[1244]: info: ------------------------------------------- May 28 08:56:19 anneks volumio[1244]: info: ----- System startup ---- May 28 08:56:19 anneks volumio[1244]: info: ------------------------------------------- May 28 08:56:20 anneks systemd[1]: systemd-fsckd.service: Deactivated successfully. May 28 08:56:22 anneks volumio[1244]: info: MYVOLUMIO Environment detected May 28 08:56:22 anneks volumio[1244]: info: Plugin folders cleanup May 28 08:56:22 anneks volumio[1244]: info: Scanning into folder /volumio/app/plugins/ May 28 08:56:22 anneks volumio[1244]: info: Scanning category audio_interface May 28 08:56:22 anneks volumio[1244]: info: Scanning category miscellanea May 28 08:56:22 anneks volumio[1244]: info: Scanning category music_service May 28 08:56:22 anneks volumio[1244]: info: Scanning category plugins.json May 28 08:56:22 anneks volumio[1244]: info: Scanning category system_controller May 28 08:56:22 anneks volumio[1244]: info: Scanning category user_interface May 28 08:56:22 anneks volumio[1244]: info: Scanning into folder /data/plugins/ May 28 08:56:22 anneks volumio[1244]: info: Scanning category music_service May 28 08:56:23 anneks volumio[1244]: info: Scanning category system_controller May 28 08:56:23 anneks volumio[1244]: info: Scanning category user_interface May 28 08:56:23 anneks volumio[1244]: info: Plugin folders cleanup completed May 28 08:56:23 anneks volumio[1244]: info: ------------------------------------------- May 28 08:56:23 anneks volumio[1244]: info: ----- Core plugins startup ---- May 28 08:56:23 anneks volumio[1244]: info: ------------------------------------------- May 28 08:56:23 anneks volumio[1244]: info: Loading plugins from folder /volumio/app/plugins/ May 28 08:56:23 anneks volumio[1244]: info: Adding plugin upnp to MyMusic Plugins May 28 08:56:23 anneks volumio[1244]: info: Adding plugin airplay_emulation to MyMusic Plugins May 28 08:56:23 anneks volumio[1244]: info: Adding plugin upnp_browser to MyMusic Plugins May 28 08:56:23 anneks volumio[1244]: info: Loading plugins from folder /data/plugins/ May 28 08:56:23 anneks volumio[1244]: info: Loading plugin "system"... May 28 08:56:23 anneks volumio[1244]: info: Loading plugin "appearance"... May 28 08:56:26 anneks volumio[1244]: info: Loading plugin "network"... May 28 08:56:26 anneks volumio[1244]: info: Refreshing Cached IP Addresses May 28 08:56:26 anneks volumio[1244]: info: Loading plugin "services"... May 28 08:56:26 anneks volumio[1244]: info: Loading plugin "volumio5onboarding"... May 28 08:56:26 anneks systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 28 08:56:26 anneks sudo[1293]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 28 08:56:26 anneks sudo[1293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:56:26 anneks volumio[1244]: info: Loading plugin "alsa_controller"... May 28 08:56:26 anneks sudo[1283]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 08:56:26 anneks sudo[1283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:56:26 anneks systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... May 28 08:56:26 anneks systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:56:26 anneks sudo[1281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 08:56:26 anneks sudo[1281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:56:26 anneks systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:56:26 anneks sudo[1283]: pam_unix(sudo:session): session closed for user root May 28 08:56:26 anneks systemd[1]: e2scrub_all.service: Deactivated successfully. May 28 08:56:26 anneks systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. May 28 08:56:26 anneks sudo[1281]: pam_unix(sudo:session): session closed for user root May 28 08:56:27 anneks upmpdcli[1307]: Could not open config: /tmp/upmpdcli.conf May 28 08:56:27 anneks systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 08:56:27 anneks systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 08:56:27 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 08:56:27 anneks volumio[1244]: info: Loading plugin "wizard"... May 28 08:56:27 anneks volumio[1244]: info: Loading plugin "networkfs"... May 28 08:56:27 anneks volumio[1244]: info: Starting Udev Watcher for removable devices May 28 08:56:27 anneks sudo[1317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=reader,password=5oBMnYY9TkbFnhMfoDx8d5,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.86.108/music /mnt/NAS/Elrond May 28 08:56:27 anneks sudo[1317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:56:27 anneks volumio[1244]: info: Ignoring mount for partition: boot May 28 08:56:27 anneks volumio[1244]: info: Ignoring mount for partition: volumio May 28 08:56:27 anneks volumio[1244]: info: Ignoring mount for partition: volumio_data May 28 08:56:27 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 08:56:27 anneks volumio[1244]: info: Loading plugin "volumio_command_line_client"... May 28 08:56:27 anneks volumio[1244]: info: Loading plugin "upnp"... May 28 08:56:27 anneks volumio[1244]: info: [1779951387770] Starting Upmpd Daemon May 28 08:56:27 anneks volumio-remote-updater[676]: [2026-05-28 08:56:27] [connect] Successful connection May 28 08:56:27 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 08:56:27 anneks volumio[1244]: info: Loading plugin "my_music"... May 28 08:56:27 anneks volumio[1244]: info: Loading plugin "mpd"... May 28 08:56:27 anneks kernel: netfs: FS-Cache loaded May 28 08:56:28 anneks kernel: Key type cifs.spnego registered May 28 08:56:28 anneks kernel: Key type cifs.idmap registered May 28 08:56:28 anneks 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. May 28 08:56:28 anneks kernel: CIFS: Attempting to mount //192.168.86.108/music May 28 08:56:29 anneks volumio[1244]: info: Loading plugin "upnp_browser"... May 28 08:56:29 anneks sudo[1293]: pam_unix(sudo:session): session closed for user root May 28 08:56:31 anneks systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 28 08:56:31 anneks kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 28 08:56:31 anneks kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 28 08:56:31 anneks sudo[1317]: pam_unix(sudo:session): session closed for user root May 28 08:56:33 anneks volumio[1244]: info: Starting UPNP Browser May 28 08:56:33 anneks volumio[1244]: info: Loading plugin "alarm-clock"... May 28 08:56:34 anneks volumio[1244]: info: Loading plugin "airplay_emulation"... May 28 08:56:34 anneks volumio[1244]: info: Starting Shairport Sync May 28 08:56:34 anneks volumio[1244]: info: Loading plugin "last_100"... May 28 08:56:34 anneks volumio[1244]: info: Loading plugin "webradio"... May 28 08:56:34 anneks volumio[1244]: info: Loading plugin "i2s_dacs"... May 28 08:56:34 anneks volumio[1244]: info: Loading plugin "volumiodiscovery"... May 28 08:56:35 anneks volumio[1244]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 08:56:35 anneks node[1244]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 08:56:35 anneks volumio[1244]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 08:56:35 anneks volumio[1244]: *** WARNING *** For more information see May 28 08:56:35 anneks volumio[1244]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 08:56:35 anneks volumio[1244]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 08:56:35 anneks volumio[1244]: *** WARNING *** For more information see May 28 08:56:35 anneks node[1244]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 08:56:35 anneks node[1244]: *** WARNING *** For more information see May 28 08:56:35 anneks node[1244]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 08:56:35 anneks node[1244]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 08:56:35 anneks node[1244]: *** WARNING *** For more information see May 28 08:56:35 anneks systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 28 08:56:35 anneks volumio[1244]: info: Applying required configuration parameters for plugin volumiodiscovery May 28 08:56:35 anneks volumio[1244]: info: Discovery: Started advertising with name: Anneks May 28 08:56:35 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 08:56:35 anneks volumio[1244]: info: Loading plugin "rp2"... May 28 08:56:37 anneks systemd[1]: setdatetime-helper.service: Deactivated successfully. May 28 08:56:37 anneks systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 28 08:56:37 anneks systemd[1]: setdatetime-helper.service: Consumed 1.967s CPU time. May 28 08:56:42 anneks systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. May 28 08:56:42 anneks systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:56:42 anneks systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:56:42 anneks upmpdcli[1377]: Could not open config: /tmp/upmpdcli.conf May 28 08:56:42 anneks systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 08:56:42 anneks systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 08:56:42 anneks volumio-remote-updater[676]: [2026-05-28 08:56:42] [connect] Successful connection May 28 08:56:44 anneks volumio[1244]: info: Loading plugin "outputs"... May 28 08:56:44 anneks volumio[1244]: info: Loading plugin "albumart"... May 28 08:56:44 anneks volumio[1244]: info: Plugin example_plugin is not enabled May 28 08:56:44 anneks volumio[1244]: info: Loading plugin "inputs"... May 28 08:56:44 anneks volumio[1244]: info: Loading plugin "updater_comm"... May 28 08:56:44 anneks volumio[1244]: info: Plugin mpdemulation is not enabled May 28 08:56:44 anneks volumio[1244]: info: Loading plugin "rest_api"... May 28 08:56:45 anneks volumio[1244]: info: Loading plugin "websocket"... May 28 08:56:45 anneks volumio[1244]: info: Starting Socket.io Server version 1.7.4 May 28 08:56:45 anneks volumio[1244]: info: Loading plugin "radio_paradise"... May 28 08:56:46 anneks volumio[1244]: info: Applying required configuration parameters for plugin radio_paradise May 28 08:56:46 anneks volumio[1244]: info: [1779951406929] [RadioParadise] API delay: 1 May 28 08:56:46 anneks volumio[1244]: info: Loading plugin "backup_restore"... May 28 08:56:47 anneks volumio[1378]: Forking 3 albumart workers May 28 08:56:50 anneks volumio[1244]: info: Applying required configuration parameters for plugin backup_restore May 28 08:56:50 anneks volumio[1244]: info: Loading plugin "lastfm"... May 28 08:56:57 anneks systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. May 28 08:56:57 anneks systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:56:57 anneks systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:56:57 anneks upmpdcli[1436]: Could not open config: /tmp/upmpdcli.conf May 28 08:56:57 anneks systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 08:56:57 anneks systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 08:56:57 anneks volumio-remote-updater[676]: [2026-05-28 08:56:57] [connect] Successful connection May 28 08:57:00 anneks volumio[1244]: info: Loading plugin "randomizer"... May 28 08:57:02 anneks volumio[1402]: Starting albumart workers May 28 08:57:02 anneks volumio[1403]: Starting albumart workers May 28 08:57:02 anneks volumio[1404]: Starting albumart workers May 28 08:57:03 anneks kernel: hwmon hwmon1: Voltage normalised May 28 08:57:04 anneks volumio[1244]: info: Applying required configuration parameters for plugin randomizer May 28 08:57:05 anneks volumio[1244]: info: Loading i18n strings for locale en May 28 08:57:05 anneks volumio[1244]: Updating browse sources language May 28 08:57:05 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::initPlayerControls May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 08:57:07 anneks volumio[1244]: Express server listening on port 3000 May 28 08:57:07 anneks volumio[1244]: [Metrics] WebUI: 49s 703.15ms May 28 08:57:07 anneks volumio[1244]: info: CoreStateMachine::resetVolumioState May 28 08:57:07 anneks volumio[1244]: info: CoreStateMachine::getcurrentVolume May 28 08:57:07 anneks volumio[1244]: info: CoreCommandRouter::volumioRetrievevolume May 28 08:57:08 anneks sudo[1471]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 08:57:08 anneks sudo[1471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:08 anneks sudo[1471]: pam_unix(sudo:session): session closed for user root May 28 08:57:08 anneks sudo[1474]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 08:57:08 anneks volumio[1244]: info: Volumio Network Manager: Network status updated: 1 May 28 08:57:08 anneks sudo[1474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:08 anneks sudo[1474]: pam_unix(sudo:session): session closed for user root May 28 08:57:08 anneks volumio[1244]: info: Cannot mount NAS Elrond at system boot, trial number 1 ,retrying in 5 seconds May 28 08:57:08 anneks volumio[1244]: info: VolumeController:: Volume=62 Mute =false May 28 08:57:08 anneks volumio[1244]: info: CoreStateMachine::pushState May 28 08:57:08 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:08 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:57:08 anneks volumio[1244]: info: CoreCommandRouter::volumioPushState May 28 08:57:08 anneks volumio[1244]: info: CoreStateMachine::updateTrackBlock May 28 08:57:08 anneks volumio[1244]: info: CorePlayQueue::getTrackBlock May 28 08:57:08 anneks volumio[1244]: info: CoreCommandRouter::volumioRetrievevolume May 28 08:57:09 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 08:57:09 anneks volumio[1244]: info: Executing start script for DAC IQaudIO DAC Plus May 28 08:57:09 anneks volumio[1244]: info: Reloading queue from file May 28 08:57:09 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 08:57:09 anneks volumio[1244]: info: CoreStateMachine::setRepeat null single undefined May 28 08:57:09 anneks volumio[1244]: info: CoreStateMachine::pushState May 28 08:57:09 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:09 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:57:09 anneks volumio[1244]: info: CoreCommandRouter::volumioPushState May 28 08:57:09 anneks volumio[1244]: info: CoreStateMachine::setRandom null May 28 08:57:09 anneks volumio[1244]: info: CoreStateMachine::pushState May 28 08:57:09 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:09 anneks volumio[1244]: info: CoreCommandRouter::volumioPushState May 28 08:57:09 anneks volumio[1244]: info: Setting Device type: Raspberry PI May 28 08:57:09 anneks volumio[1244]: info: DAC script executed May 28 08:57:09 anneks volumio[1244]: info: Completed loading Core Plugins May 28 08:57:09 anneks volumio[1244]: info: Preparing to generate the ALSA configuration file May 28 08:57:10 anneks volumio[1244]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 May 28 08:57:10 anneks volumio[1244]: info: VolumeController:: Volume=62 Mute =false May 28 08:57:10 anneks volumio[1244]: info: CoreStateMachine::pushState May 28 08:57:10 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:10 anneks volumio[1244]: info: CoreCommandRouter::volumioPushState May 28 08:57:10 anneks sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 28 08:57:10 anneks sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:10 anneks volumio[1244]: info: Discovery: adding c62f1a8a-b3bc-4597-9093-35d1a698bc97 May 28 08:57:10 anneks volumio[1244]: info: Discovery: Found device Anneks May 28 08:57:10 anneks volumio[1244]: info: CoreCommandRouter::volumioGetState May 28 08:57:10 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:10 anneks volumio[1244]: info: Discovery: this is already registered, c62f1a8a-b3bc-4597-9093-35d1a698bc97 May 28 08:57:10 anneks volumio[1244]: info: Discovery: Found device Anneks May 28 08:57:10 anneks volumio[1244]: info: CoreCommandRouter::volumioGetState May 28 08:57:10 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:11 anneks volumio[1244]: info: Asound.conf file written May 28 08:57:11 anneks sudo[1513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 28 08:57:11 anneks sudo[1513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:11 anneks sudo[1513]: pam_unix(sudo:session): session closed for user root May 28 08:57:11 anneks volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 28 08:57:11 anneks volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 28 08:57:11 anneks volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 28 08:57:11 anneks volumio[1244]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 28 08:57:11 anneks volumio[1244]: info: Output device has changed, restarting MPD May 28 08:57:11 anneks volumio[1244]: info: Output device has changed, restarting Shairport Sync May 28 08:57:11 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:11 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 08:57:11 anneks sudo[1519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 08:57:11 anneks sudo[1519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:11 anneks sudo[1519]: pam_unix(sudo:session): session closed for user root May 28 08:57:11 anneks sudo[1521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 08:57:11 anneks sudo[1521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:11 anneks volumio[1244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 08:57:11 anneks volumio[1244]: info: ___________ START PLUGINS ___________ May 28 08:57:12 anneks volumio[1244]: info: ControllerMpd::onStart: Initializing MPD May 28 08:57:12 anneks systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 28 08:57:12 anneks volumio[1244]: info: Creating MPD Configuration file May 28 08:57:12 anneks systemd[1]: Starting mpd.service - Music Player Daemon... May 28 08:57:12 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 08:57:12 anneks volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 08:57:12 anneks volumio[1244]: info: [1779951432204] CoreMusicLibrary::Adding element Media Servers May 28 08:57:12 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 08:57:12 anneks sudo[1530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 28 08:57:12 anneks sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:12 anneks volumio[1244]: info: UPNP Browser: Client initialized successfully May 28 08:57:12 anneks sudo[1533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 08:57:12 anneks sudo[1533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:12 anneks sudo[1531]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 08:57:12 anneks sudo[1531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 08:57:12 anneks sudo[1537]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 28 08:57:12 anneks sudo[1531]: pam_unix(sudo:session): session closed for user root May 28 08:57:12 anneks sudo[1533]: pam_unix(sudo:session): session closed for user root May 28 08:57:12 anneks sudo[1535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 08:57:12 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:12 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 08:57:12 anneks sudo[1535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:12 anneks systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. May 28 08:57:12 anneks sudo[1530]: pam_unix(sudo:session): session closed for user root May 28 08:57:12 anneks systemd[1]: mpd.service: Deactivated successfully. May 28 08:57:12 anneks systemd[1]: Stopped mpd.service - Music Player Daemon. May 28 08:57:12 anneks systemd[1]: mpd.socket: Deactivated successfully. May 28 08:57:12 anneks systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 28 08:57:12 anneks systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 28 08:57:12 anneks systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 28 08:57:12 anneks systemd[1]: Starting mpd.service - Music Player Daemon... May 28 08:57:12 anneks volumio[1244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 08:57:12 anneks systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. May 28 08:57:12 anneks systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:57:12 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:12 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 08:57:12 anneks volumio-remote-updater[676]: [2026-05-28 08:57:12] [connect] Successful connection May 28 08:57:12 anneks systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 08:57:12 anneks sudo[1508]: pam_unix(sudo:session): session closed for user root May 28 08:57:12 anneks volumio[1244]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 08:57:13 anneks volumio[1244]: info: [1779951433013] CoreMusicLibrary::Adding element Last_100 May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 08:57:13 anneks sudo[1547]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 08:57:13 anneks sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 08:57:13 anneks volumio[1244]: info: [1779951433018] CoreMusicLibrary::Adding element Webradio May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 08:57:13 anneks sudo[1569]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 28 08:57:13 anneks sudo[1547]: pam_unix(sudo:session): session closed for user root May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 08:57:13 anneks volumio[1244]: info: Initializing BBC Radios May 28 08:57:13 anneks volumio5-onboarding[1545]: time=2026-05-28T08:57:13.500+02:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 08:57:13 anneks volumio[1244]: info: [1779951433739] CoreMusicLibrary::Adding element Radio Paradise (RP2) May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source Radio Paradise (RP2) May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 08:57:13 anneks volumio[1244]: info: [1779951433839] CoreMusicLibrary::Adding element Radio Paradise May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source Radio Paradise (RP2) May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source Radio Paradise May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 08:57:13 anneks volumio[1244]: info: [1779951433880] CoreMusicLibrary::Adding element LastFM May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source Radio Paradise (RP2) May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source Radio Paradise May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source LastFM May 28 08:57:13 anneks volumio[1244]: info: [LastFM] scrobbler initiated! May 28 08:57:13 anneks volumio[1244]: info: [LastFM] extended logging: false May 28 08:57:13 anneks volumio[1244]: info: [LastFM] try scrobble stream/radio plays: true May 28 08:57:13 anneks volumio[1244]: info: [LastFM] Left init routine May 28 08:57:13 anneks volumio[1244]: info: [LastFM] Socket already connected: true May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 08:57:13 anneks volumio[1244]: info: [1779951433980] CoreMusicLibrary::Adding element Randomizer May 28 08:57:13 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source Radio Paradise (RP2) May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source Radio Paradise May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source LastFM May 28 08:57:13 anneks volumio[1244]: Cannot find translation for source Randomizer May 28 08:57:13 anneks volumio[1244]: info: Volumio Calling Home May 28 08:57:15 anneks sudo[1591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=reader,password=5oBMnYY9TkbFnhMfoDx8d5,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.86.108/music /mnt/NAS/Elrond May 28 08:57:15 anneks sudo[1591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:15 anneks volumio[1244]: info: MPD Permissions set May 28 08:57:15 anneks volumio[1244]: info: MPD Permissions set May 28 08:57:15 anneks volumio[1244]: info: Upmpdcli Daemon Started May 28 08:57:15 anneks kernel: CIFS: Attempting to mount //192.168.86.108/music May 28 08:57:15 anneks volumio-remote-updater[676]: [2026-05-28 08:57:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1779951432 101 May 28 08:57:15 anneks volumio[1244]: 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: 2 May 28 08:57:15 anneks volumio[1244]: info: Volumio called home May 28 08:57:16 anneks volumio[1244]: info: [LastFM] finished init: Authenticated successfully! May 28 08:57:16 anneks volumio[1244]: 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: 3 May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:16 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:17 anneks volumio[1244]: 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: 3 May 28 08:57:17 anneks volumio[1244]: info: Received Get System Info May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 08:57:17 anneks volumio[1244]: info: Discovery: Getting this device information May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::volumioGetState May 28 08:57:17 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 08:57:17 anneks volumio5-onboarding[1545]: time=2026-05-28T08:57:17.479+02:00 level=INFO msg="system info for bf49b46d7a23f69cdf5a96fd82958d00" deviceName=Anneks deviceVariant=volumio deviceModel= softwareVersion=4.119 May 28 08:57:17 anneks volumio5-onboarding[1545]: time=2026-05-28T08:57:17.511+02:00 level=INFO msg="bootstrapping state" hasInternet=true May 28 08:57:17 anneks volumio[1244]: info: Received Get System Info May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 08:57:17 anneks volumio[1244]: info: Discovery: Getting this device information May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::volumioGetState May 28 08:57:17 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:17 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 08:57:17 anneks volumio[1244]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 28 08:57:18 anneks volumio-remote-updater[676]: Test mode disabled May 28 08:57:18 anneks volumio-remote-updater[676]: Alpha mode disabled May 28 08:57:18 anneks volumio-remote-updater[676]: Alpha legacy test mode disabled May 28 08:57:18 anneks volumio[1244]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 28 08:57:18 anneks volumio[1244]: info: Starting Shairport Sync May 28 08:57:18 anneks volumio[1244]: info: Starting Shairport Sync May 28 08:57:18 anneks volumio[1244]: info: Starting Shairport Sync May 28 08:57:18 anneks sudo[1610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 08:57:18 anneks sudo[1612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 08:57:18 anneks sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:18 anneks sudo[1614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 08:57:18 anneks sudo[1614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:18 anneks sudo[1612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:18 anneks volumio[1244]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 28 08:57:18 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 28 08:57:18 anneks systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 28 08:57:18 anneks systemd[1]: shairport-sync.service: Deactivated successfully. May 28 08:57:18 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 08:57:18 anneks systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 08:57:18 anneks systemd[1]: shairport-sync.service: Consumed 2.596s CPU time. May 28 08:57:18 anneks systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 08:57:18 anneks sudo[1612]: pam_unix(sudo:session): session closed for user root May 28 08:57:18 anneks systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 28 08:57:18 anneks systemd[1]: shairport-sync.service: Deactivated successfully. May 28 08:57:18 anneks systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 08:57:18 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 08:57:18 anneks systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 08:57:19 anneks sudo[1610]: pam_unix(sudo:session): session closed for user root May 28 08:57:19 anneks sudo[1614]: pam_unix(sudo:session): session closed for user root May 28 08:57:19 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 08:57:19 anneks volumio[1244]: info: Shairport-Sync Started May 28 08:57:19 anneks volumio[1244]: Error adding Membership: Error: addMembership EINVAL May 28 08:57:19 anneks volumio[1244]: info: Shairport-Sync Started May 28 08:57:19 anneks volumio[1244]: info: Shairport-Sync Started May 28 08:57:19 anneks kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 28 08:57:19 anneks kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 28 08:57:19 anneks sudo[1591]: pam_unix(sudo:session): session closed for user root May 28 08:57:19 anneks volumio[1244]: info: Received Get System Info May 28 08:57:19 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 08:57:19 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 08:57:19 anneks volumio[1244]: info: Discovery: Getting this device information May 28 08:57:19 anneks volumio[1244]: info: CoreCommandRouter::volumioGetState May 28 08:57:19 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:19 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 08:57:20 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 08:57:20 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 08:57:20 anneks volumio5-onboarding[1545]: time=2026-05-28T08:57:20.187+02:00 level=INFO msg="enabling local network discovery" May 28 08:57:20 anneks volumio5-onboarding[1545]: time=2026-05-28T08:57:20.227+02:00 level=INFO msg="enabling BLE discovery" May 28 08:57:20 anneks volumio[1244]: info: Cannot mount NAS Elrond at system boot, trial number 2 ,retrying in 5 seconds May 28 08:57:20 anneks volumio[1244]: info: CoreCommandRouter::volumioGetState May 28 08:57:20 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:20 anneks volumio[1244]: info: CoreCommandRouter::volumioGetState May 28 08:57:20 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:20 anneks volumio[1244]: info: Listing playlists May 28 08:57:20 anneks volumio[1244]: info: Listing playlists May 28 08:57:21 anneks mpd[1570]: 2026-05-28T08:57:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 08:57:21 anneks volumio5-onboarding[1545]: time=2026-05-28T08:57:21.172+02:00 level=INFO msg="service successfully established" component=discovery/localnet May 28 08:57:21 anneks systemd[1]: Started mpd.service - Music Player Daemon. May 28 08:57:21 anneks sudo[1521]: pam_unix(sudo:session): session closed for user root May 28 08:57:21 anneks sudo[1535]: pam_unix(sudo:session): session closed for user root May 28 08:57:21 anneks volumio[1244]: info: Completed starting Core Plugins May 28 08:57:21 anneks volumio[1244]: info: ------------------------------------------- May 28 08:57:21 anneks volumio[1244]: info: ----- MyVolumio plugins startup ---- May 28 08:57:21 anneks volumio[1244]: info: ------------------------------------------- May 28 08:57:21 anneks volumio[1244]: info: [MyVolumio PluginManager] Fetching plans data.... May 28 08:57:22 anneks volumio[1244]: error: MPD error: The expression evaluated to a falsy value: May 28 08:57:22 anneks volumio[1244]: assert.ok(self.idling) May 28 08:57:22 anneks volumio[1244]: error: The expression evaluated to a falsy value: May 28 08:57:22 anneks volumio[1244]: assert.ok(self.idling) May 28 08:57:22 anneks volumio[1244]: info: MPD running with PID1570 May 28 08:57:22 anneks volumio[1244]: ,establishing connection May 28 08:57:22 anneks volumio[1244]: error: updateQueue error: null May 28 08:57:22 anneks volumio[1244]: error: updateQueue error: null May 28 08:57:25 anneks sudo[1674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=reader,password=5oBMnYY9TkbFnhMfoDx8d5,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.86.108/music /mnt/NAS/Elrond May 28 08:57:25 anneks sudo[1674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:25 anneks kernel: CIFS: Attempting to mount //192.168.86.108/music May 28 08:57:27 anneks volumio[1244]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 28 08:57:29 anneks kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 28 08:57:29 anneks kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 28 08:57:29 anneks sudo[1674]: pam_unix(sudo:session): session closed for user root May 28 08:57:32 anneks volumio[1244]: info: Cannot mount NAS Elrond at system boot, trial number 3 ,retrying in 5 seconds May 28 08:57:46 anneks volumio[1244]: info: CoreCommandRouter::volumioGetState May 28 08:57:46 anneks volumio[1244]: info: CorePlayQueue::getTrack 0 May 28 08:57:47 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 08:57:47 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 08:57:47 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 08:57:47 anneks volumio[1244]: error: MyVolumio Plugin failed to start in a timely fashion May 28 08:57:47 anneks volumio[1244]: [Metrics] CommandRouter: 87s 614.74ms May 28 08:57:47 anneks volumio[1244]: info: CoreCommandRouter::volumiosetStartupVolume May 28 08:57:47 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 08:57:47 anneks volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 08:57:47 anneks volumio[1244]: info: CoreCommandRouter::Close All Modals sent May 28 08:57:47 anneks volumio[1244]: info: CoreCommandRouter::Close All Modals sent May 28 08:57:47 anneks sudo[1710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=reader,password=5oBMnYY9TkbFnhMfoDx8d5,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.86.108/music /mnt/NAS/Elrond May 28 08:57:47 anneks sudo[1710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 08:57:47 anneks volumio[1244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 08:57:47 anneks volumio[1244]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. May 28 08:57:47 anneks volumio[1244]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues May 28 08:57:47 anneks volumio[1244]: at new NodeError (node:internal/errors:405:5) May 28 08:57:47 anneks volumio[1244]: at assert (node:internal/assert:14:11) May 28 08:57:47 anneks volumio[1244]: at internalConnectMultiple (node:net:1118:3) May 28 08:57:47 anneks volumio[1244]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) May 28 08:57:47 anneks volumio[1244]: at listOnTimeout (node:internal/timers:575:11) May 28 08:57:47 anneks volumio[1244]: at process.processTimers (node:internal/timers:514:7) { May 28 08:57:47 anneks volumio[1244]: code: 'ERR_INTERNAL_ASSERTION' May 28 08:57:47 anneks volumio[1244]: } May 28 08:57:47 anneks volumio[1244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 08:57:47 anneks kernel: CIFS: Attempting to mount //192.168.86.108/music May 28 08:57:49 anneks upmpdcli[1728]: writing RSA key May 28 08:57:51 anneks kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 28 08:57:51 anneks kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 28 08:57:51 anneks sudo[1710]: pam_unix(sudo:session): session closed for user root May 28 08:57:54 anneks sudo[1748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-28 08:56' May 28 08:57:54 anneks sudo[1748]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"