Dec 26 16:45:10 headphones ntpd[862]: CLOCK: time stepped by 64.722021 Dec 26 16:45:10 headphones ntpd[862]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 26 16:45:10 headphones systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 26 16:45:11 headphones systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 26 16:45:11 headphones systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:45:11 headphones systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:45:11 headphones upmpdcli[1307]: Could not open config: /tmp/upmpdcli.conf Dec 26 16:45:11 headphones systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 16:45:11 headphones systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 16:45:12 headphones volumio-remote-updater[694]: [2025-12-26 16:45:12] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 26 16:45:12 headphones volumio-remote-updater[694]: [2025-12-26 16:45:12] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 26 16:45:12 headphones volumio-remote-updater[694]: [2025-12-26 16:45:12] [error] handle_connect error: Connection refused Dec 26 16:45:14 headphones systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 26 16:45:14 headphones systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 26 16:45:14 headphones systemd[1]: setdatetime-helper.service: Consumed 1.981s CPU time. Dec 26 16:45:15 headphones volumio[1224]: info: ------------------------------------------- Dec 26 16:45:15 headphones volumio[1224]: info: ----- Volumio3 ---- Dec 26 16:45:15 headphones volumio[1224]: info: ------------------------------------------- Dec 26 16:45:15 headphones volumio[1224]: info: ----- System startup ---- Dec 26 16:45:15 headphones volumio[1224]: info: ------------------------------------------- Dec 26 16:45:16 headphones systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 26 16:45:18 headphones volumio[1224]: info: MYVOLUMIO Environment detected Dec 26 16:45:18 headphones volumio[1224]: info: Plugin folders cleanup Dec 26 16:45:18 headphones volumio[1224]: info: Scanning into folder /volumio/app/plugins/ Dec 26 16:45:18 headphones volumio[1224]: info: Scanning category audio_interface Dec 26 16:45:18 headphones volumio[1224]: info: Scanning category miscellanea Dec 26 16:45:18 headphones volumio[1224]: info: Scanning category music_service Dec 26 16:45:18 headphones volumio[1224]: info: Scanning category plugins.json Dec 26 16:45:18 headphones volumio[1224]: info: Scanning category system_controller Dec 26 16:45:18 headphones volumio[1224]: info: Scanning category user_interface Dec 26 16:45:18 headphones volumio[1224]: info: Scanning into folder /data/plugins/ Dec 26 16:45:18 headphones volumio[1224]: info: Plugin folders cleanup completed Dec 26 16:45:18 headphones volumio[1224]: info: ------------------------------------------- Dec 26 16:45:18 headphones volumio[1224]: info: ----- Core plugins startup ---- Dec 26 16:45:18 headphones volumio[1224]: info: ------------------------------------------- Dec 26 16:45:18 headphones volumio[1224]: info: Loading plugins from folder /volumio/app/plugins/ Dec 26 16:45:18 headphones volumio[1224]: info: Adding plugin upnp to MyMusic Plugins Dec 26 16:45:18 headphones volumio[1224]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 26 16:45:18 headphones volumio[1224]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 26 16:45:18 headphones volumio[1224]: info: Loading plugins from folder /data/plugins/ Dec 26 16:45:18 headphones volumio[1224]: info: Loading plugin "system"... Dec 26 16:45:18 headphones volumio[1224]: info: Loading plugin "appearance"... Dec 26 16:45:22 headphones volumio-remote-updater[694]: [2025-12-26 16:45:22] [connect] Successful connection Dec 26 16:45:22 headphones volumio[1224]: info: Loading plugin "network"... Dec 26 16:45:22 headphones volumio[1224]: info: Refreshing Cached IP Addresses Dec 26 16:45:23 headphones sudo[1337]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 16:45:23 headphones sudo[1337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:23 headphones volumio[1224]: info: Loading plugin "services"... Dec 26 16:45:23 headphones sudo[1337]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:23 headphones volumio[1224]: info: Loading plugin "alsa_controller"... Dec 26 16:45:23 headphones sudo[1339]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 16:45:23 headphones sudo[1339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:23 headphones sudo[1339]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:23 headphones sudo[1345]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 16:45:23 headphones sudo[1345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:23 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 16:45:23 headphones volumio[1224]: info: Loading plugin "wizard"... Dec 26 16:45:23 headphones volumio[1224]: info: Loading plugin "networkfs"... Dec 26 16:45:23 headphones volumio[1224]: info: Starting Udev Watcher for removable devices Dec 26 16:45:23 headphones sudo[1368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Admin,password=password,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.206/Music /mnt/NAS/Music Dec 26 16:45:23 headphones sudo[1368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:23 headphones sudo[1370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Admin,password=password,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.50.116/download2 /mnt/NAS/Tidal Dec 26 16:45:23 headphones sudo[1370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:23 headphones volumio[1224]: info: Ignoring mount for partition: boot Dec 26 16:45:23 headphones volumio[1224]: info: Ignoring mount for partition: volumio Dec 26 16:45:23 headphones volumio[1224]: info: Ignoring mount for partition: volumio_data Dec 26 16:45:23 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 16:45:23 headphones volumio[1224]: info: Loading plugin "volumio_command_line_client"... Dec 26 16:45:23 headphones volumio[1224]: info: Plugin upnp is not enabled Dec 26 16:45:23 headphones volumio[1224]: info: Loading plugin "my_music"... Dec 26 16:45:24 headphones volumio[1224]: info: Loading plugin "mpd"... Dec 26 16:45:24 headphones kernel: netfs: FS-Cache loaded Dec 26 16:45:24 headphones sudo[1345]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:24 headphones kernel: Key type cifs.spnego registered Dec 26 16:45:24 headphones kernel: Key type cifs.idmap registered Dec 26 16:45:24 headphones kernel: CIFS: Attempting to mount //192.168.50.116/download2 Dec 26 16:45:24 headphones 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 26 16:45:25 headphones sudo[1370]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:25 headphones kernel: CIFS: Attempting to mount //192.168.50.206/Music Dec 26 16:45:25 headphones sudo[1368]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:25 headphones volumio[1224]: info: Plugin upnp_browser is not enabled Dec 26 16:45:25 headphones volumio[1224]: info: Loading plugin "alarm-clock"... Dec 26 16:45:26 headphones volumio[1224]: info: Plugin airplay_emulation is not enabled Dec 26 16:45:26 headphones volumio[1224]: info: Loading plugin "last_100"... Dec 26 16:45:26 headphones volumio[1224]: info: Loading plugin "webradio"... Dec 26 16:45:27 headphones systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 26 16:45:27 headphones systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:45:27 headphones systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:45:27 headphones upmpdcli[1413]: Could not open config: /tmp/upmpdcli.conf Dec 26 16:45:27 headphones systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 16:45:27 headphones systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 16:45:31 headphones volumio[1224]: info: Loading plugin "i2s_dacs"... Dec 26 16:45:31 headphones volumio[1224]: info: Loading plugin "volumiodiscovery"... Dec 26 16:45:31 headphones volumio[1224]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 16:45:31 headphones volumio[1224]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 16:45:31 headphones volumio[1224]: *** WARNING *** For more information see Dec 26 16:45:31 headphones volumio[1224]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 16:45:31 headphones volumio[1224]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 16:45:31 headphones volumio[1224]: *** WARNING *** For more information see Dec 26 16:45:31 headphones node[1224]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 16:45:31 headphones node[1224]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 16:45:31 headphones node[1224]: *** WARNING *** For more information see Dec 26 16:45:31 headphones node[1224]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 16:45:31 headphones node[1224]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 16:45:31 headphones node[1224]: *** WARNING *** For more information see Dec 26 16:45:31 headphones volumio[1224]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 26 16:45:31 headphones volumio[1224]: info: Discovery: Started advertising with name: Headphones Dec 26 16:45:31 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 16:45:31 headphones volumio[1224]: info: Loading plugin "outputs"... Dec 26 16:45:31 headphones volumio[1224]: info: Loading plugin "albumart"... Dec 26 16:45:31 headphones volumio[1224]: info: Plugin example_plugin is not enabled Dec 26 16:45:31 headphones volumio[1224]: info: Loading plugin "inputs"... Dec 26 16:45:31 headphones volumio[1224]: info: Loading plugin "updater_comm"... Dec 26 16:45:32 headphones volumio[1224]: info: Plugin mpdemulation is not enabled Dec 26 16:45:32 headphones volumio[1224]: info: Loading plugin "rest_api"... Dec 26 16:45:32 headphones volumio[1224]: info: Loading plugin "websocket"... Dec 26 16:45:32 headphones volumio[1224]: info: Starting Socket.io Server version 1.7.4 Dec 26 16:45:32 headphones volumio[1224]: info: Loading i18n strings for locale en Dec 26 16:45:32 headphones volumio[1224]: Updating browse sources language Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::initPlayerControls Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:32 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 16:45:33 headphones volumio[1224]: Express server listening on port 3000 Dec 26 16:45:33 headphones volumio[1224]: [Metrics] WebUI: 19s 982.86ms Dec 26 16:45:33 headphones volumio[1224]: info: CoreStateMachine::resetVolumioState Dec 26 16:45:33 headphones volumio[1224]: info: CoreStateMachine::getcurrentVolume Dec 26 16:45:33 headphones volumio[1224]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 16:45:33 headphones volumio[1224]: info: CoreStateMachine::pushState Dec 26 16:45:33 headphones volumio[1224]: info: CorePlayQueue::getTrack 0 Dec 26 16:45:33 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 16:45:33 headphones volumio[1224]: info: CoreCommandRouter::volumioPushState Dec 26 16:45:33 headphones volumio[1224]: info: Volumio Network Manager: Network status updated: 2 Dec 26 16:45:34 headphones volumio[1224]: verbose: New Socket.io Connection to 192.168.50.45:3000 from 192.168.50.244 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 26 16:45:34 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 16:45:34 headphones volumio[1224]: verbose: New Socket.io Connection to 192.168.50.45:3000 from 192.168.50.244 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 26 16:45:35 headphones volumio[1224]: info: Setting Device type: Raspberry PI Dec 26 16:45:35 headphones volumio[1224]: info: CoreStateMachine::setRepeat false single undefined Dec 26 16:45:35 headphones volumio[1224]: info: CoreStateMachine::pushState Dec 26 16:45:35 headphones volumio[1224]: info: CorePlayQueue::getTrack 0 Dec 26 16:45:35 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 16:45:35 headphones volumio[1224]: info: CoreCommandRouter::volumioPushState Dec 26 16:45:35 headphones volumio[1224]: info: CoreStateMachine::setRandom null Dec 26 16:45:35 headphones volumio[1224]: info: CoreStateMachine::pushState Dec 26 16:45:35 headphones volumio[1224]: info: CorePlayQueue::getTrack 0 Dec 26 16:45:35 headphones volumio[1224]: info: CoreCommandRouter::volumioPushState Dec 26 16:45:35 headphones volumio[1224]: info: Completed loading Core Plugins Dec 26 16:45:35 headphones volumio[1224]: info: Preparing to generate the ALSA configuration file Dec 26 16:45:35 headphones volumio[1416]: Forking 3 albumart workers Dec 26 16:45:35 headphones volumio[1224]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 26 16:45:35 headphones volumio[1224]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Dec 26 16:45:35 headphones volumio[1224]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 26 16:45:35 headphones volumio[1224]: info: Discovery: adding 840b2736-09c9-40f2-90d4-911f72425c9a Dec 26 16:45:35 headphones volumio[1224]: info: Discovery: Found device Headphones Dec 26 16:45:35 headphones volumio[1224]: info: CoreCommandRouter::volumioGetState Dec 26 16:45:35 headphones volumio[1224]: info: CorePlayQueue::getTrack 0 Dec 26 16:45:35 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:45:35 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:45:35 headphones volumio[1224]: info: Discovery: Getting this device information Dec 26 16:45:35 headphones volumio[1224]: info: CoreCommandRouter::volumioGetState Dec 26 16:45:35 headphones volumio[1224]: info: CorePlayQueue::getTrack 0 Dec 26 16:45:35 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:45:36 headphones volumio[1224]: info: Asound.conf file unchanged, so no further update is needed Dec 26 16:45:36 headphones volumio[1224]: info: Output device has changed, restarting MPD Dec 26 16:45:36 headphones volumio[1224]: info: ___________ START PLUGINS ___________ Dec 26 16:45:36 headphones volumio[1224]: info: ControllerMpd::onStart: Initializing MPD Dec 26 16:45:36 headphones volumio[1224]: info: Creating MPD Configuration file Dec 26 16:45:36 headphones sudo[1481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 16:45:36 headphones sudo[1481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:36 headphones sudo[1481]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:36 headphones volumio[1224]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 16:45:36 headphones volumio[1224]: info: [1766796336862] CoreMusicLibrary::Adding element Last_100 Dec 26 16:45:36 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 16:45:36 headphones sudo[1483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 16:45:36 headphones sudo[1483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:36 headphones volumio[1224]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 16:45:36 headphones volumio[1224]: info: [1766796336919] CoreMusicLibrary::Adding element Webradio Dec 26 16:45:36 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 16:45:37 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 16:45:37 headphones sudo[1486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 16:45:37 headphones sudo[1486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:37 headphones sudo[1488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 16:45:37 headphones volumio[1224]: info: Initializing BBC Radios Dec 26 16:45:37 headphones sudo[1488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:37 headphones sudo[1486]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:37 headphones systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 16:45:37 headphones systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 16:45:37 headphones volumio-remote-updater[694]: [2025-12-26 16:45:37] [connect] Successful connection Dec 26 16:45:37 headphones systemd[1]: mpd.service: Deactivated successfully. Dec 26 16:45:37 headphones systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 16:45:37 headphones systemd[1]: mpd.socket: Deactivated successfully. Dec 26 16:45:37 headphones systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 16:45:37 headphones systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 16:45:37 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 16:45:37 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 16:45:37 headphones volumio[1224]: info: Volumio Calling Home Dec 26 16:45:37 headphones systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 16:45:37 headphones systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 16:45:38 headphones sudo[1512]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 16:45:38 headphones sudo[1512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 16:45:38 headphones sudo[1514]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 16:45:38 headphones sudo[1512]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:42 headphones systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Dec 26 16:45:42 headphones systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:45:42 headphones systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:45:42 headphones upmpdcli[1517]: Could not open config: /tmp/upmpdcli.conf Dec 26 16:45:42 headphones systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 16:45:42 headphones systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 16:45:50 headphones volumio[1224]: info: Discovery: adding e4988065-cb74-44a7-83a4-42382f28c06b Dec 26 16:45:50 headphones volumio[1224]: info: Discovery: Found device Speakers Dec 26 16:45:50 headphones volumio[1224]: info: Discovery: Connecting to remote: 192.168.50.244 Dec 26 16:45:50 headphones volumio[1224]: info: Discovery: this is already registered, 840b2736-09c9-40f2-90d4-911f72425c9a Dec 26 16:45:50 headphones volumio[1224]: info: Discovery: Found device Headphones Dec 26 16:45:50 headphones volumio[1224]: info: CoreCommandRouter::volumioGetState Dec 26 16:45:50 headphones volumio[1224]: info: CorePlayQueue::getTrack 0 Dec 26 16:45:50 headphones mpd[1515]: 2025-12-26T16:45:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 16:45:51 headphones systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 16:45:51 headphones sudo[1483]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:51 headphones sudo[1488]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:52 headphones volumio[1224]: info: Completed starting Core Plugins Dec 26 16:45:52 headphones volumio[1224]: info: ------------------------------------------- Dec 26 16:45:52 headphones volumio[1224]: info: ----- MyVolumio plugins startup ---- Dec 26 16:45:52 headphones volumio[1224]: info: ------------------------------------------- Dec 26 16:45:52 headphones volumio[1224]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 26 16:45:52 headphones volumio[1224]: info: MPD Permissions set Dec 26 16:45:52 headphones volumio[1224]: info: MPD Permissions set Dec 26 16:45:52 headphones volumio-remote-updater[694]: [2025-12-26 16:45:52] [connect] Successful connection Dec 26 16:45:52 headphones volumio[1224]: verbose: New Socket.io Connection to 192.168.50.45:3000 from 192.168.50.217 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Dec 26 16:45:53 headphones volumio[1224]: info: MPD running with PID1515 Dec 26 16:45:53 headphones volumio[1224]: ,establishing connection Dec 26 16:45:53 headphones volumio[1224]: info: Volumio called home Dec 26 16:45:54 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 16:45:54 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 16:45:56 headphones volumio[1224]: error: MPD error: The expression evaluated to a falsy value: Dec 26 16:45:56 headphones volumio[1224]: assert.ok(self.idling) Dec 26 16:45:56 headphones volumio[1224]: error: The expression evaluated to a falsy value: Dec 26 16:45:56 headphones volumio[1224]: assert.ok(self.idling) Dec 26 16:45:56 headphones volumio[1224]: error: MPD error: The expression evaluated to a falsy value: Dec 26 16:45:56 headphones volumio[1224]: assert.ok(self.idling) Dec 26 16:45:56 headphones volumio[1224]: error: The expression evaluated to a falsy value: Dec 26 16:45:56 headphones volumio[1224]: assert.ok(self.idling) Dec 26 16:45:56 headphones volumio[1224]: verbose: New Socket.io Connection to 192.168.50.45:3000 from 192.168.50.244 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 26 16:45:56 headphones volumio[1455]: Starting albumart workers Dec 26 16:45:56 headphones sudo[1552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 16:45:56 headphones sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:56 headphones sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 16:45:56 headphones sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:56 headphones systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 16:45:56 headphones mpd_monitor.sh[1557]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 16:45:56 headphones sudo[1552]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 16:45:56 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 16:45:57 headphones systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 16:45:57 headphones systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 16:45:57 headphones systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 16:45:57 headphones sudo[1554]: pam_unix(sudo:session): session closed for user root Dec 26 16:45:57 headphones mpd_monitor.sh[1568]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 16:45:57 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:45:57 headphones systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Dec 26 16:45:57 headphones systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:45:57 headphones systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:45:57 headphones volumio[1453]: Starting albumart workers Dec 26 16:45:57 headphones upmpdcli[1575]: Could not open config: /tmp/upmpdcli.conf Dec 26 16:45:57 headphones systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 16:45:57 headphones systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 16:45:58 headphones volumio[1454]: Starting albumart workers Dec 26 16:45:59 headphones volumio[1224]: info: Discovery: Connected to remote: 192.168.50.244 Dec 26 16:45:59 headphones volumio[1224]: error: updateQueue error: null Dec 26 16:45:59 headphones volumio[1224]: verbose: New Socket.io Connection to 192.168.50.45:3000 from 192.168.50.244 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 26 16:45:59 headphones volumio[1224]: info: Successfully started MPD Monitor Dec 26 16:45:59 headphones volumio[1224]: info: Successfully started MPD Monitor Dec 26 16:45:59 headphones volumio[1224]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 26 16:46:00 headphones volumio[1224]: verbose: New Socket.io Connection to 192.168.50.45:3000 from 192.168.50.217 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 26 16:46:00 headphones volumio[1224]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 26 16:46:01 headphones volumio[1224]: verbose: New Socket.io Connection to 192.168.50.45:3000 from 192.168.50.244 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 26 16:46:07 headphones volumio-remote-updater[694]: [2025-12-26 16:46:07] [connect] Successful connection Dec 26 16:46:10 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:46:10 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:46:10 headphones volumio[1224]: info: Discovery: Getting this device information Dec 26 16:46:10 headphones volumio[1224]: info: CoreCommandRouter::volumioGetState Dec 26 16:46:10 headphones volumio[1224]: info: CorePlayQueue::getTrack 0 Dec 26 16:46:10 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:46:11 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 16:46:11 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 16:46:11 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 16:46:12 headphones systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Dec 26 16:46:12 headphones systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:46:12 headphones systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:46:13 headphones upmpdcli[1613]: Could not open config: /tmp/upmpdcli.conf Dec 26 16:46:13 headphones systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 16:46:13 headphones systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 16:46:13 headphones volumio[1224]: error: MyVolumio Plugin failed to start in a timely fashion Dec 26 16:46:13 headphones volumio[1224]: [Metrics] CommandRouter: 58s 6.67ms Dec 26 16:46:13 headphones volumio[1224]: info: CoreCommandRouter::volumiosetStartupVolume Dec 26 16:46:13 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 16:46:13 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 16:46:13 headphones volumio[1224]: info: CoreCommandRouter::Close All Modals sent Dec 26 16:46:13 headphones volumio[1224]: info: CoreCommandRouter::Close All Modals sent Dec 26 16:46:22 headphones volumio-remote-updater[694]: [2025-12-26 16:46:22] [connect] Successful connection Dec 26 16:46:24 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 26 16:46:24 headphones volumio[1224]: info: CoreCommandRouter::volumioGetState Dec 26 16:46:24 headphones volumio[1224]: info: CorePlayQueue::getTrack 0 Dec 26 16:46:25 headphones volumio[1224]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 26 16:46:25 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 26 16:46:25 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 16:46:25 headphones volumio[1224]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 26 16:46:25 headphones volumio[1224]: info: Version has changed, forcing UI Reload Dec 26 16:46:25 headphones volumio[1224]: info: CoreCommandRouter::Reload Ui Dec 26 16:46:26 headphones volumio[1224]: info: BOOT COMPLETED Dec 26 16:46:26 headphones volumio[1224]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 16:46:26 headphones volumio[1224]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 26 16:46:26 headphones volumio[1224]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 26 16:46:26 headphones volumio[1224]: at new NodeError (node:internal/errors:405:5) Dec 26 16:46:26 headphones volumio[1224]: at assert (node:internal/assert:14:11) Dec 26 16:46:26 headphones volumio[1224]: at internalConnectMultiple (node:net:1118:3) Dec 26 16:46:26 headphones volumio[1224]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 26 16:46:26 headphones volumio[1224]: at listOnTimeout (node:internal/timers:575:11) Dec 26 16:46:26 headphones volumio[1224]: at process.processTimers (node:internal/timers:514:7) { Dec 26 16:46:26 headphones volumio[1224]: code: 'ERR_INTERNAL_ASSERTION' Dec 26 16:46:26 headphones volumio[1224]: } Dec 26 16:46:26 headphones volumio[1224]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 16:46:28 headphones systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Dec 26 16:46:28 headphones systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:46:28 headphones systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 16:46:28 headphones upmpdcli[1659]: Could not open config: /tmp/upmpdcli.conf Dec 26 16:46:28 headphones systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 16:46:28 headphones systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 16:46:37 headphones sudo[1676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 16:45' Dec 26 16:46:37 headphones sudo[1676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:46:37 headphones volumio-remote-updater[694]: [2025-12-26 16:46:37] [connect] Successful connection 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"