Dec 17 22:22:15 digione01 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 17 22:22:15 digione01 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 17 22:22:15 digione01 systemd[1]: setdatetime-helper.service: Consumed 3.091s CPU time. Dec 17 22:22:15 digione01 ntpd[799]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 17 22:22:15 digione01 ntpd[799]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 17 22:22:15 digione01 ntpd[799]: DNS: Pool taking: 193.33.30.39 Dec 17 22:22:15 digione01 ntpd[799]: DNS: Pool taking: 195.186.4.101 Dec 17 22:22:15 digione01 ntpd[799]: DNS: Pool taking: 217.147.223.78 Dec 17 22:22:15 digione01 ntpd[799]: DNS: Pool taking: 156.106.214.48 Dec 17 22:22:15 digione01 ntpd[799]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 17 22:22:16 digione01 smbd[985]: [2025/12/17 22:22:16.042781, 0] ../../source3/smbd/server.c:1741(main) Dec 17 22:22:16 digione01 smbd[985]: smbd version 4.17.8-Raspbian started. Dec 17 22:22:16 digione01 smbd[985]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: === SNM TRANSITION === Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: New ethernet state: connected Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: Single Network Mode: enabled Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: First start: yes Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: === END TRANSITION === Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Dec 17 22:22:17 digione01 wireless.js[634]: WIRELESS.JS - INFO: ERROR: wlan0 does not exist, exiting... Dec 17 22:22:17 digione01 systemd[1]: wireless.service: Main process exited, code=exited, status=1/FAILURE Dec 17 22:22:17 digione01 systemd[1]: wireless.service: Killing process 1003 (sh) with signal SIGKILL. Dec 17 22:22:17 digione01 systemd[1]: wireless.service: Killing process 1004 (pkill) with signal SIGKILL. Dec 17 22:22:17 digione01 systemd[1]: wireless.service: Failed with result 'exit-code'. Dec 17 22:22:17 digione01 systemd[1]: Failed to start wireless.service - Wireless Services. Dec 17 22:22:17 digione01 systemd[1]: wireless.service: Consumed 13.303s CPU time. Dec 17 22:22:17 digione01 systemd[1]: Started volumio.service - Volumio Backend Module. Dec 17 22:22:17 digione01 systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Dec 17 22:22:17 digione01 systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Dec 17 22:22:17 digione01 systemd[1]: Received SIGRTMIN+21 from PID 277 (plymouthd). Dec 17 22:22:17 digione01 systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Dec 17 22:22:17 digione01 systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Dec 17 22:22:17 digione01 systemd[1]: Received SIGRTMIN+21 from PID 277 (n/a). Dec 17 22:22:17 digione01 volumio-cpu-tweak[1008]: Setting RT Priority for mpd Dec 17 22:22:17 digione01 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 17 22:22:17 digione01 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Dec 17 22:22:17 digione01 systemd[1]: Reached target getty.target - Login Prompts. Dec 17 22:22:17 digione01 volumio-cpu-tweak[1023]: pid 35's current scheduling policy: SCHED_OTHER Dec 17 22:22:17 digione01 volumio-cpu-tweak[1023]: pid 35's current scheduling priority: 0 Dec 17 22:22:17 digione01 volumio-cpu-tweak[1008]: Setting MPD Affinity Dec 17 22:22:17 digione01 volumio-cpu-tweak[1025]: pid 3's current affinity mask: f Dec 17 22:22:17 digione01 volumio-cpu-tweak[1008]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 17 22:22:17 digione01 volumio-cpu-tweak[1008]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 17 22:22:18 digione01 systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Dec 17 22:22:18 digione01 systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 17 22:22:18 digione01 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 17 22:22:18 digione01 systemd[1]: Reached target graphical.target - Graphical Interface. Dec 17 22:22:18 digione01 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 17 22:22:18 digione01 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 17 22:22:18 digione01 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 17 22:22:18 digione01 systemd[1]: Startup finished in 19.295s (kernel) + 20.775s (userspace) = 40.071s. Dec 17 22:22:20 digione01 volumio-remote-updater[630]: [2025-12-17 22:22:20] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 17 22:22:20 digione01 volumio-remote-updater[630]: [2025-12-17 22:22:20] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 17 22:22:20 digione01 volumio-remote-updater[630]: [2025-12-17 22:22:20] [error] handle_connect error: Connection refused Dec 17 22:22:23 digione01 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Dec 17 22:22:23 digione01 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 17 22:22:23 digione01 volumio[1006]: info: ------------------------------------------- Dec 17 22:22:23 digione01 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 17 22:22:23 digione01 volumio[1006]: info: ----- Volumio3 ---- Dec 17 22:22:23 digione01 volumio[1006]: info: ------------------------------------------- Dec 17 22:22:23 digione01 volumio[1006]: info: ----- System startup ---- Dec 17 22:22:23 digione01 volumio[1006]: info: ------------------------------------------- Dec 17 22:22:23 digione01 upmpdcli[1041]: Could not open config: /tmp/upmpdcli.conf Dec 17 22:22:23 digione01 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 17 22:22:23 digione01 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 17 22:22:25 digione01 volumio[1006]: info: MYVOLUMIO Environment detected Dec 17 22:22:26 digione01 volumio[1006]: info: Plugin folders cleanup Dec 17 22:22:26 digione01 volumio[1006]: info: Scanning into folder /volumio/app/plugins/ Dec 17 22:22:26 digione01 volumio[1006]: info: Scanning category audio_interface Dec 17 22:22:26 digione01 volumio[1006]: info: Scanning category miscellanea Dec 17 22:22:26 digione01 volumio[1006]: info: Scanning category music_service Dec 17 22:22:26 digione01 volumio[1006]: info: Scanning category plugins.json Dec 17 22:22:26 digione01 volumio[1006]: info: Scanning category system_controller Dec 17 22:22:26 digione01 volumio[1006]: info: Scanning category user_interface Dec 17 22:22:26 digione01 volumio[1006]: info: Scanning into folder /data/plugins/ Dec 17 22:22:26 digione01 volumio[1006]: info: Plugin folders cleanup completed Dec 17 22:22:26 digione01 volumio[1006]: info: ------------------------------------------- Dec 17 22:22:26 digione01 volumio[1006]: info: ----- Core plugins startup ---- Dec 17 22:22:26 digione01 volumio[1006]: info: ------------------------------------------- Dec 17 22:22:26 digione01 volumio[1006]: info: Loading plugins from folder /volumio/app/plugins/ Dec 17 22:22:26 digione01 volumio[1006]: info: Adding plugin upnp to MyMusic Plugins Dec 17 22:22:26 digione01 volumio[1006]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 17 22:22:26 digione01 volumio[1006]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 17 22:22:26 digione01 volumio[1006]: info: Loading plugins from folder /data/plugins/ Dec 17 22:22:26 digione01 volumio[1006]: info: Loading plugin "system"... Dec 17 22:22:26 digione01 volumio[1006]: info: Loading plugin "appearance"... Dec 17 22:22:29 digione01 volumio[1006]: info: Loading plugin "network"... Dec 17 22:22:29 digione01 volumio[1006]: info: Refreshing Cached IP Addresses Dec 17 22:22:29 digione01 volumio[1006]: info: Loading plugin "services"... Dec 17 22:22:29 digione01 volumio[1006]: info: Loading plugin "alsa_controller"... Dec 17 22:22:30 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 22:22:30 digione01 volumio[1006]: info: Loading plugin "wizard"... Dec 17 22:22:30 digione01 volumio[1006]: info: Loading plugin "networkfs"... Dec 17 22:22:30 digione01 sudo[1050]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 22:22:30 digione01 sudo[1059]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 17 22:22:30 digione01 sudo[1052]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 22:22:30 digione01 sudo[1059]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:30 digione01 sudo[1052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:30 digione01 sudo[1050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:30 digione01 sudo[1050]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:30 digione01 volumio-remote-updater[630]: [2025-12-17 22:22:30] [connect] Successful connection Dec 17 22:22:30 digione01 sudo[1052]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:30 digione01 sudo[1059]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:30 digione01 volumio[1006]: info: Starting Udev Watcher for removable devices Dec 17 22:22:30 digione01 sudo[1085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Musik,password=MyStreamer(JM,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.11/music /mnt/NAS/Musik Dec 17 22:22:30 digione01 sudo[1085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:30 digione01 volumio[1006]: info: Ignoring mount for partition: boot Dec 17 22:22:30 digione01 volumio[1006]: info: Ignoring mount for partition: volumio Dec 17 22:22:30 digione01 volumio[1006]: info: Ignoring mount for partition: volumio_data Dec 17 22:22:30 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 22:22:30 digione01 volumio[1006]: info: Loading plugin "volumio_command_line_client"... Dec 17 22:22:30 digione01 volumio[1006]: info: Loading plugin "upnp"... Dec 17 22:22:30 digione01 volumio[1006]: info: [1766006550380] Starting Upmpd Daemon Dec 17 22:22:30 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 22:22:30 digione01 volumio[1006]: info: Loading plugin "my_music"... Dec 17 22:22:30 digione01 volumio[1006]: info: Loading plugin "mpd"... Dec 17 22:22:30 digione01 kernel: netfs: FS-Cache loaded Dec 17 22:22:30 digione01 kernel: Key type cifs.spnego registered Dec 17 22:22:30 digione01 kernel: Key type cifs.idmap registered Dec 17 22:22:30 digione01 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. Dec 17 22:22:30 digione01 kernel: CIFS: Attempting to mount //192.168.178.11/music Dec 17 22:22:31 digione01 kernel: cryptd: max_cpu_qlen set to 1000 Dec 17 22:22:31 digione01 sudo[1085]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:31 digione01 volumio[1006]: info: Loading plugin "upnp_browser"... Dec 17 22:22:33 digione01 systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 17 22:22:34 digione01 volumio[1006]: info: Starting UPNP Browser Dec 17 22:22:34 digione01 volumio[1006]: info: Loading plugin "alarm-clock"... Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "airplay_emulation"... Dec 17 22:22:35 digione01 volumio[1006]: info: Starting Shairport Sync Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "last_100"... Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "webradio"... Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "i2s_dacs"... Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "volumiodiscovery"... Dec 17 22:22:35 digione01 volumio[1006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 17 22:22:35 digione01 volumio[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 22:22:35 digione01 volumio[1006]: *** WARNING *** For more information see Dec 17 22:22:35 digione01 volumio[1006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 17 22:22:35 digione01 volumio[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 22:22:35 digione01 volumio[1006]: *** WARNING *** For more information see Dec 17 22:22:35 digione01 node[1006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 17 22:22:35 digione01 node[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 22:22:35 digione01 node[1006]: *** WARNING *** For more information see Dec 17 22:22:35 digione01 node[1006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 17 22:22:35 digione01 node[1006]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 17 22:22:35 digione01 node[1006]: *** WARNING *** For more information see Dec 17 22:22:35 digione01 volumio[1006]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 17 22:22:35 digione01 volumio[1006]: info: Discovery: Started advertising with name: DigiOne01 Dec 17 22:22:35 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "outputs"... Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "albumart"... Dec 17 22:22:35 digione01 volumio[1006]: info: Plugin example_plugin is not enabled Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "inputs"... Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "updater_comm"... Dec 17 22:22:35 digione01 volumio[1006]: info: Plugin mpdemulation is not enabled Dec 17 22:22:35 digione01 volumio[1006]: info: Loading plugin "rest_api"... Dec 17 22:22:36 digione01 volumio[1006]: info: Loading plugin "websocket"... Dec 17 22:22:36 digione01 volumio[1006]: info: Starting Socket.io Server version 1.7.4 Dec 17 22:22:36 digione01 volumio[1006]: info: Loading i18n strings for locale de Dec 17 22:22:36 digione01 volumio[1006]: Updating browse sources language Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::initPlayerControls Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 22:22:36 digione01 volumio[1006]: Express server listening on port 3000 Dec 17 22:22:36 digione01 volumio[1006]: [Metrics] WebUI: 14s 706.81ms Dec 17 22:22:36 digione01 volumio[1006]: info: CoreStateMachine::resetVolumioState Dec 17 22:22:36 digione01 volumio[1006]: info: CoreStateMachine::getcurrentVolume Dec 17 22:22:36 digione01 volumio[1006]: info: CoreCommandRouter::volumioRetrievevolume Dec 17 22:22:37 digione01 volumio[1006]: info: Volumio Network Manager: Network status updated: 1 Dec 17 22:22:37 digione01 volumio[1006]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 17 22:22:37 digione01 volumio[1006]: wlan0 Interface doesn't support scanning. Dec 17 22:22:37 digione01 volumio[1006]: info: Cannot use regular scanning, forcing with ap-force Dec 17 22:22:37 digione01 sudo[1152]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 17 22:22:37 digione01 sudo[1152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:37 digione01 sudo[1152]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:37 digione01 volumio[1006]: command failed: No such device (-19) Dec 17 22:22:37 digione01 volumio[1006]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 17 22:22:37 digione01 volumio[1006]: command failed: No such device (-19) Dec 17 22:22:37 digione01 volumio[1006]: info: CoreStateMachine::pushState Dec 17 22:22:37 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:37 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 22:22:37 digione01 volumio[1006]: info: CoreCommandRouter::volumioPushState Dec 17 22:22:37 digione01 volumio[1006]: info: CoreStateMachine::updateTrackBlock Dec 17 22:22:37 digione01 volumio[1006]: info: CorePlayQueue::getTrackBlock Dec 17 22:22:37 digione01 volumio[1006]: info: CoreCommandRouter::volumioRetrievevolume Dec 17 22:22:38 digione01 volumio[1006]: verbose: New Socket.io Connection to 192.168.178.159:3000 from 192.168.178.143 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 17 22:22:38 digione01 volumio[1006]: info: Reloading queue from file Dec 17 22:22:38 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:22:38 digione01 volumio[1006]: info: CoreStateMachine::pushState Dec 17 22:22:38 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:38 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 22:22:38 digione01 volumio[1006]: info: CoreCommandRouter::volumioPushState Dec 17 22:22:38 digione01 volumio[1130]: Forking 3 albumart workers Dec 17 22:22:38 digione01 volumio[1006]: info: Setting Device type: Raspberry PI Dec 17 22:22:38 digione01 volumio[1006]: info: CoreStateMachine::setRepeat true single undefined Dec 17 22:22:38 digione01 volumio[1006]: info: CoreStateMachine::pushState Dec 17 22:22:38 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:38 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 22:22:38 digione01 volumio[1006]: info: CoreCommandRouter::volumioPushState Dec 17 22:22:38 digione01 volumio[1006]: info: CoreStateMachine::setRandom null Dec 17 22:22:38 digione01 volumio[1006]: info: CoreStateMachine::pushState Dec 17 22:22:38 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:38 digione01 volumio[1006]: info: CoreCommandRouter::volumioPushState Dec 17 22:22:38 digione01 volumio[1006]: info: Completed loading Core Plugins Dec 17 22:22:38 digione01 volumio[1006]: info: Preparing to generate the ALSA configuration file Dec 17 22:22:38 digione01 volumio[1006]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 17 22:22:38 digione01 volumio[1006]: info: Reading ALSA contributions from plugins. Dec 17 22:22:38 digione01 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 17 22:22:38 digione01 volumio[1006]: info: Discovery: adding 1d482b6c-8f61-43ff-bb59-4c691ad8d984 Dec 17 22:22:38 digione01 volumio[1006]: info: Discovery: Found device Boss02 Dec 17 22:22:38 digione01 volumio[1006]: info: Discovery: Connecting to remote: 192.168.178.175 Dec 17 22:22:38 digione01 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 17 22:22:38 digione01 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 17 22:22:38 digione01 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 17 22:22:39 digione01 upmpdcli[1200]: Could not open config: /tmp/upmpdcli.conf Dec 17 22:22:39 digione01 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 17 22:22:39 digione01 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 17 22:22:39 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 17 22:22:39 digione01 volumio[1006]: info: CoreCommandRouter::volumioGetState Dec 17 22:22:39 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:39 digione01 volumio[1006]: info: Discovery: adding f5337ffb-d4a1-4a45-b657-9942747418e1 Dec 17 22:22:39 digione01 volumio[1006]: info: Discovery: Found device DigiOneSignEG Dec 17 22:22:39 digione01 volumio[1006]: info: Discovery: Connecting to remote: 192.168.178.143 Dec 17 22:22:39 digione01 volumio[1006]: info: Discovery: adding bef1e73e-22cb-4634-a271-6e1a7c49f514 Dec 17 22:22:39 digione01 volumio[1006]: info: Discovery: Found device DigiOne01 Dec 17 22:22:39 digione01 volumio[1006]: info: CoreCommandRouter::volumioGetState Dec 17 22:22:39 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:40 digione01 volumio[1006]: info: Discovery: this is already registered, bef1e73e-22cb-4634-a271-6e1a7c49f514 Dec 17 22:22:40 digione01 volumio[1006]: info: Discovery: Found device DigiOne01 Dec 17 22:22:40 digione01 volumio[1006]: info: CoreCommandRouter::volumioGetState Dec 17 22:22:40 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:40 digione01 sudo[1239]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 22:22:40 digione01 sudo[1239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:40 digione01 sudo[1239]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:41 digione01 sudo[1241]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 22:22:41 digione01 sudo[1241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:41 digione01 volumio[1006]: info: Discovery: Connected to remote: 192.168.178.143 Dec 17 22:22:41 digione01 sudo[1241]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:41 digione01 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 17 22:22:41 digione01 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 17 22:22:41 digione01 systemd[1]: setdatetime-helper.service: Consumed 1.993s CPU time. Dec 17 22:22:41 digione01 volumio[1006]: info: Asound.conf file unchanged, so no further update is needed Dec 17 22:22:41 digione01 volumio[1006]: info: Output device has changed, restarting MPD Dec 17 22:22:41 digione01 volumio[1006]: info: Output device has changed, restarting Shairport Sync Dec 17 22:22:41 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:41 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:22:41 digione01 sudo[1251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 22:22:41 digione01 sudo[1251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:41 digione01 sudo[1251]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:41 digione01 sudo[1254]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 22:22:41 digione01 volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 22:22:41 digione01 volumio[1006]: info: ___________ START PLUGINS ___________ Dec 17 22:22:41 digione01 sudo[1254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:41 digione01 volumio[1006]: info: ControllerMpd::onStart: Initializing MPD Dec 17 22:22:41 digione01 volumio[1006]: info: Creating MPD Configuration file Dec 17 22:22:41 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 22:22:41 digione01 volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 22:22:41 digione01 volumio[1006]: info: [1766006561975] CoreMusicLibrary::Adding element Medienserver Dec 17 22:22:41 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 22:22:42 digione01 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 17 22:22:42 digione01 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 17 22:22:42 digione01 volumio[1006]: info: UPNP Browser: Client initialized successfully Dec 17 22:22:42 digione01 sudo[1261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 17 22:22:42 digione01 sudo[1261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:42 digione01 sudo[1261]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:42 digione01 sudo[1264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 17 22:22:42 digione01 sudo[1264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:42 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:42 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:22:42 digione01 sudo[1265]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 17 22:22:42 digione01 sudo[1265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 22:22:42 digione01 sudo[1272]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 17 22:22:42 digione01 sudo[1265]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:42 digione01 volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 22:22:42 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:42 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:22:42 digione01 systemd[1]: mpd.service: Deactivated successfully. Dec 17 22:22:42 digione01 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 17 22:22:42 digione01 systemd[1]: mpd.socket: Deactivated successfully. Dec 17 22:22:42 digione01 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 17 22:22:42 digione01 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 17 22:22:42 digione01 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 17 22:22:42 digione01 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 17 22:22:42 digione01 volumio[1006]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 17 22:22:42 digione01 volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 22:22:42 digione01 volumio[1006]: info: [1766006562886] CoreMusicLibrary::Adding element Last_100 Dec 17 22:22:42 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 22:22:42 digione01 volumio[1006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 17 22:22:42 digione01 volumio[1006]: info: [1766006562936] CoreMusicLibrary::Adding element Webradio Dec 17 22:22:42 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 22:22:43 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 22:22:43 digione01 volumio[1006]: info: Initializing BBC Radios Dec 17 22:22:43 digione01 sudo[1279]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 17 22:22:43 digione01 sudo[1279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 17 22:22:43 digione01 sudo[1282]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 17 22:22:43 digione01 sudo[1279]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:43 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 22:22:43 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:22:43 digione01 volumio[1006]: info: Volumio Calling Home Dec 17 22:22:45 digione01 volumio-remote-updater[630]: [2025-12-17 22:22:45] [connect] Successful connection Dec 17 22:22:51 digione01 volumio[1006]: info: MPD Permissions set Dec 17 22:22:51 digione01 volumio[1006]: info: MPD Permissions set Dec 17 22:22:51 digione01 volumio[1006]: info: Discovery: Disconnected from remote: 192.168.178.143 Dec 17 22:22:51 digione01 volumio[1006]: info: Volumio called home Dec 17 22:22:52 digione01 volumio[1170]: Starting albumart workers Dec 17 22:22:53 digione01 volumio[1172]: Starting albumart workers Dec 17 22:22:54 digione01 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 17 22:22:54 digione01 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1171]: Starting albumart workers Dec 17 22:22:54 digione01 mpd[1283]: 2025-12-17T22:22:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:22:54 digione01 sudo[1315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 17 22:22:54 digione01 sudo[1315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:54 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:55 digione01 sudo[1315]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:55 digione01 systemd[1]: Started mpd.service - Music Player Daemon. Dec 17 22:22:55 digione01 sudo[1264]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:55 digione01 sudo[1254]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:55 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:22:55 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:22:55 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 22:22:55 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 22:22:55 digione01 volumio[1006]: info: Discovery: Getting this device information Dec 17 22:22:55 digione01 volumio[1006]: info: CoreCommandRouter::volumioGetState Dec 17 22:22:55 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:55 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 22:22:56 digione01 volumio[1006]: verbose: New Socket.io Connection to 192.168.178.159:3000 from 192.168.178.112 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Dec 17 22:22:56 digione01 volumio[1006]: info: Discovery: Connecting to remote: 192.168.178.143 Dec 17 22:22:56 digione01 volumio[1006]: verbose: New Socket.io Connection to 192.168.178.159:3000 from 192.168.178.175 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 17 22:22:56 digione01 volumio[1006]: info: Upmpdcli Daemon Started Dec 17 22:22:56 digione01 volumio[1006]: info: Completed starting Core Plugins Dec 17 22:22:56 digione01 volumio[1006]: info: ------------------------------------------- Dec 17 22:22:56 digione01 volumio[1006]: info: ----- MyVolumio plugins startup ---- Dec 17 22:22:56 digione01 volumio[1006]: info: ------------------------------------------- Dec 17 22:22:56 digione01 volumio[1006]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 17 22:22:56 digione01 volumio[1006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 17 22:22:57 digione01 volumio[1006]: info: Starting Shairport Sync Dec 17 22:22:57 digione01 volumio[1006]: info: Starting Shairport Sync Dec 17 22:22:57 digione01 volumio[1006]: info: Starting Shairport Sync Dec 17 22:22:57 digione01 sudo[1345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 22:22:57 digione01 sudo[1345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:57 digione01 sudo[1347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 22:22:57 digione01 sudo[1347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:57 digione01 sudo[1349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 17 22:22:57 digione01 sudo[1349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:57 digione01 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 22:22:57 digione01 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 22:22:57 digione01 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:22:57 digione01 systemd[1]: shairport-sync.service: Consumed 2.615s CPU time. Dec 17 22:22:57 digione01 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:22:57 digione01 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 17 22:22:57 digione01 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 17 22:22:57 digione01 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:22:57 digione01 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 17 22:22:57 digione01 sudo[1345]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:57 digione01 sudo[1347]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:57 digione01 sudo[1349]: pam_unix(sudo:session): session closed for user root Dec 17 22:22:58 digione01 volumio[1006]: error: MPD error: The expression evaluated to a falsy value: Dec 17 22:22:58 digione01 volumio[1006]: assert.ok(self.idling) Dec 17 22:22:58 digione01 volumio[1006]: error: The expression evaluated to a falsy value: Dec 17 22:22:58 digione01 volumio[1006]: assert.ok(self.idling) Dec 17 22:22:58 digione01 volumio[1006]: verbose: New Socket.io Connection to 192.168.178.159:3000 from 192.168.178.112 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 17 22:22:58 digione01 volumio[1006]: error: updateQueue error: null Dec 17 22:22:58 digione01 volumio[1006]: info: Discovery: Connected to remote: 192.168.178.143 Dec 17 22:22:59 digione01 volumio[1006]: info: Shairport-Sync Started Dec 17 22:22:59 digione01 volumio[1006]: Error adding Membership: Error: addMembership EINVAL Dec 17 22:22:59 digione01 volumio[1006]: info: Shairport-Sync Started Dec 17 22:22:59 digione01 volumio[1006]: info: Shairport-Sync Started Dec 17 22:22:59 digione01 volumio[1006]: info: MPD running with PID1283 Dec 17 22:22:59 digione01 volumio[1006]: ,establishing connection Dec 17 22:22:59 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 22:22:59 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 22:22:59 digione01 volumio[1006]: info: Discovery: Getting this device information Dec 17 22:22:59 digione01 volumio[1006]: info: CoreCommandRouter::volumioGetState Dec 17 22:22:59 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:59 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 22:22:59 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 22:22:59 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 22:22:59 digione01 volumio[1006]: info: Discovery: Getting this device information Dec 17 22:22:59 digione01 volumio[1006]: info: CoreCommandRouter::volumioGetState Dec 17 22:22:59 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:22:59 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 22:22:59 digione01 volumio[1006]: error: updateQueue error: null Dec 17 22:22:59 digione01 sudo[1383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 17 22:22:59 digione01 sudo[1383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:22:59 digione01 volumio[1006]: verbose: New Socket.io Connection to 192.168.178.159:3000 from 192.168.178.112 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 17 22:23:00 digione01 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 17 22:23:00 digione01 mpd_monitor.sh[1385]: MPD Monitor Service: Starting MPD Monitor Service Dec 17 22:23:00 digione01 volumio-remote-updater[630]: [2025-12-17 22:23:00] [connect] Successful connection Dec 17 22:23:00 digione01 sudo[1383]: pam_unix(sudo:session): session closed for user root Dec 17 22:23:00 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 17 22:23:00 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 17 22:23:00 digione01 sudo[1388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 17 22:23:00 digione01 volumio[1006]: info: Successfully started MPD Monitor Dec 17 22:23:00 digione01 sudo[1388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:23:00 digione01 volumio-remote-updater[630]: [2025-12-17 22:23:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766006580 101 Dec 17 22:23:00 digione01 volumio[1006]: 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: 7 Dec 17 22:23:00 digione01 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 17 22:23:00 digione01 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 17 22:23:00 digione01 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 17 22:23:00 digione01 volumio[1006]: info: CoreCommandRouter::volumioGetState Dec 17 22:23:00 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:23:00 digione01 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 17 22:23:00 digione01 sudo[1388]: pam_unix(sudo:session): session closed for user root Dec 17 22:23:00 digione01 mpd_monitor.sh[1400]: MPD Monitor Service: Starting MPD Monitor Service Dec 17 22:23:00 digione01 volumio[1006]: info: Successfully started MPD Monitor Dec 17 22:23:03 digione01 volumio[1006]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 22:23:18 digione01 volumio[1006]: error: MyVolumio Plugin failed to start in a timely fashion Dec 17 22:23:18 digione01 volumio[1006]: [Metrics] CommandRouter: 54s 825.33ms Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::volumiosetStartupVolume Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 22:23:18 digione01 volumio[1006]: info: VolumeController:: Setting startup Volume 5 Dec 17 22:23:18 digione01 volumio[1006]: info: VolumeController::SetAlsaVolume5 Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::Close All Modals sent Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::Close All Modals sent Dec 17 22:23:18 digione01 volumio[1006]: info: CoreStateMachine::pushState Dec 17 22:23:18 digione01 volumio[1006]: info: CorePlayQueue::getTrack 0 Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 22:23:18 digione01 volumio[1006]: info: CoreCommandRouter::volumioPushState Dec 17 22:23:19 digione01 volumio[1006]: info: Discovery: Connected to remote: 192.168.178.175 Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 17 22:23:19 digione01 volumio[1006]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 17 22:23:19 digione01 volumio[1006]: info: Adding plugin bluetooth to MyMusic Plugins Dec 17 22:23:19 digione01 volumio[1006]: info: Adding plugin multiroom to MyMusic Plugins Dec 17 22:23:19 digione01 volumio[1006]: info: Adding plugin metavolumio to MyMusic Plugins Dec 17 22:23:19 digione01 volumio[1006]: info: Adding plugin cd_controller to MyMusic Plugins Dec 17 22:23:19 digione01 volumio[1006]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 17 22:23:19 digione01 volumio[1006]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 17 22:23:19 digione01 volumio[1006]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 17 22:23:20 digione01 volumio[1006]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 17 22:23:28 digione01 volumio[1006]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 17 22:23:28 digione01 volumio[1006]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 17 22:23:28 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:23:28 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 22:23:28 digione01 volumio[1006]: info: Starting MyVolumio Remote Streaming Endpoints Dec 17 22:23:28 digione01 volumio[1006]: info: MyVolumio login type: Token Dec 17 22:23:29 digione01 volumio[1006]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 17 22:23:29 digione01 volumio[1006]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 17 22:23:34 digione01 volumio[1006]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 17 22:23:34 digione01 volumio[1006]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 17 22:23:34 digione01 volumio[1006]: info: Streaming services startup Dec 17 22:23:34 digione01 volumio[1006]: info: Starting Streaming Daemon Dec 17 22:23:35 digione01 volumio[1006]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 17 22:23:35 digione01 sudo[1473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 17 22:23:35 digione01 sudo[1473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 22:23:35 digione01 sudo[1473]: pam_unix(sudo:session): session closed for user root Dec 17 22:23:35 digione01 volumio[1006]: verbose: New Socket.io Connection to 192.168.178.159:3000 from 192.168.178.175 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 17 22:23:35 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 17 22:23:35 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 22:23:35 digione01 volumio[1006]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 17 22:23:35 digione01 volumio[1006]: info: Version has changed, forcing UI Reload Dec 17 22:23:35 digione01 volumio[1006]: info: CoreCommandRouter::Reload Ui Dec 17 22:23:36 digione01 volumio[1006]: info: BOOT COMPLETED Dec 17 22:23:36 digione01 volumio[1006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 22:23:36 digione01 volumio[1006]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 17 22:23:36 digione01 volumio[1006]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 17 22:23:36 digione01 volumio[1006]: at new NodeError (node:internal/errors:405:5) Dec 17 22:23:36 digione01 volumio[1006]: at assert (node:internal/assert:14:11) Dec 17 22:23:36 digione01 volumio[1006]: at internalConnectMultiple (node:net:1118:3) Dec 17 22:23:36 digione01 volumio[1006]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 17 22:23:36 digione01 volumio[1006]: at listOnTimeout (node:internal/timers:575:11) Dec 17 22:23:36 digione01 volumio[1006]: at process.processTimers (node:internal/timers:514:7) { Dec 17 22:23:36 digione01 volumio[1006]: code: 'ERR_INTERNAL_ASSERTION' Dec 17 22:23:36 digione01 volumio[1006]: } Dec 17 22:23:36 digione01 volumio[1006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 22:23:36 digione01 upmpdcli[1480]: writing RSA key Dec 17 22:23:42 digione01 sudo[1526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-17 22:22' Dec 17 22:23:42 digione01 sudo[1526]: 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="6afb90945e8ed48e8055051e2b7fdfaa52c3637b" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Dec 17 16:02:38 UTC 2025" VOLUMIO_VERSION="4.081" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="441a598d858427165f18cef3d6ec4be3"