Dec 23 14:56:59 volumiodsp ntpd[990]: CLOCK: time stepped by 66.338146 Dec 23 14:56:59 volumiodsp ntpd[990]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 23 14:56:59 volumiodsp kernel: netfs: FS-Cache loaded Dec 23 14:56:59 volumiodsp volumio[1270]: info: Loading plugin "mpd"... Dec 23 14:56:59 volumiodsp kernel: Key type cifs.spnego registered Dec 23 14:56:59 volumiodsp kernel: Key type cifs.idmap registered Dec 23 14:56:59 volumiodsp 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 23 14:56:59 volumiodsp kernel: CIFS: Attempting to mount //192.168.1.4/Multimedia Dec 23 14:56:59 volumiodsp systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 23 14:57:00 volumiodsp volumio[1270]: info: Plugin upnp_browser is not enabled Dec 23 14:57:00 volumiodsp volumio[1270]: info: Loading plugin "alarm-clock"... Dec 23 14:57:00 volumiodsp volumio[1270]: info: Plugin airplay_emulation is not enabled Dec 23 14:57:00 volumiodsp volumio[1270]: info: Loading plugin "last_100"... Dec 23 14:57:00 volumiodsp volumio[1270]: info: Loading plugin "webradio"... Dec 23 14:57:00 volumiodsp sudo[1390]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:00 volumiodsp dhcpcd[704]: timed out Dec 23 14:57:00 volumiodsp sh[683]: timed out Dec 23 14:57:00 volumiodsp sh[625]: ifup: failed to bring up eth0 Dec 23 14:57:00 volumiodsp systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 23 14:57:00 volumiodsp systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 23 14:57:01 volumiodsp volumio[1270]: info: Loading plugin "i2s_dacs"... Dec 23 14:57:01 volumiodsp volumio[1270]: info: I2S DAC not set, start Auto-detection Dec 23 14:57:01 volumiodsp volumio[1270]: info: Loading plugin "volumiodiscovery"... Dec 23 14:57:01 volumiodsp volumio[1270]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 23 14:57:01 volumiodsp volumio[1270]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 14:57:01 volumiodsp volumio[1270]: *** WARNING *** For more information see Dec 23 14:57:01 volumiodsp volumio[1270]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 23 14:57:01 volumiodsp volumio[1270]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 14:57:01 volumiodsp node[1270]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 23 14:57:01 volumiodsp volumio[1270]: *** WARNING *** For more information see Dec 23 14:57:01 volumiodsp node[1270]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 14:57:01 volumiodsp node[1270]: *** WARNING *** For more information see Dec 23 14:57:01 volumiodsp node[1270]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 23 14:57:01 volumiodsp node[1270]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 14:57:01 volumiodsp node[1270]: *** WARNING *** For more information see Dec 23 14:57:01 volumiodsp volumio[1270]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 23 14:57:01 volumiodsp volumio[1270]: info: Discovery: Started advertising with name: VolumioDSP Dec 23 14:57:01 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 14:57:01 volumiodsp volumio[1270]: info: Loading plugin "gpio-buttons"... Dec 23 14:57:01 volumiodsp volumio[1270]: gpiox_arm32 V2.2.1 Dec 23 14:57:01 volumiodsp systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 23 14:57:02 volumiodsp volumio[1270]: info: gpio-buttons - GPIO-Buttons initialized Dec 23 14:57:02 volumiodsp volumio[1270]: info: Loading plugin "now_playing"... Dec 23 14:57:02 volumiodsp systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 23 14:57:02 volumiodsp systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 14:57:02 volumiodsp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 14:57:02 volumiodsp upmpdcli[1423]: Could not open config: /tmp/upmpdcli.conf Dec 23 14:57:02 volumiodsp systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 23 14:57:02 volumiodsp systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 23 14:57:02 volumiodsp sudo[1355]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:04 volumiodsp volumio-remote-updater[726]: [2025-12-23 14:57:04] [connect] Successful connection Dec 23 14:57:04 volumiodsp volumio[1270]: info: Loading plugin "outputs"... Dec 23 14:57:04 volumiodsp volumio[1270]: info: Loading plugin "albumart"... Dec 23 14:57:04 volumiodsp volumio[1270]: info: Plugin example_plugin is not enabled Dec 23 14:57:04 volumiodsp volumio[1270]: info: Loading plugin "inputs"... Dec 23 14:57:04 volumiodsp volumio[1270]: info: Loading plugin "updater_comm"... Dec 23 14:57:04 volumiodsp volumio[1270]: info: Plugin mpdemulation is not enabled Dec 23 14:57:04 volumiodsp volumio[1270]: info: Loading plugin "rest_api"... Dec 23 14:57:04 volumiodsp volumio[1270]: info: Loading plugin "websocket"... Dec 23 14:57:04 volumiodsp volumio[1270]: info: Starting Socket.io Server version 1.7.4 Dec 23 14:57:04 volumiodsp volumio[1270]: info: Loading plugin "fusiondsp"... Dec 23 14:57:05 volumiodsp volumio[1425]: Forking 3 albumart workers Dec 23 14:57:05 volumiodsp volumio[1270]: info: Applying required configuration parameters for plugin fusiondsp Dec 23 14:57:06 volumiodsp volumio[1270]: info: Loading plugin "backup_restore"... Dec 23 14:57:08 volumiodsp volumio[1270]: info: Applying required configuration parameters for plugin backup_restore Dec 23 14:57:08 volumiodsp volumio[1270]: info: Loading plugin "gpio_control"... Dec 23 14:57:09 volumiodsp volumio[1270]: gpiox_arm32 V2.2.1 Dec 23 14:57:09 volumiodsp volumio[1436]: Starting albumart workers Dec 23 14:57:11 volumiodsp volumio[1435]: Starting albumart workers Dec 23 14:57:11 volumiodsp volumio[1437]: Starting albumart workers Dec 23 14:57:11 volumiodsp volumio[1270]: info: Applying required configuration parameters for plugin gpio_control Dec 23 14:57:11 volumiodsp volumio[1270]: info: Loading plugin "Systeminfo"... Dec 23 14:57:13 volumiodsp volumio[1270]: info: Loading i18n strings for locale it Dec 23 14:57:13 volumiodsp volumio[1270]: Updating browse sources language Dec 23 14:57:13 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::initPlayerControls Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 14:57:14 volumiodsp volumio[1270]: Express server listening on port 3000 Dec 23 14:57:14 volumiodsp volumio[1270]: [Metrics] WebUI: 19s 402.23ms Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreStateMachine::resetVolumioState Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreStateMachine::getcurrentVolume Dec 23 14:57:14 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::getcurrentVolume Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 14:57:15 volumiodsp volumio[1270]: info: Volumio Network Manager: Network status updated: 2 Dec 23 14:57:15 volumiodsp volumio[1270]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 23 14:57:15 volumiodsp systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 23 14:57:15 volumiodsp volumio[1270]: verbose: New Socket.io Connection to 192.168.1.71:3000 from 192.168.1.15 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 23 14:57:15 volumiodsp volumio[1270]: info: VolumeController:: Volume=undefined Mute =false Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::pushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioPushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::updateTrackBlock Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrackBlock Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 14:57:15 volumiodsp volumio[1270]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 23 14:57:15 volumiodsp volumio[1270]: info: VolumeController:: Volume=undefined Mute =false Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::pushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioPushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::updateTrackBlock Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrackBlock Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 14:57:15 volumiodsp volumio[1270]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 23 14:57:15 volumiodsp volumio[1270]: info: Reloading queue from file Dec 23 14:57:15 volumiodsp volumio[1270]: info: VolumeController:: Volume=undefined Mute =false Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::pushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioPushState Dec 23 14:57:15 volumiodsp volumio[1270]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 14:57:15 volumiodsp volumio[1270]: info: Discovery: Getting this device information Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioGetState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::setRepeat null single undefined Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::pushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioPushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::setRandom null Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::pushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioPushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: Setting Device type: Raspberry PI Dec 23 14:57:15 volumiodsp volumio[1270]: info: VolumeController:: Volume=undefined Mute =false Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreStateMachine::pushState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioPushState Dec 23 14:57:15 volumiodsp volumio[1270]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 23 14:57:15 volumiodsp volumio[1270]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 23 14:57:15 volumiodsp volumio[1270]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 23 14:57:15 volumiodsp volumio[1270]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Dec 23 14:57:15 volumiodsp volumio[1270]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 14:57:15 volumiodsp volumio[1270]: info: Discovery: Getting this device information Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioGetState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 14:57:15 volumiodsp volumio[1270]: info: Discovery: adding 0e204b5a-b58f-410f-a564-388cbe07f5a4 Dec 23 14:57:15 volumiodsp volumio[1270]: info: Discovery: Found device VolumioDSP Dec 23 14:57:15 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioGetState Dec 23 14:57:15 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:16 volumiodsp volumio[1270]: info: Discovery: this is already registered, 0e204b5a-b58f-410f-a564-388cbe07f5a4 Dec 23 14:57:16 volumiodsp volumio[1270]: info: Discovery: Found device VolumioDSP Dec 23 14:57:16 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioGetState Dec 23 14:57:16 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:16 volumiodsp volumio[1270]: info: Completed loading Core Plugins Dec 23 14:57:16 volumiodsp volumio[1270]: info: Preparing to generate the ALSA configuration file Dec 23 14:57:16 volumiodsp volumio[1270]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 23 14:57:16 volumiodsp volumio[1270]: info: Reading ALSA contributions from plugins. Dec 23 14:57:16 volumiodsp volumio[1270]: info: Asound.conf file unchanged, so no further update is needed Dec 23 14:57:16 volumiodsp volumio[1270]: info: Output device has changed, restarting MPD Dec 23 14:57:16 volumiodsp volumio[1270]: info: ___________ START PLUGINS ___________ Dec 23 14:57:16 volumiodsp volumio[1270]: info: ControllerMpd::onStart: Initializing MPD Dec 23 14:57:16 volumiodsp volumio[1270]: info: Creating MPD Configuration file Dec 23 14:57:16 volumiodsp sudo[1534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 14:57:16 volumiodsp sudo[1534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:16 volumiodsp sudo[1536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 14:57:16 volumiodsp sudo[1534]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:16 volumiodsp sudo[1536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:16 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 14:57:16 volumiodsp volumio[1270]: info: [1766498236842] CoreMusicLibrary::Adding element Last_100 Dec 23 14:57:16 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 14:57:16 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 14:57:16 volumiodsp volumio[1270]: info: [1766498236887] CoreMusicLibrary::Adding element Webradio Dec 23 14:57:16 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 14:57:16 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 14:57:16 volumiodsp volumio[1270]: info: Initializing BBC Radios Dec 23 14:57:16 volumiodsp sudo[1541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 14:57:16 volumiodsp sudo[1541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:16 volumiodsp sudo[1540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 14:57:16 volumiodsp sudo[1540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:16 volumiodsp sudo[1541]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:17 volumiodsp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 23 14:57:17 volumiodsp systemd[1]: Starting mpd.service - Music Player Daemon... Dec 23 14:57:17 volumiodsp systemd[1]: mpd.service: Deactivated successfully. Dec 23 14:57:17 volumiodsp systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 23 14:57:17 volumiodsp systemd[1]: mpd.socket: Deactivated successfully. Dec 23 14:57:17 volumiodsp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 23 14:57:17 volumiodsp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 23 14:57:17 volumiodsp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 23 14:57:17 volumiodsp systemd[1]: Starting mpd.service - Music Player Daemon... Dec 23 14:57:17 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 14:57:17 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 14:57:17 volumiodsp volumio[1270]: info: gpio-buttons - Initializing GPIO triggers... Dec 23 14:57:17 volumiodsp volumio[1270]: info: gpio-buttons - ✅ GPIO3 configured for shutdown Dec 23 14:57:17 volumiodsp sudo[1555]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 14:57:17 volumiodsp sudo[1555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 23 14:57:17 volumiodsp sudo[1563]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 23 14:57:17 volumiodsp sudo[1555]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:17 volumiodsp volumio[1270]: info: [now-playing] ConfigUpdater: config is up to date. Dec 23 14:57:17 volumiodsp volumio[1270]: info: FusionDsp - CamillaDSP binary found. Dec 23 14:57:17 volumiodsp volumio[1270]: info: Loading i18n strings for locale it Dec 23 14:57:17 volumiodsp systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 23 14:57:17 volumiodsp systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 14:57:17 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 23 14:57:17 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:17 volumiodsp volumio[1270]: info: FusionDsp - mixtype--------------------- undefined Dec 23 14:57:17 volumiodsp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 14:57:17 volumiodsp volumio[1270]: info: Volumio Calling Home Dec 23 14:57:17 volumiodsp upmpdcli[1565]: Could not open config: /tmp/upmpdcli.conf Dec 23 14:57:17 volumiodsp systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 23 14:57:17 volumiodsp systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 23 14:57:17 volumiodsp volumio[1270]: info: gpio-buttons - GPIO-Buttons started Dec 23 14:57:17 volumiodsp volumio[1270]: info: Preparing to generate the ALSA configuration file Dec 23 14:57:19 volumiodsp volumio-remote-updater[726]: [2025-12-23 14:57:19] [connect] Successful connection Dec 23 14:57:19 volumiodsp volumio[1270]: info: [now-playing] App is listening on port 4004. Dec 23 14:57:19 volumiodsp volumio[1270]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Dec 23 14:57:19 volumiodsp systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 23 14:57:19 volumiodsp systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 23 14:57:20 volumiodsp volumio[1270]: info: camilladsp service started and running in background, instance 1 Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for Pro53010480 Dec 23 14:57:20 volumiodsp volumio[1270]: cannot open device 'volumioHw': No such device Dec 23 14:57:20 volumiodsp volumio[1270]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 23 14:57:20 volumiodsp volumio[1270]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for Pro53010480 Dec 23 14:57:20 volumiodsp volumio[1270]: cannot open device 'volumioHw': No such device Dec 23 14:57:20 volumiodsp volumio[1270]: info: FusionDsp loaded Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 14:57:20 volumiodsp volumio[1270]: error: FusionDsp - Socket connection not established Dec 23 14:57:20 volumiodsp volumio[1270]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 23 14:57:20 volumiodsp volumio[1270]: info: Adding Signal Path Element [object Object] Dec 23 14:57:20 volumiodsp volumio[1270]: info: Adding fusiondspeq DSP Signal Path Element Dec 23 14:57:20 volumiodsp volumio[1270]: info: FusionDsp - ---- installed callbackRead Dec 23 14:57:20 volumiodsp volumio[1270]: error: FusionDsp - File size not found in array! Dec 23 14:57:20 volumiodsp volumio[1270]: error: FusionDsp - Error in eqv! Cannot split values! Dec 23 14:57:20 volumiodsp volumio[1270]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"} Dec 23 14:57:20 volumiodsp sudo[1604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 23 14:57:20 volumiodsp sudo[1604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:20 volumiodsp volumio[1270]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 23 14:57:20 volumiodsp volumio[1270]: info: Reading ALSA contributions from plugins. Dec 23 14:57:20 volumiodsp volumio[1270]: info: MPD Permissions set Dec 23 14:57:20 volumiodsp volumio[1270]: info: MPD Permissions set Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioGetState Dec 23 14:57:20 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:20 volumiodsp volumio-remote-updater[726]: [2025-12-23 14:57:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766498239 101 Dec 23 14:57:20 volumiodsp volumio[1270]: 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: 4 Dec 23 14:57:20 volumiodsp systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 14:57:20 volumiodsp volumio[1270]: info: Discovery: Getting this device information Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioGetState Dec 23 14:57:20 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 14:57:20 volumiodsp sudo[1604]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:20 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:21 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 14:57:21 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:21 volumiodsp volumio[1270]: 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 23 14:57:21 volumiodsp volumio[1270]: info: Volumio called home Dec 23 14:57:21 volumiodsp mpd[1564]: 2025-12-23T14:57:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 23 14:57:21 volumiodsp volumio[1270]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 23 14:57:22 volumiodsp systemd[1]: Started mpd.service - Music Player Daemon. Dec 23 14:57:22 volumiodsp sudo[1536]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:22 volumiodsp sudo[1540]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:22 volumiodsp volumio[1270]: verbose: New Socket.io Connection to 192.168.1.71:3000 from 192.168.1.121 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 23 14:57:22 volumiodsp volumio[1270]: info: Completed starting Core Plugins Dec 23 14:57:22 volumiodsp volumio[1270]: info: ------------------------------------------- Dec 23 14:57:22 volumiodsp volumio[1270]: info: ----- MyVolumio plugins startup ---- Dec 23 14:57:22 volumiodsp volumio[1270]: info: ------------------------------------------- Dec 23 14:57:22 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 23 14:57:22 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 23 14:57:22 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 23 14:57:22 volumiodsp volumio[1270]: info: MPD running with PID1564 Dec 23 14:57:22 volumiodsp volumio[1270]: ,establishing connection Dec 23 14:57:23 volumiodsp volumio[1270]: error: MPD error: The expression evaluated to a falsy value: Dec 23 14:57:23 volumiodsp volumio[1270]: assert.ok(self.idling) Dec 23 14:57:23 volumiodsp volumio[1270]: error: The expression evaluated to a falsy value: Dec 23 14:57:23 volumiodsp volumio[1270]: assert.ok(self.idling) Dec 23 14:57:23 volumiodsp volumio[1270]: error: MPD error: The expression evaluated to a falsy value: Dec 23 14:57:23 volumiodsp volumio[1270]: assert.ok(self.idling) Dec 23 14:57:23 volumiodsp volumio[1270]: error: The expression evaluated to a falsy value: Dec 23 14:57:23 volumiodsp volumio[1270]: assert.ok(self.idling) Dec 23 14:57:23 volumiodsp volumio[1270]: info: Asound.conf file unchanged, so no further update is needed Dec 23 14:57:23 volumiodsp volumio[1270]: info: Output device has changed, restarting MPD Dec 23 14:57:23 volumiodsp sudo[1627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 14:57:23 volumiodsp sudo[1627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:23 volumiodsp sudo[1627]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:23 volumiodsp sudo[1629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 14:57:23 volumiodsp volumio[1270]: error: updateQueue error: null Dec 23 14:57:23 volumiodsp sudo[1629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:23 volumiodsp systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 23 14:57:23 volumiodsp volumio[1270]: info: MPD Permissions set Dec 23 14:57:23 volumiodsp volumio[1270]: verbose: New Socket.io Connection to 192.168.1.71:3000 from 192.168.1.121 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:23 volumiodsp systemd[1]: mpd.service: Deactivated successfully. Dec 23 14:57:23 volumiodsp systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 23 14:57:23 volumiodsp systemd[1]: mpd.service: Consumed 3.257s CPU time. Dec 23 14:57:23 volumiodsp systemd[1]: mpd.socket: Deactivated successfully. Dec 23 14:57:23 volumiodsp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 23 14:57:23 volumiodsp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 14:57:23 volumiodsp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 14:57:23 volumiodsp volumio[1270]: info: Discovery: Getting this device information Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioGetState Dec 23 14:57:23 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 14:57:23 volumiodsp systemd[1]: Starting mpd.service - Music Player Daemon... Dec 23 14:57:23 volumiodsp volumio[1270]: info: CoreCommandRouter::volumioGetState Dec 23 14:57:23 volumiodsp volumio[1270]: info: CorePlayQueue::getTrack 0 Dec 23 14:57:23 volumiodsp sudo[1638]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 14:57:23 volumiodsp sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 23 14:57:23 volumiodsp sudo[1638]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:25 volumiodsp mpd[1640]: 2025-12-23T14:57:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 23 14:57:25 volumiodsp systemd[1]: Started mpd.service - Music Player Daemon. Dec 23 14:57:25 volumiodsp sudo[1629]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:25 volumiodsp volumio[1270]: error: updateQueue error: null Dec 23 14:57:25 volumiodsp sudo[1644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 23 14:57:25 volumiodsp sudo[1644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:25 volumiodsp systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 23 14:57:25 volumiodsp mpd_monitor.sh[1646]: MPD Monitor Service: Starting MPD Monitor Service Dec 23 14:57:25 volumiodsp sudo[1644]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:25 volumiodsp volumio[1270]: info: Successfully started MPD Monitor Dec 23 14:57:25 volumiodsp sudo[1650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 23 14:57:25 volumiodsp sudo[1650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:25 volumiodsp systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 23 14:57:25 volumiodsp systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 23 14:57:25 volumiodsp systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 23 14:57:25 volumiodsp systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 23 14:57:25 volumiodsp mpd_monitor.sh[1653]: MPD Monitor Service: Starting MPD Monitor Service Dec 23 14:57:25 volumiodsp sudo[1650]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:25 volumiodsp volumio[1270]: info: Successfully started MPD Monitor Dec 23 14:57:28 volumiodsp volumio[1270]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 23 14:57:30 volumiodsp sudo[1672]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 23 14:57:30 volumiodsp sudo[1672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:30 volumiodsp systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 23 14:57:30 volumiodsp systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 23 14:57:30 volumiodsp systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 23 14:57:30 volumiodsp systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 23 14:57:30 volumiodsp mpd_monitor.sh[1674]: MPD Monitor Service: Starting MPD Monitor Service Dec 23 14:57:30 volumiodsp sudo[1672]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:31 volumiodsp volumio[1270]: info: Successfully started MPD Monitor Dec 23 14:57:32 volumiodsp systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Dec 23 14:57:32 volumiodsp systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 14:57:32 volumiodsp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 14:57:32 volumiodsp upmpdcli[1678]: Could not open config: /tmp/upmpdcli.conf Dec 23 14:57:32 volumiodsp systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 23 14:57:32 volumiodsp systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 23 14:57:42 volumiodsp volumio[1270]: Cannot compose Albumart path Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 23 14:57:45 volumiodsp volumio[1270]: info: Adding plugin bluetooth to MyMusic Plugins Dec 23 14:57:45 volumiodsp volumio[1270]: info: Adding plugin multiroom to MyMusic Plugins Dec 23 14:57:45 volumiodsp volumio[1270]: info: Adding plugin metavolumio to MyMusic Plugins Dec 23 14:57:45 volumiodsp volumio[1270]: info: Adding plugin cd_controller to MyMusic Plugins Dec 23 14:57:45 volumiodsp volumio[1270]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 23 14:57:45 volumiodsp volumio[1270]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 23 14:57:45 volumiodsp volumio[1270]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 23 14:57:45 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 23 14:57:48 volumiodsp systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Dec 23 14:57:48 volumiodsp systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 14:57:48 volumiodsp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 14:57:48 volumiodsp upmpdcli[1693]: Could not open config: /tmp/upmpdcli.conf Dec 23 14:57:48 volumiodsp systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 23 14:57:48 volumiodsp systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 23 14:57:48 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 23 14:57:48 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 23 14:57:48 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 14:57:48 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 14:57:48 volumiodsp volumio[1270]: info: Starting MyVolumio Remote Streaming Endpoints Dec 23 14:57:48 volumiodsp volumio[1270]: info: MyVolumio login type: Token Dec 23 14:57:48 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 23 14:57:48 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 23 14:57:50 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 23 14:57:50 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 23 14:57:50 volumiodsp volumio[1270]: info: Streaming services startup Dec 23 14:57:50 volumiodsp volumio[1270]: info: Starting Streaming Daemon Dec 23 14:57:50 volumiodsp volumio[1270]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 23 14:57:50 volumiodsp sudo[1695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 23 14:57:50 volumiodsp sudo[1695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 14:57:50 volumiodsp sudo[1695]: pam_unix(sudo:session): session closed for user root Dec 23 14:57:50 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 14:57:50 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 14:57:50 volumiodsp volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 14:57:50 volumiodsp volumio[1270]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 14:57:50 volumiodsp volumio[1270]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 23 14:57:50 volumiodsp volumio[1270]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 23 14:57:50 volumiodsp volumio[1270]: at new NodeError (node:internal/errors:405:5) Dec 23 14:57:50 volumiodsp volumio[1270]: at assert (node:internal/assert:14:11) Dec 23 14:57:50 volumiodsp volumio[1270]: at internalConnectMultiple (node:net:1118:3) Dec 23 14:57:50 volumiodsp volumio[1270]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 23 14:57:50 volumiodsp volumio[1270]: at listOnTimeout (node:internal/timers:575:11) Dec 23 14:57:50 volumiodsp volumio[1270]: at process.processTimers (node:internal/timers:514:7) { Dec 23 14:57:50 volumiodsp volumio[1270]: code: 'ERR_INTERNAL_ASSERTION' Dec 23 14:57:50 volumiodsp volumio[1270]: } Dec 23 14:57:50 volumiodsp volumio[1270]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 14:57:53 volumiodsp sudo[1735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-23 14:56' Dec 23 14:57:54 volumiodsp sudo[1735]: 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="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"