-- Logs begin at Fri 2025-12-26 12:58:12 EET, end at Fri 2025-12-26 13:18:08 EET. -- Dec 26 13:17:00 volumio-dac sudo[25952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:00 volumio-dac sudo[25952]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:00 volumio-dac sudo[25950]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:00 volumio-dac volumio[25847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:00 volumio-dac volumio[25847]: info: ___________ START PLUGINS ___________ Dec 26 13:17:00 volumio-dac volumio[25847]: info: ControllerMpd::onStart: Initializing MPD Dec 26 13:17:00 volumio-dac volumio[25847]: info: Creating MPD Configuration file Dec 26 13:17:00 volumio-dac systemd[1]: Stopping Music Player Daemon... Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:00 volumio-dac volumio[25847]: info: [1766747820104] CoreMusicLibrary::Adding element Media Servers Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:00 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:00 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:00 volumio-dac volumio[25847]: info: UPNP Browser: Client initialized successfully Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:00 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:00 volumio-dac sudo[25961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:00 volumio-dac sudo[25961]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:00 volumio-dac sudo[25960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:17:00 volumio-dac sudo[25960]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:00 volumio-dac sudo[25960]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:00 volumio-dac systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 26 13:17:00 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:00 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:00 volumio-dac volumio[25847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:00 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:00 volumio-dac volumio[25847]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:00 volumio-dac volumio[25847]: info: [1766747820321] CoreMusicLibrary::Adding element Last_100 Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:00 volumio-dac volumio[25847]: info: [1766747820326] CoreMusicLibrary::Adding element Webradio Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:00 volumio-dac volumio[25847]: info: Initializing BBC Radios Dec 26 13:17:00 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:00 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1302. Dec 26 13:17:00 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:00 volumio-dac sudo[25968]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 13:17:00 volumio-dac sudo[25968]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:00 volumio-dac sudo[25968]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:00 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:00 volumio-dac volumio[25847]: info: [1766747820531] CoreMusicLibrary::Adding element Mixcloud Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:00 volumio-dac volumio[25847]: Cannot find translation for source Mixcloud Dec 26 13:17:00 volumio-dac volumio[25977]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:00 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:00 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:00 volumio-dac volumio[25847]: info: Creating Spotify config file Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:00 volumio-dac volumio[25847]: info: Loading i18n strings for locale es Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 26 13:17:00 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:00 volumio-dac volumio[25847]: info: FusionDsp - mixtype--------------------- Hardware Dec 26 13:17:00 volumio-dac volumio[25847]: info: Volumio Calling Home Dec 26 13:17:00 volumio-dac volumio[25847]: info: Preparing to generate the ALSA configuration file Dec 26 13:17:01 volumio-dac volumio[25847]: info: Discovery: adding f59de741-b187-4a9b-8bce-02c45f5e940d Dec 26 13:17:01 volumio-dac volumio[25847]: info: Discovery: Found device Volumio DAC Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:01 volumio-dac volumio[25847]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:01 volumio-dac volumio[25847]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 13:17:01 volumio-dac volumio[25847]: info: Reading ALSA contributions from plugins. Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:01 volumio-dac volumio[25847]: info: MPD Permissions set Dec 26 13:17:01 volumio-dac volumio[25847]: info: MPD Permissions set Dec 26 13:17:01 volumio-dac volumio[25847]: info: VolumeController:: Volume=0 Mute =false Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreStateMachine::pushState Dec 26 13:17:01 volumio-dac volumio[25847]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:01 volumio-dac volumio[25847]: info: Spotify config file written Dec 26 13:17:01 volumio-dac sudo[26015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 26 13:17:01 volumio-dac sudo[26015]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:01 volumio-dac systemd[1]: Stopping go-librespot Daemon... Dec 26 13:17:01 volumio-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 26 13:17:01 volumio-dac systemd[1]: go-librespot-daemon.service: Succeeded. Dec 26 13:17:01 volumio-dac systemd[1]: Stopped go-librespot Daemon. Dec 26 13:17:01 volumio-dac volumio[25847]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:01 volumio-dac volumio[25847]: info: No need to fix Spotify hosts Dec 26 13:17:01 volumio-dac systemd[1]: Started go-librespot Daemon. Dec 26 13:17:01 volumio-dac sudo[26015]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:01 volumio-dac go-librespot[26017]: go-librespot daemon starting... Dec 26 13:17:01 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:01+02:00" level=info msg="running go-librespot 0.4.0" Dec 26 13:17:01 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:01+02:00" level=debug msg="app state loaded" Dec 26 13:17:01 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 13:17:01 volumio-dac volumio[25847]: info: Volumio called home Dec 26 13:17:01 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:01 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:01 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 13:17:01 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:01+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 13:17:01 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:01+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 13:17:01 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:01+02:00" level=info msg="zeroconf server listening on port 45177" Dec 26 13:17:02 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:02+02:00" level=debug msg="obtained new client token: AACykFxsk0RPY1YS7T4V2LvoH3JaxRQv2gu/LicwIQRIZk9gcm3hSIxWEs/copOdYL7t76PGvAeYOp03MeL49JOEn/k4CXscUI31W1JO32xPa/Bwd6NxhWsAjLt4edgXrLQSq7ALn2AdHlwFGigoTQazgJtXamxqSH25fPkGyHMua3NqgVSVaErhpHvFGBkqb5DYXKJ7upoptisq/qCRTQwrPA4PQ0W0dHTmL7A6F736+iXN1fpMGO0A" Dec 26 13:17:02 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:02 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:02+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 26 13:17:02 volumio-dac go-librespot[26017]: time="2025-12-26T13:17:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 26 13:17:02 volumio-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 13:17:02 volumio-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 13:17:02 volumio-dac mpd[25981]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 13:17:02 volumio-dac mpd[25981]: output: No 'audio_output' defined in config file Dec 26 13:17:02 volumio-dac mpd[25981]: output: Successfully detected a sndio audio device Dec 26 13:17:02 volumio-dac mpd[25981]: zeroconf: No global port, disabling zeroconf Dec 26 13:17:02 volumio-dac systemd[1]: Started Music Player Daemon. Dec 26 13:17:02 volumio-dac sudo[25961]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:02 volumio-dac sudo[25952]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:02 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:02 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:02 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:02 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1303. Dec 26 13:17:02 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:02 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:02 volumio-dac volumio[26036]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:02 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:02 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:02 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:03 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:03 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:03 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:03 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:03 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:04 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:04 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:04 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:04 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:04 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:04 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:04 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:04 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1304. Dec 26 13:17:04 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:04 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:04 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:04 volumio-dac volumio[26037]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:04 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:04 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:05 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:05 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:05 volumio-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 13:17:05 volumio-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 26 13:17:05 volumio-dac systemd[1]: Stopped go-librespot Daemon. Dec 26 13:17:05 volumio-dac systemd[1]: Started go-librespot Daemon. Dec 26 13:17:05 volumio-dac go-librespot[26052]: go-librespot daemon starting... Dec 26 13:17:05 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:05 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:05+02:00" level=info msg="running go-librespot 0.4.0" Dec 26 13:17:05 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:05+02:00" level=debug msg="app state loaded" Dec 26 13:17:05 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 13:17:05 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:05 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 13:17:05 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:05+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 13:17:05 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:05+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 13:17:05 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:05 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:05+02:00" level=info msg="zeroconf server listening on port 35135" Dec 26 13:17:05 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:05+02:00" level=debug msg="obtained new client token: AAA0lOBUWbreEp9plhDeXVoTUAuPLs48gRLzmB5c9FPalkqk+SdDGK7OXSZu8D8Bl7c8/hCmticS+pSEtqP9dEvCt11ovbHQIqH5oK3XQDDVDYTMIQfF3iNZLuWcY+Bxnrp50eVR5led7g+3zyq4AP3nEtWlhqOXKVr0ZuLLnHKMHmALJUMCWjpAi09nRtUBjVXSJOU4D0laVuiWtsjhnSHAf9WRu9rgjAXFFDVy1RQ5tBj/Uea4BHu0" Dec 26 13:17:05 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:05 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:05+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Dec 26 13:17:06 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="completed keyexchange" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="completed challenge" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=info msg="authenticated AP" username="me***53" Dec 26 13:17:06 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=info msg="authenticated Login5" username="me***53" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="initializing zeroconf session" username="me***53" Dec 26 13:17:06 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="dealer connection opened" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=trace msg="starting accesspoint recv loop" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=trace msg="starting dealer recv loop" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=trace msg="received accesspoint ping" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="received connection id: NTVlN2EzM2MtODBl...Q0ZFODBGNjM0Rg==" Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=trace msg="received accesspoint pong ack" Dec 26 13:17:06 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:06 volumio-dac go-librespot[26052]: time="2025-12-26T13:17:06+02:00" level=debug msg="put connect state because NEW_DEVICE" Dec 26 13:17:06 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:06 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:07 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:07 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:07 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1305. Dec 26 13:17:07 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:07 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:07 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:07 volumio-dac volumio[26060]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:07 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:07 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:07 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:07 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:07 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:07 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:07 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:08 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:08 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:08 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:08 volumio-dac volumio[25847]: info: FusionDsp - Dec 26 13:17:08 volumio-dac volumio[25847]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 26 13:17:08 volumio-dac volumio[25847]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Dec 26 13:17:08 volumio-dac volumio[25847]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 26 13:17:08 volumio-dac volumio[25847]: info: camilladsp service started and running in background, instance 1 Dec 26 13:17:08 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:08 volumio-dac volumio[25847]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 26 13:17:08 volumio-dac volumio[25847]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 26 13:17:08 volumio-dac volumio[25847]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 26 13:17:08 volumio-dac volumio[25847]: info: FusionDsp loaded Dec 26 13:17:08 volumio-dac volumio[25847]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 13:17:08 volumio-dac sudo[26071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 26 13:17:08 volumio-dac sudo[26071]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:09 volumio-dac volumio[25847]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 26 13:17:09 volumio-dac volumio[25847]: info: Adding Signal Path Element [object Object] Dec 26 13:17:09 volumio-dac volumio[25847]: info: Adding fusiondspeq DSP Signal Path Element Dec 26 13:17:09 volumio-dac volumio[25847]: info: FusionDsp - ---- installed callbackRead Dec 26 13:17:09 volumio-dac volumio[25847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:17:09 volumio-dac volumio[25847]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 26 13:17:09 volumio-dac volumio[25847]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Dec 26 13:17:09 volumio-dac volumio[25847]: at onErrorNT (internal/child_process.js:465:16) Dec 26 13:17:09 volumio-dac volumio[25847]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 26 13:17:09 volumio-dac volumio[25847]: at runNextTicks (internal/process/task_queues.js:62:3) Dec 26 13:17:09 volumio-dac volumio[25847]: at listOnTimeout (internal/timers.js:523:9) Dec 26 13:17:09 volumio-dac volumio[25847]: at processTimers (internal/timers.js:497:7) { Dec 26 13:17:09 volumio-dac volumio[25847]: errno: -2, Dec 26 13:17:09 volumio-dac volumio[25847]: code: 'ENOENT', Dec 26 13:17:09 volumio-dac volumio[25847]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 26 13:17:09 volumio-dac volumio[25847]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 26 13:17:09 volumio-dac volumio[25847]: spawnargs: [ Dec 26 13:17:09 volumio-dac volumio[25847]: '-p', Dec 26 13:17:09 volumio-dac volumio[25847]: 9876, Dec 26 13:17:09 volumio-dac volumio[25847]: '-o', Dec 26 13:17:09 volumio-dac volumio[25847]: '/tmp/camilladsp.log', Dec 26 13:17:09 volumio-dac volumio[25847]: '-l', Dec 26 13:17:09 volumio-dac volumio[25847]: 'warn', Dec 26 13:17:09 volumio-dac volumio[25847]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 26 13:17:09 volumio-dac volumio[25847]: ] Dec 26 13:17:09 volumio-dac volumio[25847]: } Dec 26 13:17:09 volumio-dac volumio[25847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:17:09 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:09 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1306. Dec 26 13:17:09 volumio-dac sudo[26071]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:09 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:09 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:09 volumio-dac volumio[26081]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:09 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:09 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:09 volumio-dac sudo[26083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 13:16 Dec 26 13:17:09 volumio-dac sudo[26083]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:09 volumio-dac sudo[26083]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:09 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 26 13:17:09 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 26 13:17:09 volumio-dac systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 26 13:17:09 volumio-dac systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 26 13:17:10 volumio-dac systemd[1]: Started dynamicswap service. Dec 26 13:17:10 volumio-dac systemd[1]: dynamicswap.service: Succeeded. Dec 26 13:17:10 volumio-dac systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 26 13:17:10 volumio-dac systemd[1]: volumio.service: Scheduled restart job, restart counter is at 211. Dec 26 13:17:10 volumio-dac systemd[1]: Started dynamicswap service. Dec 26 13:17:10 volumio-dac systemd[1]: Stopped Volumio Backend Module. Dec 26 13:17:10 volumio-dac systemd[1]: Started Volumio Backend Module. Dec 26 13:17:10 volumio-dac systemd[1]: dynamicswap.service: Succeeded. Dec 26 13:17:11 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:11 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1307. Dec 26 13:17:11 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:11 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:11 volumio-dac volumio[26114]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:11 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:11 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:11 volumio-dac volumio[26094]: info: ------------------------------------------- Dec 26 13:17:11 volumio-dac volumio[26094]: info: ----- Volumio3 ---- Dec 26 13:17:11 volumio-dac volumio[26094]: info: ------------------------------------------- Dec 26 13:17:11 volumio-dac volumio[26094]: info: ----- System startup ---- Dec 26 13:17:11 volumio-dac volumio[26094]: info: ------------------------------------------- Dec 26 13:17:12 volumio-dac volumio[26094]: info: MYVOLUMIO Environment detected Dec 26 13:17:12 volumio-dac volumio[26094]: info: Plugin folders cleanup Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning into folder /volumio/app/plugins/ Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning category audio_interface Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning category miscellanea Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning category music_service Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning category plugins.json Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning category system_controller Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning category user_interface Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning into folder /data/plugins/ Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning category audio_interface Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning category music_service Dec 26 13:17:12 volumio-dac volumio[26094]: info: Scanning category user_interface Dec 26 13:17:12 volumio-dac volumio[26094]: info: Plugin folders cleanup completed Dec 26 13:17:12 volumio-dac volumio[26094]: info: ------------------------------------------- Dec 26 13:17:12 volumio-dac volumio[26094]: info: ----- Core plugins startup ---- Dec 26 13:17:12 volumio-dac volumio[26094]: info: ------------------------------------------- Dec 26 13:17:12 volumio-dac volumio[26094]: info: Loading plugins from folder /volumio/app/plugins/ Dec 26 13:17:12 volumio-dac volumio[26094]: info: Adding plugin upnp to MyMusic Plugins Dec 26 13:17:12 volumio-dac volumio[26094]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 26 13:17:12 volumio-dac volumio[26094]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 26 13:17:12 volumio-dac volumio[26094]: info: Loading plugins from folder /data/plugins/ Dec 26 13:17:12 volumio-dac volumio[26094]: info: Loading plugin "system"... Dec 26 13:17:12 volumio-dac volumio[26094]: info: Loading plugin "appearance"... Dec 26 13:17:13 volumio-dac volumio[26094]: info: Loading plugin "network"... Dec 26 13:17:13 volumio-dac volumio[26094]: info: Refreshing Cached IP Addresses Dec 26 13:17:13 volumio-dac sudo[26121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 13:17:13 volumio-dac sudo[26121]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:13 volumio-dac sudo[26121]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:13 volumio-dac sudo[26123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:17:13 volumio-dac sudo[26123]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:13 volumio-dac volumio[26094]: info: Loading plugin "services"... Dec 26 13:17:13 volumio-dac sudo[26123]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:13 volumio-dac volumio[26094]: info: Loading plugin "alsa_controller"... Dec 26 13:17:13 volumio-dac sudo[26131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 13:17:13 volumio-dac sudo[26131]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:13 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:13 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1308. Dec 26 13:17:13 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:13 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:13 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:13 volumio-dac volumio[26094]: info: Loading plugin "wizard"... Dec 26 13:17:13 volumio-dac volumio[26094]: info: Loading plugin "networkfs"... Dec 26 13:17:13 volumio-dac volumio[26094]: info: Starting Udev Watcher for removable devices Dec 26 13:17:14 volumio-dac volumio[26146]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:14 volumio-dac volumio[26094]: info: Ignoring mount for partition: boot Dec 26 13:17:14 volumio-dac volumio[26094]: info: Ignoring mount for partition: volumio Dec 26 13:17:14 volumio-dac volumio[26094]: info: Ignoring mount for partition: volumio_data Dec 26 13:17:14 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 13:17:14 volumio-dac volumio[26094]: info: Loading plugin "volumio_command_line_client"... Dec 26 13:17:14 volumio-dac volumio[26094]: info: Loading plugin "upnp"... Dec 26 13:17:14 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:14 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:14 volumio-dac volumio[26094]: info: [1766747834038] Starting Upmpd Daemon Dec 26 13:17:14 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 13:17:14 volumio-dac volumio[26094]: info: Loading plugin "my_music"... Dec 26 13:17:14 volumio-dac volumio[26094]: info: Loading plugin "mpd"... Dec 26 13:17:14 volumio-dac volumio[26094]: info: Loading plugin "upnp_browser"... Dec 26 13:17:14 volumio-dac sudo[26131]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:14 volumio-dac volumio[26094]: info: Starting UPNP Browser Dec 26 13:17:14 volumio-dac volumio[26094]: info: Loading plugin "alarm-clock"... Dec 26 13:17:14 volumio-dac volumio[26094]: info: Loading plugin "airplay_emulation"... Dec 26 13:17:14 volumio-dac volumio[26094]: info: Starting Shairport Sync Dec 26 13:17:14 volumio-dac volumio[26094]: info: Loading plugin "last_100"... Dec 26 13:17:14 volumio-dac volumio[26094]: info: Loading plugin "webradio"... Dec 26 13:17:14 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:14] [connect] Successful connection Dec 26 13:17:15 volumio-dac volumio[26094]: info: Loading plugin "i2s_dacs"... Dec 26 13:17:15 volumio-dac volumio[26094]: info: Loading plugin "volumiodiscovery"... Dec 26 13:17:15 volumio-dac volumio[26094]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:15 volumio-dac volumio[26094]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:15 volumio-dac volumio[26094]: *** WARNING *** For more information see Dec 26 13:17:15 volumio-dac node[26094]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:15 volumio-dac volumio[26094]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:15 volumio-dac volumio[26094]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:15 volumio-dac volumio[26094]: *** WARNING *** For more information see Dec 26 13:17:15 volumio-dac node[26094]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:15 volumio-dac node[26094]: *** WARNING *** For more information see Dec 26 13:17:15 volumio-dac node[26094]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:15 volumio-dac node[26094]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:15 volumio-dac node[26094]: *** WARNING *** For more information see Dec 26 13:17:15 volumio-dac volumio[26094]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 26 13:17:15 volumio-dac volumio[26094]: info: Discovery: Started advertising with name: Volumio DAC Dec 26 13:17:15 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 13:17:15 volumio-dac volumio[26094]: info: Loading plugin "mixcloud"... Dec 26 13:17:15 volumio-dac volumio[26094]: info: Loading plugin "spop"... Dec 26 13:17:16 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:16 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1309. Dec 26 13:17:16 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:16 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:16 volumio-dac volumio[26151]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:16 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:16 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:16 volumio-dac volumio[26094]: info: Plugin ytmusic is not enabled Dec 26 13:17:16 volumio-dac volumio[26094]: info: Plugin now_playing is not enabled Dec 26 13:17:16 volumio-dac volumio[26094]: info: Loading plugin "outputs"... Dec 26 13:17:16 volumio-dac volumio[26094]: info: Loading plugin "albumart"... Dec 26 13:17:16 volumio-dac volumio[26094]: info: Plugin example_plugin is not enabled Dec 26 13:17:16 volumio-dac volumio[26094]: info: Loading plugin "inputs"... Dec 26 13:17:16 volumio-dac volumio[26094]: info: Loading plugin "updater_comm"... Dec 26 13:17:17 volumio-dac volumio[26094]: info: Plugin mpdemulation is not enabled Dec 26 13:17:17 volumio-dac volumio[26094]: info: Loading plugin "rest_api"... Dec 26 13:17:17 volumio-dac volumio[26094]: info: Loading plugin "websocket"... Dec 26 13:17:17 volumio-dac volumio[26094]: info: Starting Socket.io Server version 2.3.0 Dec 26 13:17:17 volumio-dac volumio[26094]: info: Loading plugin "fusiondsp"... Dec 26 13:17:17 volumio-dac volumio[26094]: Forking 3 albumart workers Dec 26 13:17:17 volumio-dac volumio[26094]: info: Applying required configuration parameters for plugin fusiondsp Dec 26 13:17:17 volumio-dac volumio[26094]: info: Plugin 80s80s is not enabled Dec 26 13:17:17 volumio-dac volumio[26094]: info: Loading i18n strings for locale es Dec 26 13:17:17 volumio-dac volumio[26094]: Updating browse sources language Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::initPlayerControls Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:17 volumio-dac volumio[26094]: Express server listening on port 3000 Dec 26 13:17:17 volumio-dac volumio[26094]: [Metrics] WebUI: 6s 631.48ms Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreStateMachine::resetVolumioState Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreStateMachine::getcurrentVolume Dec 26 13:17:17 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 13:17:18 volumio-dac volumio[26094]: info: Volumio Network Manager: Network status updated: 2 Dec 26 13:17:18 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 26 13:17:18 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 26 13:17:18 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 26 13:17:18 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 192.168.0.138:3000 from 192.168.0.92 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 26 13:17:18 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 26 13:17:18 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766747834 101 Dec 26 13:17:18 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Dec 26 13:17:18 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 26 13:17:18 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 192.168.0.138:3000 from 192.168.0.92 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:18 volumio-dac volumio[26094]: info: Executing start script for DAC IQaudIO DAC Plus Dec 26 13:17:18 volumio-dac volumio[26094]: info: Reloading queue from file Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreStateMachine::setRepeat null single undefined Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreStateMachine::pushState Dec 26 13:17:18 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreStateMachine::setRandom null Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreStateMachine::pushState Dec 26 13:17:18 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:18 volumio-dac volumio[26094]: info: Setting Device type: Raspberry PI Dec 26 13:17:18 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:18 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1310. Dec 26 13:17:18 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:18 volumio-dac volumio[26094]: info: VolumeController:: Volume=0 Mute =false Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreStateMachine::pushState Dec 26 13:17:18 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreStateMachine::updateTrackBlock Dec 26 13:17:18 volumio-dac volumio[26094]: info: CorePlayQueue::getTrackBlock Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 13:17:18 volumio-dac volumio[26094]: Starting albumart workers Dec 26 13:17:18 volumio-dac volumio[26094]: Starting albumart workers Dec 26 13:17:18 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 26 13:17:18 volumio-dac volumio[26094]: info: DAC script executed Dec 26 13:17:18 volumio-dac volumio[26094]: info: Completed loading Core Plugins Dec 26 13:17:18 volumio-dac volumio[26094]: info: Preparing to generate the ALSA configuration file Dec 26 13:17:18 volumio-dac volumio[26094]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 13:17:18 volumio-dac volumio[26094]: info: Reading ALSA contributions from plugins. Dec 26 13:17:18 volumio-dac volumio[26194]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:18 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:18 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:18 volumio-dac volumio[26094]: Starting albumart workers Dec 26 13:17:18 volumio-dac volumio[26094]: info: Discovery: adding f59de741-b187-4a9b-8bce-02c45f5e940d Dec 26 13:17:18 volumio-dac volumio[26094]: info: Discovery: Found device Volumio DAC Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:18 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:18 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:18 volumio-dac volumio[26094]: info: VolumeController:: Volume=0 Mute =false Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreStateMachine::pushState Dec 26 13:17:18 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:18 volumio-dac volumio[26094]: info: Asound.conf file unchanged, so no further update is needed Dec 26 13:17:18 volumio-dac volumio[26094]: info: Output device has changed, restarting MPD Dec 26 13:17:18 volumio-dac volumio[26094]: info: Output device has changed, restarting Shairport Sync Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:18 volumio-dac sudo[26198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:17:18 volumio-dac sudo[26198]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:18 volumio-dac sudo[26198]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:18 volumio-dac sudo[26200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:18 volumio-dac sudo[26200]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:18 volumio-dac systemd[1]: Stopping Music Player Daemon... Dec 26 13:17:18 volumio-dac volumio[26094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:18 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:18 volumio-dac volumio[26094]: info: ___________ START PLUGINS ___________ Dec 26 13:17:18 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:18 volumio-dac volumio[26094]: info: ControllerMpd::onStart: Initializing MPD Dec 26 13:17:18 volumio-dac volumio[26094]: info: Creating MPD Configuration file Dec 26 13:17:18 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:18 volumio-dac sudo[26208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:17:18 volumio-dac sudo[26208]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:18 volumio-dac sudo[26208]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:18 volumio-dac volumio[26094]: info: [1766747838955] CoreMusicLibrary::Adding element Media Servers Dec 26 13:17:18 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:18 volumio-dac sudo[26211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:18 volumio-dac sudo[26211]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:18 volumio-dac systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 26 13:17:18 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:18 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:19 volumio-dac volumio[26094]: info: UPNP Browser: Client initialized successfully Dec 26 13:17:19 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:19 volumio-dac sudo[26213]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 13:17:19 volumio-dac sudo[26213]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:19 volumio-dac sudo[26213]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:19 volumio-dac volumio[26094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:19 volumio-dac volumio[26094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:19 volumio-dac volumio[26094]: info: [1766747839384] CoreMusicLibrary::Adding element Last_100 Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:19 volumio-dac volumio[26094]: info: [1766747839410] CoreMusicLibrary::Adding element Webradio Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:19 volumio-dac volumio[26094]: info: Initializing BBC Radios Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:19 volumio-dac volumio[26094]: info: [1766747839570] CoreMusicLibrary::Adding element Mixcloud Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:19 volumio-dac volumio[26094]: Cannot find translation for source Mixcloud Dec 26 13:17:19 volumio-dac volumio[26094]: info: Creating Spotify config file Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:19 volumio-dac volumio[26094]: info: Loading i18n strings for locale es Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:19 volumio-dac volumio[26094]: info: FusionDsp - mixtype--------------------- Hardware Dec 26 13:17:19 volumio-dac volumio[26094]: info: Volumio Calling Home Dec 26 13:17:19 volumio-dac volumio[26094]: info: Preparing to generate the ALSA configuration file Dec 26 13:17:19 volumio-dac volumio[26094]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 13:17:19 volumio-dac volumio[26094]: info: Reading ALSA contributions from plugins. Dec 26 13:17:19 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 26 13:17:19 volumio-dac volumio[26094]: info: MPD Permissions set Dec 26 13:17:19 volumio-dac volumio[26094]: info: MPD Permissions set Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 13:17:19 volumio-dac volumio[26094]: info: Volumio called home Dec 26 13:17:19 volumio-dac volumio[26094]: info: Spotify config file written Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:19 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 13:17:19 volumio-dac sudo[26261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 26 13:17:19 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 13:17:19 volumio-dac sudo[26261]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:20 volumio-dac volumio[26094]: info: Received Get System Info Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 13:17:20 volumio-dac volumio[26094]: info: Discovery: Getting this device information Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:20 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:20 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:20 volumio-dac systemd[1]: Stopping go-librespot Daemon... Dec 26 13:17:20 volumio-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 26 13:17:20 volumio-dac systemd[1]: go-librespot-daemon.service: Succeeded. Dec 26 13:17:20 volumio-dac systemd[1]: Stopped go-librespot Daemon. Dec 26 13:17:20 volumio-dac volumio[26094]: info: Listing playlists Dec 26 13:17:20 volumio-dac volumio[26094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac systemd[1]: Started go-librespot Daemon. Dec 26 13:17:20 volumio-dac go-librespot[26263]: go-librespot daemon starting... Dec 26 13:17:20 volumio-dac sudo[26261]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=info msg="running go-librespot 0.4.0" Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=debug msg="app state loaded" Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: No need to fix Spotify hosts Dec 26 13:17:20 volumio-dac volumio[26094]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 13:17:20 volumio-dac volumio[26094]: info: Starting Shairport Sync Dec 26 13:17:20 volumio-dac volumio[26094]: info: Starting Shairport Sync Dec 26 13:17:20 volumio-dac volumio[26094]: info: Starting Shairport Sync Dec 26 13:17:20 volumio-dac sudo[26280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:17:20 volumio-dac sudo[26280]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:20 volumio-dac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 13:17:20 volumio-dac systemd[1]: shairport-sync.service: Succeeded. Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 13:17:20 volumio-dac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:20 volumio-dac sudo[26285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:17:20 volumio-dac sudo[26285]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:20 volumio-dac sudo[26282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:17:20 volumio-dac sudo[26282]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:20 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 13:17:20 volumio-dac volumio[26094]: info: Received Get System Info Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 13:17:20 volumio-dac volumio[26094]: info: Discovery: Getting this device information Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:20 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:20 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:20 volumio-dac volumio[26094]: info: Listing playlists Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=info msg="zeroconf server listening on port 42155" Dec 26 13:17:20 volumio-dac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 13:17:20 volumio-dac sudo[26280]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:20 volumio-dac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 13:17:20 volumio-dac systemd[1]: shairport-sync.service: Succeeded. Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:20 volumio-dac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 13:17:20 volumio-dac volumio[26094]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:20 volumio-dac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 13:17:20 volumio-dac sudo[26282]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:20 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:20 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1311. Dec 26 13:17:20 volumio-dac sudo[26285]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:20 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:20 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=debug msg="obtained new client token: AACXrFfbIW1d6wLqoFkeP7Vx+iUBBG8waXB28H5qbVwCMitg63TpTS7X4Uxq0PE5R5QgzO+zq6Eo5cisI4HWxhQp5nqiiQw7keYZHCBWW/K016A9tORHSATcvVm1tHVBs9JkUCyxfj2rFw72qjypFrX7DvFSp7nNI34yel8976d+aosZ3llcSeFbAxOZUQhK/Ri3teK11hrhFfHxPyO2oWXePGFw39CPInGrugJg6N+kLJTXPZ6cQvXZ" Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26291]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:20 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=debug msg="completed keyexchange" Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=debug msg="completed challenge" Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:20 volumio-dac volumio[26094]: info: Shairport-Sync Started Dec 26 13:17:20 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:20+02:00" level=info msg="authenticated AP" username="me***53" Dec 26 13:17:20 volumio-dac volumio[26094]: Error adding Membership: Error: addMembership EINVAL Dec 26 13:17:20 volumio-dac volumio[26094]: info: Asound.conf file unchanged, so no further update is needed Dec 26 13:17:20 volumio-dac volumio[26094]: info: Output device has changed, restarting MPD Dec 26 13:17:20 volumio-dac sudo[26295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:17:20 volumio-dac volumio[26094]: info: Output device has changed, restarting Shairport Sync Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:20 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:20 volumio-dac sudo[26295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:20 volumio-dac sudo[26295]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:20 volumio-dac sudo[26298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:20 volumio-dac sudo[26298]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:20 volumio-dac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 26 13:17:20 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:20 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:20 volumio-dac volumio[26094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:21 volumio-dac volumio[26094]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 26 13:17:21 volumio-dac volumio[26094]: SPOTIFY: BQCgcZ_35Y_I1iYRcgC3rTcXt4dUh9SaI-AsJSjSnCrN7rbfZRDRo-ysAYK5peb1TjLkuIsnaSfgE9J82HPbM2zeJ0CDLy1GJ-Do9eofIxPtcVSGd8VYDAprttpSURJHKPgEpWmxRRubKicY6PsO8LLKztWZdEbLwU_RH3BTBEzkGH1lnK-UNznIXK1Gj-hoglqFd_9CmfnjHwux6S7cvgeas2CRVwuZydDGcTFEIf2ghmS6t3cinqmVBQQqPq4jElRAMb7Yu3t040wGEMpRrFjKs4Dmkr9n-Y-Ll0-z3v7MsyKZaXTs0I0d2c_TUW0u0DfMyw1KBArx4Pr-gKbuPUoiVtTVA73ZylfLMqDHCYVVEOIz8-4 Dec 26 13:17:21 volumio-dac volumio[26094]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 26 13:17:21 volumio-dac volumio[26094]: info: New Spotify access token = BQCgcZ_35Y_I1iYRcgC3rTcXt4dUh9SaI-AsJSjSnCrN7rbfZRDRo-ysAYK5peb1TjLkuIsnaSfgE9J82HPbM2zeJ0CDLy1GJ-Do9eofIxPtcVSGd8VYDAprttpSURJHKPgEpWmxRRubKicY6PsO8LLKztWZdEbLwU_RH3BTBEzkGH1lnK-UNznIXK1Gj-hoglqFd_9CmfnjHwux6S7cvgeas2CRVwuZydDGcTFEIf2ghmS6t3cinqmVBQQqPq4jElRAMb7Yu3t040wGEMpRrFjKs4Dmkr9n-Y-Ll0-z3v7MsyKZaXTs0I0d2c_TUW0u0DfMyw1KBArx4Pr-gKbuPUoiVtTVA73ZylfLMqDHCYVVEOIz8-4 Dec 26 13:17:21 volumio-dac volumio[26094]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 26 13:17:21 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=info msg="authenticated Login5" username="me***53" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=debug msg="initializing zeroconf session" username="me***53" Dec 26 13:17:21 volumio-dac volumio[26094]: info: Shairport-Sync Started Dec 26 13:17:21 volumio-dac volumio[26094]: info: Shairport-Sync Started Dec 26 13:17:21 volumio-dac volumio[26094]: info: MPD Permissions set Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=debug msg="dealer connection opened" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=trace msg="starting accesspoint recv loop" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=trace msg="starting dealer recv loop" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=trace msg="received accesspoint ping" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=debug msg="received connection id: NjJjYTA1NzgtYzA1...NTUzRjY4RTJCRg==" Dec 26 13:17:21 volumio-dac sudo[26304]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 13:17:21 volumio-dac sudo[26304]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:21 volumio-dac sudo[26304]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:21 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=trace msg="received accesspoint pong ack" Dec 26 13:17:21 volumio-dac go-librespot[26263]: time="2025-12-26T13:17:21+02:00" level=debug msg="put connect state because NEW_DEVICE" Dec 26 13:17:21 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:21 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:21 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:22 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:22 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:22 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:22 volumio-dac mpd[26307]: Dec 26 13:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 13:17:22 volumio-dac systemd[1]: Started Music Player Daemon. Dec 26 13:17:22 volumio-dac sudo[26211]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:22 volumio-dac sudo[26200]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:22 volumio-dac sudo[26298]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:22 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:22 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:22 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1312. Dec 26 13:17:22 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:22 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:22 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:23 volumio-dac volumio[26310]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:23 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:23 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:23 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:23 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:23 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:23 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:23 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:24 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:24 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:24 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:24 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:24 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:24 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:25 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:25 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1313. Dec 26 13:17:25 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:25 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:25 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:25 volumio-dac volumio[26326]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:25 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:25 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:25 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:25 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:25 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:25 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:25 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:26 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:26 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:26 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:26 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:26 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:26 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:26 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:27 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:27 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:27 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:27 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1314. Dec 26 13:17:27 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:27 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:27 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:27 volumio-dac volumio[26327]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:27 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:27 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:27 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:27 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:27 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:28 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:28 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:28 volumio-dac volumio[26094]: info: FusionDsp - Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:28 volumio-dac volumio[26094]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 26 13:17:28 volumio-dac volumio[26094]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Dec 26 13:17:28 volumio-dac volumio[26094]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 26 13:17:28 volumio-dac volumio[26094]: info: camilladsp service started and running in background, instance 1 Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:28 volumio-dac volumio[26094]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 26 13:17:28 volumio-dac volumio[26094]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 26 13:17:28 volumio-dac volumio[26094]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 26 13:17:28 volumio-dac volumio[26094]: info: FusionDsp loaded Dec 26 13:17:28 volumio-dac volumio[26094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 13:17:28 volumio-dac sudo[26338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 26 13:17:29 volumio-dac sudo[26338]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:29 volumio-dac volumio[26094]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 26 13:17:29 volumio-dac volumio[26094]: info: Adding Signal Path Element [object Object] Dec 26 13:17:29 volumio-dac volumio[26094]: info: Adding fusiondspeq DSP Signal Path Element Dec 26 13:17:29 volumio-dac volumio[26094]: info: FusionDsp - ---- installed callbackRead Dec 26 13:17:29 volumio-dac volumio[26094]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:17:29 volumio-dac volumio[26094]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 26 13:17:29 volumio-dac volumio[26094]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Dec 26 13:17:29 volumio-dac volumio[26094]: at onErrorNT (internal/child_process.js:465:16) Dec 26 13:17:29 volumio-dac volumio[26094]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 26 13:17:29 volumio-dac volumio[26094]: at runNextTicks (internal/process/task_queues.js:62:3) Dec 26 13:17:29 volumio-dac volumio[26094]: at listOnTimeout (internal/timers.js:523:9) Dec 26 13:17:29 volumio-dac volumio[26094]: at processTimers (internal/timers.js:497:7) { Dec 26 13:17:29 volumio-dac volumio[26094]: errno: -2, Dec 26 13:17:29 volumio-dac volumio[26094]: code: 'ENOENT', Dec 26 13:17:29 volumio-dac volumio[26094]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 26 13:17:29 volumio-dac volumio[26094]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 26 13:17:29 volumio-dac volumio[26094]: spawnargs: [ Dec 26 13:17:29 volumio-dac volumio[26094]: '-p', Dec 26 13:17:29 volumio-dac volumio[26094]: 9876, Dec 26 13:17:29 volumio-dac volumio[26094]: '-o', Dec 26 13:17:29 volumio-dac volumio[26094]: '/tmp/camilladsp.log', Dec 26 13:17:29 volumio-dac volumio[26094]: '-l', Dec 26 13:17:29 volumio-dac volumio[26094]: 'warn', Dec 26 13:17:29 volumio-dac volumio[26094]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 26 13:17:29 volumio-dac volumio[26094]: ] Dec 26 13:17:29 volumio-dac volumio[26094]: } Dec 26 13:17:29 volumio-dac volumio[26094]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:17:29 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:29 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1315. Dec 26 13:17:29 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:29 volumio-dac sudo[26350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 13:16 Dec 26 13:17:29 volumio-dac sudo[26350]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:29 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:29 volumio-dac sudo[26338]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:29 volumio-dac volumio[26348]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:29 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:29 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:29 volumio-dac sudo[26350]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:29 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 26 13:17:29 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 26 13:17:29 volumio-dac systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 26 13:17:29 volumio-dac systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 26 13:17:30 volumio-dac systemd[1]: Started dynamicswap service. Dec 26 13:17:30 volumio-dac systemd[1]: dynamicswap.service: Succeeded. Dec 26 13:17:30 volumio-dac systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 26 13:17:30 volumio-dac systemd[1]: volumio.service: Scheduled restart job, restart counter is at 212. Dec 26 13:17:30 volumio-dac systemd[1]: Started dynamicswap service. Dec 26 13:17:30 volumio-dac systemd[1]: Stopped Volumio Backend Module. Dec 26 13:17:30 volumio-dac systemd[1]: Started Volumio Backend Module. Dec 26 13:17:30 volumio-dac systemd[1]: dynamicswap.service: Succeeded. Dec 26 13:17:31 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:31 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1316. Dec 26 13:17:31 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:31 volumio-dac volumio[26361]: info: ------------------------------------------- Dec 26 13:17:31 volumio-dac volumio[26361]: info: ----- Volumio3 ---- Dec 26 13:17:31 volumio-dac volumio[26361]: info: ------------------------------------------- Dec 26 13:17:31 volumio-dac volumio[26361]: info: ----- System startup ---- Dec 26 13:17:31 volumio-dac volumio[26361]: info: ------------------------------------------- Dec 26 13:17:31 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:31 volumio-dac volumio[26382]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:32 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:32 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:32 volumio-dac volumio[26361]: info: MYVOLUMIO Environment detected Dec 26 13:17:32 volumio-dac volumio[26361]: info: Plugin folders cleanup Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning into folder /volumio/app/plugins/ Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning category audio_interface Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning category miscellanea Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning category music_service Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning category plugins.json Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning category system_controller Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning category user_interface Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning into folder /data/plugins/ Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning category audio_interface Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning category music_service Dec 26 13:17:32 volumio-dac volumio[26361]: info: Scanning category user_interface Dec 26 13:17:32 volumio-dac volumio[26361]: info: Plugin folders cleanup completed Dec 26 13:17:32 volumio-dac volumio[26361]: info: ------------------------------------------- Dec 26 13:17:32 volumio-dac volumio[26361]: info: ----- Core plugins startup ---- Dec 26 13:17:32 volumio-dac volumio[26361]: info: ------------------------------------------- Dec 26 13:17:32 volumio-dac volumio[26361]: info: Loading plugins from folder /volumio/app/plugins/ Dec 26 13:17:32 volumio-dac volumio[26361]: info: Adding plugin upnp to MyMusic Plugins Dec 26 13:17:32 volumio-dac volumio[26361]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 26 13:17:32 volumio-dac volumio[26361]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 26 13:17:32 volumio-dac volumio[26361]: info: Loading plugins from folder /data/plugins/ Dec 26 13:17:32 volumio-dac volumio[26361]: info: Loading plugin "system"... Dec 26 13:17:32 volumio-dac volumio[26361]: info: Loading plugin "appearance"... Dec 26 13:17:33 volumio-dac volumio[26361]: info: Loading plugin "network"... Dec 26 13:17:33 volumio-dac volumio[26361]: info: Refreshing Cached IP Addresses Dec 26 13:17:33 volumio-dac sudo[26389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 13:17:33 volumio-dac sudo[26389]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:33 volumio-dac sudo[26391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:17:33 volumio-dac sudo[26391]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:33 volumio-dac sudo[26389]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:33 volumio-dac sudo[26391]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:33 volumio-dac volumio[26361]: info: Loading plugin "services"... Dec 26 13:17:33 volumio-dac volumio[26361]: info: Loading plugin "alsa_controller"... Dec 26 13:17:33 volumio-dac sudo[26400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 13:17:33 volumio-dac sudo[26400]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:33 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:33 volumio-dac volumio[26361]: info: Loading plugin "wizard"... Dec 26 13:17:33 volumio-dac volumio[26361]: info: Loading plugin "networkfs"... Dec 26 13:17:33 volumio-dac volumio[26361]: info: Starting Udev Watcher for removable devices Dec 26 13:17:34 volumio-dac volumio[26361]: info: Ignoring mount for partition: boot Dec 26 13:17:34 volumio-dac volumio[26361]: info: Ignoring mount for partition: volumio Dec 26 13:17:34 volumio-dac volumio[26361]: info: Ignoring mount for partition: volumio_data Dec 26 13:17:34 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 13:17:34 volumio-dac volumio[26361]: info: Loading plugin "volumio_command_line_client"... Dec 26 13:17:34 volumio-dac volumio[26361]: info: Loading plugin "upnp"... Dec 26 13:17:34 volumio-dac volumio[26361]: info: [1766747854034] Starting Upmpd Daemon Dec 26 13:17:34 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 13:17:34 volumio-dac volumio[26361]: info: Loading plugin "my_music"... Dec 26 13:17:34 volumio-dac volumio[26361]: info: Loading plugin "mpd"... Dec 26 13:17:34 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:34 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1317. Dec 26 13:17:34 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:34 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:34 volumio-dac volumio[26416]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:34 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:34 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:34 volumio-dac volumio[26361]: info: Loading plugin "upnp_browser"... Dec 26 13:17:34 volumio-dac volumio[26361]: info: Starting UPNP Browser Dec 26 13:17:34 volumio-dac volumio[26361]: info: Loading plugin "alarm-clock"... Dec 26 13:17:34 volumio-dac sudo[26400]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:34 volumio-dac volumio[26361]: info: Loading plugin "airplay_emulation"... Dec 26 13:17:34 volumio-dac volumio[26361]: info: Starting Shairport Sync Dec 26 13:17:34 volumio-dac volumio[26361]: info: Loading plugin "last_100"... Dec 26 13:17:34 volumio-dac volumio[26361]: info: Loading plugin "webradio"... Dec 26 13:17:34 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:34] [connect] Successful connection Dec 26 13:17:35 volumio-dac volumio[26361]: info: Loading plugin "i2s_dacs"... Dec 26 13:17:35 volumio-dac volumio[26361]: info: Loading plugin "volumiodiscovery"... Dec 26 13:17:35 volumio-dac volumio[26361]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:35 volumio-dac volumio[26361]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:35 volumio-dac volumio[26361]: *** WARNING *** For more information see Dec 26 13:17:35 volumio-dac node[26361]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:35 volumio-dac volumio[26361]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:35 volumio-dac volumio[26361]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:35 volumio-dac volumio[26361]: *** WARNING *** For more information see Dec 26 13:17:35 volumio-dac node[26361]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:35 volumio-dac node[26361]: *** WARNING *** For more information see Dec 26 13:17:35 volumio-dac node[26361]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:35 volumio-dac node[26361]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:35 volumio-dac node[26361]: *** WARNING *** For more information see Dec 26 13:17:35 volumio-dac volumio[26361]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 26 13:17:35 volumio-dac volumio[26361]: info: Discovery: Started advertising with name: Volumio DAC Dec 26 13:17:35 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 13:17:35 volumio-dac volumio[26361]: info: Loading plugin "mixcloud"... Dec 26 13:17:35 volumio-dac volumio[26361]: info: Loading plugin "spop"... Dec 26 13:17:36 volumio-dac volumiologrotate[627]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Dec 26 13:17:36 volumio-dac volumiologrotate[627]: ls: cannot access 'DAC': No such file or directory Dec 26 13:17:36 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:36 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1318. Dec 26 13:17:36 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:36 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:36 volumio-dac volumio[26460]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:36 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:36 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:36 volumio-dac volumio[26361]: info: Plugin ytmusic is not enabled Dec 26 13:17:36 volumio-dac volumio[26361]: info: Plugin now_playing is not enabled Dec 26 13:17:36 volumio-dac volumio[26361]: info: Loading plugin "outputs"... Dec 26 13:17:36 volumio-dac volumio[26361]: info: Loading plugin "albumart"... Dec 26 13:17:36 volumio-dac volumio[26361]: info: Plugin example_plugin is not enabled Dec 26 13:17:36 volumio-dac volumio[26361]: info: Loading plugin "inputs"... Dec 26 13:17:36 volumio-dac volumio[26361]: info: Loading plugin "updater_comm"... Dec 26 13:17:36 volumio-dac volumio[26361]: info: Plugin mpdemulation is not enabled Dec 26 13:17:36 volumio-dac volumio[26361]: info: Loading plugin "rest_api"... Dec 26 13:17:36 volumio-dac volumio[26361]: info: Loading plugin "websocket"... Dec 26 13:17:37 volumio-dac volumio[26361]: info: Starting Socket.io Server version 2.3.0 Dec 26 13:17:37 volumio-dac volumio[26361]: info: Loading plugin "fusiondsp"... Dec 26 13:17:37 volumio-dac volumio[26361]: Forking 3 albumart workers Dec 26 13:17:37 volumio-dac volumio[26361]: info: Applying required configuration parameters for plugin fusiondsp Dec 26 13:17:37 volumio-dac volumio[26361]: info: Plugin 80s80s is not enabled Dec 26 13:17:37 volumio-dac volumio[26361]: info: Loading i18n strings for locale es Dec 26 13:17:37 volumio-dac volumio[26361]: Updating browse sources language Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::initPlayerControls Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:37 volumio-dac volumio[26361]: Express server listening on port 3000 Dec 26 13:17:37 volumio-dac volumio[26361]: [Metrics] WebUI: 6s 390.89ms Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreStateMachine::resetVolumioState Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreStateMachine::getcurrentVolume Dec 26 13:17:37 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 13:17:37 volumio-dac volumio[26361]: info: Volumio Network Manager: Network status updated: 2 Dec 26 13:17:38 volumio-dac volumio[26361]: verbose: New Socket.io Connection to 192.168.0.138:3000 from 192.168.0.92 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 26 13:17:38 volumio-dac volumio[26361]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 26 13:17:38 volumio-dac volumio[26361]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 26 13:17:38 volumio-dac volumio[26361]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 26 13:17:38 volumio-dac volumio[26361]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 26 13:17:38 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766747854 101 Dec 26 13:17:38 volumio-dac volumio[26361]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Dec 26 13:17:38 volumio-dac volumio[26361]: verbose: New Socket.io Connection to 192.168.0.138:3000 from 192.168.0.92 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 26 13:17:38 volumio-dac volumio[26361]: Starting albumart workers Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:38 volumio-dac volumio[26361]: info: Executing start script for DAC IQaudIO DAC Plus Dec 26 13:17:38 volumio-dac volumio[26361]: info: Reloading queue from file Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreStateMachine::setRepeat null single undefined Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreStateMachine::pushState Dec 26 13:17:38 volumio-dac volumio[26361]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:38 volumio-dac volumio[26361]: Starting albumart workers Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreStateMachine::setRandom null Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreStateMachine::pushState Dec 26 13:17:38 volumio-dac volumio[26361]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:38 volumio-dac volumio[26361]: info: Setting Device type: Raspberry PI Dec 26 13:17:38 volumio-dac volumio[26361]: info: VolumeController:: Volume=0 Mute =false Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreStateMachine::pushState Dec 26 13:17:38 volumio-dac volumio[26361]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreStateMachine::updateTrackBlock Dec 26 13:17:38 volumio-dac volumio[26361]: info: CorePlayQueue::getTrackBlock Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 13:17:38 volumio-dac volumio[26361]: Starting albumart workers Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 26 13:17:38 volumio-dac volumio[26361]: info: DAC script executed Dec 26 13:17:38 volumio-dac volumio[26361]: info: Completed loading Core Plugins Dec 26 13:17:38 volumio-dac volumio[26361]: info: Preparing to generate the ALSA configuration file Dec 26 13:17:38 volumio-dac volumio[26361]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 13:17:38 volumio-dac volumio[26361]: info: Reading ALSA contributions from plugins. Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:38 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:38 volumio-dac volumio[26361]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:38 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1319. Dec 26 13:17:38 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:38 volumio-dac volumio[26361]: info: VolumeController:: Volume=0 Mute =false Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreStateMachine::pushState Dec 26 13:17:38 volumio-dac volumio[26361]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:38 volumio-dac volumio[26361]: info: Discovery: adding f59de741-b187-4a9b-8bce-02c45f5e940d Dec 26 13:17:38 volumio-dac volumio[26361]: info: Discovery: Found device Volumio DAC Dec 26 13:17:38 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:38 volumio-dac volumio[26361]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:38 volumio-dac volumio[26361]: info: Asound.conf file unchanged, so no further update is needed Dec 26 13:17:38 volumio-dac volumio[26361]: info: Output device has changed, restarting MPD Dec 26 13:17:38 volumio-dac volumio[26505]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:38 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:38 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:38 volumio-dac sudo[26507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:17:38 volumio-dac sudo[26507]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:38 volumio-dac volumio[26361]: info: Output device has changed, restarting Shairport Sync Dec 26 13:17:38 volumio-dac sudo[26507]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:38 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:38 volumio-dac sudo[26510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:38 volumio-dac sudo[26510]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:38 volumio-dac systemd[1]: Stopping Music Player Daemon... Dec 26 13:17:38 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:38 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:38 volumio-dac volumio[26361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:38 volumio-dac volumio[26361]: info: ___________ START PLUGINS ___________ Dec 26 13:17:39 volumio-dac volumio[26361]: info: ControllerMpd::onStart: Initializing MPD Dec 26 13:17:39 volumio-dac volumio[26361]: info: Creating MPD Configuration file Dec 26 13:17:39 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:39 volumio-dac sudo[26517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:17:39 volumio-dac sudo[26517]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:39 volumio-dac sudo[26517]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:39 volumio-dac volumio[26361]: info: [1766747859096] CoreMusicLibrary::Adding element Media Servers Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:39 volumio-dac volumio[26361]: info: UPNP Browser: Client initialized successfully Dec 26 13:17:39 volumio-dac sudo[26520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:39 volumio-dac sudo[26520]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:39 volumio-dac sudo[26515]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 13:17:39 volumio-dac sudo[26515]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:39 volumio-dac sudo[26515]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:39 volumio-dac volumio[26361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:39 volumio-dac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 26 13:17:39 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:39 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:39 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:39 volumio-dac volumio[26361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:39 volumio-dac volumio[26361]: info: [1766747859315] CoreMusicLibrary::Adding element Last_100 Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:39 volumio-dac volumio[26361]: info: [1766747859323] CoreMusicLibrary::Adding element Webradio Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:39 volumio-dac volumio[26361]: info: Initializing BBC Radios Dec 26 13:17:39 volumio-dac sudo[26534]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 13:17:39 volumio-dac sudo[26534]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:39 volumio-dac sudo[26534]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:39 volumio-dac volumio[26361]: info: [1766747859469] CoreMusicLibrary::Adding element Mixcloud Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:39 volumio-dac volumio[26361]: Cannot find translation for source Mixcloud Dec 26 13:17:39 volumio-dac volumio[26361]: info: Creating Spotify config file Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: Loading i18n strings for locale es Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: FusionDsp - mixtype--------------------- Hardware Dec 26 13:17:39 volumio-dac volumio[26361]: info: Volumio Calling Home Dec 26 13:17:39 volumio-dac volumio[26361]: info: Preparing to generate the ALSA configuration file Dec 26 13:17:39 volumio-dac volumio[26361]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 13:17:39 volumio-dac volumio[26361]: info: Reading ALSA contributions from plugins. Dec 26 13:17:39 volumio-dac volumio[26361]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 26 13:17:39 volumio-dac volumio[26361]: info: MPD Permissions set Dec 26 13:17:39 volumio-dac volumio[26361]: info: MPD Permissions set Dec 26 13:17:39 volumio-dac volumio[26361]: info: Spotify config file written Dec 26 13:17:39 volumio-dac volumio[26361]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 26 13:17:39 volumio-dac volumio[26361]: info: Volumio called home Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac sudo[26571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac sudo[26571]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac systemd[1]: Stopping go-librespot Daemon... Dec 26 13:17:39 volumio-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 26 13:17:39 volumio-dac systemd[1]: go-librespot-daemon.service: Succeeded. Dec 26 13:17:39 volumio-dac systemd[1]: Stopped go-librespot Daemon. Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:39 volumio-dac volumio[26361]: info: No need to fix Spotify hosts Dec 26 13:17:39 volumio-dac systemd[1]: Started go-librespot Daemon. Dec 26 13:17:39 volumio-dac go-librespot[26577]: go-librespot daemon starting... Dec 26 13:17:39 volumio-dac sudo[26571]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=info msg="running go-librespot 0.4.0" Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=debug msg="app state loaded" Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 13:17:40 volumio-dac volumio[26361]: info: Starting Shairport Sync Dec 26 13:17:40 volumio-dac volumio[26361]: info: Starting Shairport Sync Dec 26 13:17:40 volumio-dac sudo[26589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:17:40 volumio-dac sudo[26589]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:40 volumio-dac volumio[26361]: info: Starting Shairport Sync Dec 26 13:17:40 volumio-dac sudo[26592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:17:40 volumio-dac sudo[26592]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:40 volumio-dac volumio[26361]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:40 volumio-dac sudo[26594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:17:40 volumio-dac sudo[26594]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:40 volumio-dac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 13:17:40 volumio-dac systemd[1]: shairport-sync.service: Succeeded. Dec 26 13:17:40 volumio-dac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 13:17:40 volumio-dac volumio[26361]: info: Asound.conf file unchanged, so no further update is needed Dec 26 13:17:40 volumio-dac volumio[26361]: info: Output device has changed, restarting MPD Dec 26 13:17:40 volumio-dac volumio[26361]: info: Output device has changed, restarting Shairport Sync Dec 26 13:17:40 volumio-dac sudo[26600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:17:40 volumio-dac sudo[26600]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:40 volumio-dac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 13:17:40 volumio-dac sudo[26600]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:40 volumio-dac sudo[26589]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:40 volumio-dac sudo[26592]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:40 volumio-dac sudo[26602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:40 volumio-dac sudo[26602]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=info msg="zeroconf server listening on port 37413" Dec 26 13:17:40 volumio-dac sudo[26594]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:40 volumio-dac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 26 13:17:40 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:40 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:40 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:40 volumio-dac volumio[26361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:40 volumio-dac volumio[26361]: info: MPD Permissions set Dec 26 13:17:40 volumio-dac volumio[26361]: info: Shairport-Sync Started Dec 26 13:17:40 volumio-dac volumio[26361]: Error adding Membership: Error: addMembership EINVAL Dec 26 13:17:40 volumio-dac volumio[26361]: info: Shairport-Sync Started Dec 26 13:17:40 volumio-dac volumio[26361]: info: Shairport-Sync Started Dec 26 13:17:40 volumio-dac volumio[26361]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 26 13:17:40 volumio-dac volumio[26361]: SPOTIFY: BQCrHiGVh4pLxVM7iYDeh78VeJih33xfdmd4ZVaZKMm4QRJoBbACe3wSh926OmatnidymCw9LM-zjAyBHwKLHqi6z1M_klyLCRodtbI9iPq8oZCR6xwfw2z2v5dz1LJe__0CdjcWbkLETGDm0FFTilI-q1eTH8h7Y0pGVAudI0OZbUFXJnnlRkRcMH9Ts2aV3ZcT7qe5e2xptUYrP3uYcMuee5wldU92PlUnaJ4UcgAbRhlBWcuTL1xYbqsR6JSXI6a_AtFSS9fsuuwJB7qG9rQMRNuH1lYvzkNkrIgQbGU57_ko7SZgdCovi-_h-X8EygH209HLBjbuIIl8tCkM_X9XKGYJAcWk91wNdKfLG-8S6C4dhRs Dec 26 13:17:40 volumio-dac volumio[26361]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 26 13:17:40 volumio-dac volumio[26361]: info: New Spotify access token = BQCrHiGVh4pLxVM7iYDeh78VeJih33xfdmd4ZVaZKMm4QRJoBbACe3wSh926OmatnidymCw9LM-zjAyBHwKLHqi6z1M_klyLCRodtbI9iPq8oZCR6xwfw2z2v5dz1LJe__0CdjcWbkLETGDm0FFTilI-q1eTH8h7Y0pGVAudI0OZbUFXJnnlRkRcMH9Ts2aV3ZcT7qe5e2xptUYrP3uYcMuee5wldU92PlUnaJ4UcgAbRhlBWcuTL1xYbqsR6JSXI6a_AtFSS9fsuuwJB7qG9rQMRNuH1lYvzkNkrIgQbGU57_ko7SZgdCovi-_h-X8EygH209HLBjbuIIl8tCkM_X9XKGYJAcWk91wNdKfLG-8S6C4dhRs Dec 26 13:17:40 volumio-dac volumio[26361]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=debug msg="obtained new client token: AABi+UHilWIV4JjtVMOjkVXu65ixY4M3djtq8+/1kerC9hNmOVpgGP4k1Mup4Qg5laAHK1YEml4G2UDovh5FPHr2qnZpA+kwP87nqW9kMwrj280GRCk6XaI4t340LHihCR5zFOr6cuJFGscw3wD9lh7Bfq1gdjMiXhmM53VA+TJKo7D7D8B8QhTwci12hBTfmvWc9uQNrIHcuxig0Ro8Wo+BI0HaTXCkYtT8YmU3uhfl0gqvSWdBnS47" Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:40 volumio-dac sudo[26610]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 13:17:40 volumio-dac sudo[26610]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:40 volumio-dac volumio[26361]: info: Starting Shairport Sync Dec 26 13:17:40 volumio-dac sudo[26610]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:40 volumio-dac sudo[26619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 13:17:40 volumio-dac sudo[26619]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Dec 26 13:17:40 volumio-dac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 13:17:40 volumio-dac systemd[1]: shairport-sync.service: Succeeded. Dec 26 13:17:40 volumio-dac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 13:17:40 volumio-dac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Dec 26 13:17:40 volumio-dac sudo[26619]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:40 volumio-dac volumio[26361]: info: Shairport-Sync Started Dec 26 13:17:40 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:40 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1320. Dec 26 13:17:40 volumio-dac volumio[26361]: SPOTIFY: User informations: {"country":"BG","display_name":"Nikolay Tsvetarski","email":"tsvetarski@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/medun53"},"followers":{"href":null,"total":4},"href":"https://api.spotify.com/v1/users/medun53","id":"medun53","images":[{"height":300,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10203338845702820&height=300&width=300&ext=1769324739&hash=AT8kTepJwrXv5tDHdxc7aBw-","width":300},{"height":64,"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=10203338845702820&height=50&width=50&ext=1769324739&hash=AT8sqdV-95vT5ebbEftnxRFk","width":64}],"product":"premium","type":"user","uri":"spotify:user:medun53"} Dec 26 13:17:40 volumio-dac volumio[26361]: info: Spotify Successfully logged in Dec 26 13:17:40 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:40 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:40 volumio-dac volumio[26361]: info: [1766747860887] CoreMusicLibrary::Adding element Spotify Dec 26 13:17:40 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:40 volumio-dac volumio[26361]: Cannot find translation for source Mixcloud Dec 26 13:17:40 volumio-dac volumio[26361]: Cannot find translation for source Spotify Dec 26 13:17:40 volumio-dac go-librespot[26577]: time="2025-12-26T13:17:40+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.138:51028->104.199.65.9:443: read: connection reset by peer" Dec 26 13:17:40 volumio-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 13:17:40 volumio-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 13:17:40 volumio-dac volumio[26624]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:41 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:41 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:41 volumio-dac volumio[26361]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 26 13:17:41 volumio-dac volumio[26361]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 26 13:17:41 volumio-dac volumio[26361]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Dec 26 13:17:41 volumio-dac volumio[26361]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Dec 26 13:17:41 volumio-dac volumio[26361]: info: camilladsp service started and running in background, instance 1 Dec 26 13:17:41 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:41 volumio-dac volumio[26361]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 26 13:17:41 volumio-dac volumio[26361]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Dec 26 13:17:41 volumio-dac volumio[26361]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Dec 26 13:17:41 volumio-dac volumio[26361]: info: FusionDsp loaded Dec 26 13:17:41 volumio-dac volumio[26361]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 13:17:41 volumio-dac sudo[26636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 26 13:17:41 volumio-dac sudo[26636]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:41 volumio-dac volumio[26361]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 26 13:17:41 volumio-dac volumio[26361]: info: Adding Signal Path Element [object Object] Dec 26 13:17:41 volumio-dac volumio[26361]: info: Adding fusiondspeq DSP Signal Path Element Dec 26 13:17:41 volumio-dac volumio[26361]: info: FusionDsp - ---- installed callbackRead Dec 26 13:17:41 volumio-dac volumio[26361]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:17:41 volumio-dac volumio[26361]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Dec 26 13:17:41 volumio-dac volumio[26361]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Dec 26 13:17:41 volumio-dac volumio[26361]: at onErrorNT (internal/child_process.js:465:16) Dec 26 13:17:41 volumio-dac volumio[26361]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Dec 26 13:17:41 volumio-dac volumio[26361]: errno: -2, Dec 26 13:17:41 volumio-dac volumio[26361]: code: 'ENOENT', Dec 26 13:17:41 volumio-dac volumio[26361]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Dec 26 13:17:41 volumio-dac volumio[26361]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Dec 26 13:17:41 volumio-dac volumio[26361]: spawnargs: [ Dec 26 13:17:41 volumio-dac volumio[26361]: '-p', Dec 26 13:17:41 volumio-dac volumio[26361]: 9876, Dec 26 13:17:41 volumio-dac volumio[26361]: '-o', Dec 26 13:17:41 volumio-dac volumio[26361]: '/tmp/camilladsp.log', Dec 26 13:17:41 volumio-dac volumio[26361]: '-l', Dec 26 13:17:41 volumio-dac volumio[26361]: 'warn', Dec 26 13:17:41 volumio-dac volumio[26361]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Dec 26 13:17:41 volumio-dac volumio[26361]: ] Dec 26 13:17:41 volumio-dac volumio[26361]: } Dec 26 13:17:41 volumio-dac volumio[26361]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:17:42 volumio-dac mpd[26617]: Dec 26 13:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 13:17:42 volumio-dac systemd[1]: Started Music Player Daemon. Dec 26 13:17:42 volumio-dac sudo[26520]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:42 volumio-dac sudo[26510]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:42 volumio-dac sudo[26602]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:42 volumio-dac sudo[26649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 13:16 Dec 26 13:17:42 volumio-dac sudo[26649]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:42 volumio-dac sudo[26649]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:42 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 26 13:17:42 volumio-dac systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 26 13:17:42 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 26 13:17:42 volumio-dac sudo[26636]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:42 volumio-dac systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 26 13:17:42 volumio-dac systemd[1]: Started dynamicswap service. Dec 26 13:17:42 volumio-dac systemd[1]: dynamicswap.service: Succeeded. Dec 26 13:17:42 volumio-dac systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 26 13:17:42 volumio-dac systemd[1]: volumio.service: Scheduled restart job, restart counter is at 213. Dec 26 13:17:42 volumio-dac systemd[1]: Stopped Volumio Backend Module. Dec 26 13:17:42 volumio-dac systemd[1]: Started Volumio Backend Module. Dec 26 13:17:42 volumio-dac systemd[1]: Started dynamicswap service. Dec 26 13:17:42 volumio-dac systemd[1]: dynamicswap.service: Succeeded. Dec 26 13:17:43 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:43 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1321. Dec 26 13:17:43 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:43 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:43 volumio-dac volumio[26672]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:43 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:43 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:44 volumio-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 13:17:44 volumio-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 26 13:17:44 volumio-dac systemd[1]: Stopped go-librespot Daemon. Dec 26 13:17:44 volumio-dac systemd[1]: Started go-librespot Daemon. Dec 26 13:17:44 volumio-dac go-librespot[26673]: go-librespot daemon starting... Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=info msg="running go-librespot 0.4.0" Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=debug msg="app state loaded" Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=info msg="zeroconf server listening on port 43247" Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=debug msg="obtained new client token: AAB6eh7I+QwjJGORcPav0FBNIsQyFqKWK8FV79kJObTwmfFfPWdr3vRsLTtK+7AiyuvadyaG5WMYPfe6sDwXCaYoENLEVISKB4luU+OawpcYPWpURFo9d74m53ArG7etgiJwfCN7Box2VSjfysfTPIDQYAN8mVGcjyxWL+xUyAqhdUtM6gGSheRUrdyZc/ymgwLwTzTzwmwg4y1vP06J2J3LoI1it6JRc+h0wpCOgRuIRefrkpWGixrD" Dec 26 13:17:44 volumio-dac volumio[26659]: info: ------------------------------------------- Dec 26 13:17:44 volumio-dac volumio[26659]: info: ----- Volumio3 ---- Dec 26 13:17:44 volumio-dac volumio[26659]: info: ------------------------------------------- Dec 26 13:17:44 volumio-dac volumio[26659]: info: ----- System startup ---- Dec 26 13:17:44 volumio-dac volumio[26659]: info: ------------------------------------------- Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 26 13:17:44 volumio-dac go-librespot[26673]: time="2025-12-26T13:17:44+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.138:50572->104.199.65.9:4070: read: connection reset by peer" Dec 26 13:17:44 volumio-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 13:17:44 volumio-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 13:17:45 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:45 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1322. Dec 26 13:17:45 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:45 volumio-dac volumio[26659]: info: MYVOLUMIO Environment detected Dec 26 13:17:45 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:45 volumio-dac volumio[26689]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:45 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:45 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:45 volumio-dac volumio[26659]: info: Plugin folders cleanup Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning into folder /volumio/app/plugins/ Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning category audio_interface Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning category miscellanea Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning category music_service Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning category plugins.json Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning category system_controller Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning category user_interface Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning into folder /data/plugins/ Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning category audio_interface Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning category music_service Dec 26 13:17:45 volumio-dac volumio[26659]: info: Scanning category user_interface Dec 26 13:17:45 volumio-dac volumio[26659]: info: Plugin folders cleanup completed Dec 26 13:17:45 volumio-dac volumio[26659]: info: ------------------------------------------- Dec 26 13:17:45 volumio-dac volumio[26659]: info: ----- Core plugins startup ---- Dec 26 13:17:45 volumio-dac volumio[26659]: info: ------------------------------------------- Dec 26 13:17:45 volumio-dac volumio[26659]: info: Loading plugins from folder /volumio/app/plugins/ Dec 26 13:17:45 volumio-dac volumio[26659]: info: Adding plugin upnp to MyMusic Plugins Dec 26 13:17:45 volumio-dac volumio[26659]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 26 13:17:45 volumio-dac volumio[26659]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 26 13:17:45 volumio-dac volumio[26659]: info: Loading plugins from folder /data/plugins/ Dec 26 13:17:45 volumio-dac volumio[26659]: info: Loading plugin "system"... Dec 26 13:17:45 volumio-dac volumio[26659]: info: Loading plugin "appearance"... Dec 26 13:17:46 volumio-dac volumio[26659]: info: Loading plugin "network"... Dec 26 13:17:46 volumio-dac volumio[26659]: info: Refreshing Cached IP Addresses Dec 26 13:17:46 volumio-dac sudo[26696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 13:17:46 volumio-dac sudo[26696]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:46 volumio-dac sudo[26696]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:46 volumio-dac sudo[26698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 13:17:46 volumio-dac sudo[26698]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:46 volumio-dac sudo[26698]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:46 volumio-dac volumio[26659]: info: Loading plugin "services"... Dec 26 13:17:46 volumio-dac volumio[26659]: info: Loading plugin "alsa_controller"... Dec 26 13:17:46 volumio-dac sudo[26708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 13:17:46 volumio-dac sudo[26708]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:46 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:46 volumio-dac volumio[26659]: info: Loading plugin "wizard"... Dec 26 13:17:46 volumio-dac volumio[26659]: info: Loading plugin "networkfs"... Dec 26 13:17:46 volumio-dac volumio[26659]: info: Starting Udev Watcher for removable devices Dec 26 13:17:46 volumio-dac volumio[26659]: info: Ignoring mount for partition: boot Dec 26 13:17:46 volumio-dac volumio[26659]: info: Ignoring mount for partition: volumio Dec 26 13:17:46 volumio-dac volumio[26659]: info: Ignoring mount for partition: volumio_data Dec 26 13:17:46 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 13:17:46 volumio-dac volumio[26659]: info: Loading plugin "volumio_command_line_client"... Dec 26 13:17:46 volumio-dac volumio[26659]: info: Loading plugin "upnp"... Dec 26 13:17:46 volumio-dac volumio[26659]: info: [1766747866905] Starting Upmpd Daemon Dec 26 13:17:46 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 13:17:46 volumio-dac volumio[26659]: info: Loading plugin "my_music"... Dec 26 13:17:46 volumio-dac volumio[26659]: info: Loading plugin "mpd"... Dec 26 13:17:47 volumio-dac volumio[26659]: info: Loading plugin "upnp_browser"... Dec 26 13:17:47 volumio-dac volumio[26659]: info: Starting UPNP Browser Dec 26 13:17:47 volumio-dac volumio[26659]: info: Loading plugin "alarm-clock"... Dec 26 13:17:47 volumio-dac sudo[26708]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:47 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:47 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1323. Dec 26 13:17:47 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:47 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:47 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:47] [connect] Successful connection Dec 26 13:17:47 volumio-dac volumio[26659]: info: Loading plugin "airplay_emulation"... Dec 26 13:17:47 volumio-dac volumio[26723]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:47 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:47 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:47 volumio-dac volumio[26659]: info: Starting Shairport Sync Dec 26 13:17:47 volumio-dac volumio[26659]: info: Loading plugin "last_100"... Dec 26 13:17:47 volumio-dac volumio[26659]: info: Loading plugin "webradio"... Dec 26 13:17:47 volumio-dac volumio[26659]: info: Loading plugin "i2s_dacs"... Dec 26 13:17:47 volumio-dac volumio[26659]: info: Loading plugin "volumiodiscovery"... Dec 26 13:17:47 volumio-dac volumio[26659]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:47 volumio-dac node[26659]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:47 volumio-dac volumio[26659]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:47 volumio-dac node[26659]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:47 volumio-dac volumio[26659]: *** WARNING *** For more information see Dec 26 13:17:47 volumio-dac node[26659]: *** WARNING *** For more information see Dec 26 13:17:47 volumio-dac volumio[26659]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:47 volumio-dac node[26659]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 13:17:47 volumio-dac volumio[26659]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:47 volumio-dac node[26659]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 13:17:47 volumio-dac volumio[26659]: *** WARNING *** For more information see Dec 26 13:17:47 volumio-dac node[26659]: *** WARNING *** For more information see Dec 26 13:17:47 volumio-dac volumio[26659]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 26 13:17:47 volumio-dac volumio[26659]: info: Discovery: Started advertising with name: Volumio DAC Dec 26 13:17:47 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 13:17:47 volumio-dac volumio[26659]: info: Loading plugin "mixcloud"... Dec 26 13:17:48 volumio-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 13:17:48 volumio-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 26 13:17:48 volumio-dac systemd[1]: Stopped go-librespot Daemon. Dec 26 13:17:48 volumio-dac systemd[1]: Started go-librespot Daemon. Dec 26 13:17:48 volumio-dac go-librespot[26726]: go-librespot daemon starting... Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=info msg="running go-librespot 0.4.0" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=debug msg="app state loaded" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=info msg="zeroconf server listening on port 39821" Dec 26 13:17:48 volumio-dac volumio[26659]: info: Loading plugin "spop"... Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=debug msg="obtained new client token: AACP2fyNutgUv9tFV2NrWOqb3zgEcs9081VrS8jBH85COQle2nYzn+oOq8ZCUdg7Xoxuvl7l75FU0E4J9BY9P11FDwpNwou02gXth9KJfRWfquuWZtWN+HJF+Zpb+F8tyzLvtxzKFp3M1HueQlBG+h+LbXXbb3AQDvAzsu2784UW95wKeIOPl21MuuvcYtRhIndkhHPOrn6QlduwnJPZni8vib4Vz/jensWAJqIFQN4d3KjeihJWWSFu" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=debug msg="completed keyexchange" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=debug msg="completed challenge" Dec 26 13:17:48 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:48+02:00" level=info msg="authenticated AP" username="me***53" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=info msg="authenticated Login5" username="me***53" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=debug msg="initializing zeroconf session" username="me***53" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=debug msg="dealer connection opened" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=trace msg="starting accesspoint recv loop" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=trace msg="starting dealer recv loop" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=trace msg="received accesspoint ping" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=debug msg="received connection id: MWI3MWFjMGUtYWQz...QUJCQzU3RUJEOQ==" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=trace msg="received accesspoint pong ack" Dec 26 13:17:49 volumio-dac go-librespot[26726]: time="2025-12-26T13:17:49+02:00" level=debug msg="put connect state because NEW_DEVICE" Dec 26 13:17:49 volumio-dac volumio[26659]: info: Plugin ytmusic is not enabled Dec 26 13:17:49 volumio-dac volumio[26659]: info: Plugin now_playing is not enabled Dec 26 13:17:49 volumio-dac volumio[26659]: info: Loading plugin "outputs"... Dec 26 13:17:49 volumio-dac volumio[26659]: info: Loading plugin "albumart"... Dec 26 13:17:49 volumio-dac volumio[26659]: info: Plugin example_plugin is not enabled Dec 26 13:17:49 volumio-dac volumio[26659]: info: Loading plugin "inputs"... Dec 26 13:17:49 volumio-dac volumio[26659]: info: Loading plugin "updater_comm"... Dec 26 13:17:49 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:49 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1324. Dec 26 13:17:49 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:49 volumio-dac volumio[26659]: info: Plugin mpdemulation is not enabled Dec 26 13:17:49 volumio-dac volumio[26659]: info: Loading plugin "rest_api"... Dec 26 13:17:49 volumio-dac volumio[26659]: info: Loading plugin "websocket"... Dec 26 13:17:49 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:49 volumio-dac volumio[26659]: info: Starting Socket.io Server version 2.3.0 Dec 26 13:17:49 volumio-dac volumio[26659]: info: Loading plugin "fusiondsp"... Dec 26 13:17:50 volumio-dac volumio[26742]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:50 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:50 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:50 volumio-dac volumio[26659]: Forking 3 albumart workers Dec 26 13:17:50 volumio-dac volumio[26659]: info: Applying required configuration parameters for plugin fusiondsp Dec 26 13:17:50 volumio-dac volumio[26659]: info: Plugin 80s80s is not enabled Dec 26 13:17:50 volumio-dac volumio[26659]: info: Loading i18n strings for locale es Dec 26 13:17:50 volumio-dac volumio[26659]: Updating browse sources language Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::initPlayerControls Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:50 volumio-dac volumio[26659]: Express server listening on port 3000 Dec 26 13:17:50 volumio-dac volumio[26659]: [Metrics] WebUI: 6s 636.89ms Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreStateMachine::resetVolumioState Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreStateMachine::getcurrentVolume Dec 26 13:17:50 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 13:17:50 volumio-dac volumio[26659]: info: Volumio Network Manager: Network status updated: 2 Dec 26 13:17:51 volumio-dac volumio[26659]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 26 13:17:51 volumio-dac volumio[26659]: verbose: New Socket.io Connection to 192.168.0.138:3000 from 192.168.0.92 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 26 13:17:51 volumio-dac volumio-remote-updater[638]: [2025-12-26 13:17:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766747867 101 Dec 26 13:17:51 volumio-dac volumio[26659]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Dec 26 13:17:51 volumio-dac volumio[26659]: verbose: New Socket.io Connection to 192.168.0.138:3000 from 192.168.0.92 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 26 13:17:51 volumio-dac volumio[26659]: Starting albumart workers Dec 26 13:17:51 volumio-dac volumio[26659]: Starting albumart workers Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:51 volumio-dac volumio[26659]: info: Executing start script for DAC IQaudIO DAC Plus Dec 26 13:17:51 volumio-dac volumio[26659]: info: Reloading queue from file Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreStateMachine::setRepeat null single undefined Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreStateMachine::pushState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreStateMachine::setRandom null Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreStateMachine::pushState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:51 volumio-dac volumio[26659]: info: Setting Device type: Raspberry PI Dec 26 13:17:51 volumio-dac volumio[26659]: info: VolumeController:: Volume=0 Mute =false Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreStateMachine::pushState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreStateMachine::updateTrackBlock Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrackBlock Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 13:17:51 volumio-dac volumio[26659]: Starting albumart workers Dec 26 13:17:51 volumio-dac volumio[26659]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Dec 26 13:17:51 volumio-dac volumio[26659]: info: DAC script executed Dec 26 13:17:51 volumio-dac volumio[26659]: info: Completed loading Core Plugins Dec 26 13:17:51 volumio-dac volumio[26659]: info: Preparing to generate the ALSA configuration file Dec 26 13:17:51 volumio-dac volumio[26659]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 13:17:51 volumio-dac volumio[26659]: info: Reading ALSA contributions from plugins. Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 13:17:51 volumio-dac volumio[26659]: info: Discovery: adding f59de741-b187-4a9b-8bce-02c45f5e940d Dec 26 13:17:51 volumio-dac volumio[26659]: info: Discovery: Found device Volumio DAC Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 13:17:51 volumio-dac volumio[26659]: info: Received Get System Info Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 13:17:51 volumio-dac volumio[26659]: info: Discovery: Getting this device information Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: Listing playlists Dec 26 13:17:51 volumio-dac volumio[26659]: verbose: New Socket.io Connection to 192.168.0.138 from 192.168.0.77 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 13:17:51 volumio-dac volumio[26659]: info: VolumeController:: Volume=0 Mute =false Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreStateMachine::pushState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioPushState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 13:17:51 volumio-dac volumio[26659]: info: Received Get System Info Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 13:17:51 volumio-dac volumio[26659]: info: Discovery: Getting this device information Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioGetState Dec 26 13:17:51 volumio-dac volumio[26659]: info: CorePlayQueue::getTrack 0 Dec 26 13:17:51 volumio-dac volumio[26659]: info: Listing playlists Dec 26 13:17:51 volumio-dac volumio[26659]: info: Asound.conf file unchanged, so no further update is needed Dec 26 13:17:51 volumio-dac volumio[26659]: info: Output device has changed, restarting MPD Dec 26 13:17:51 volumio-dac sudo[26781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:17:51 volumio-dac volumio[26659]: info: Output device has changed, restarting Shairport Sync Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:51 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:51 volumio-dac sudo[26781]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:51 volumio-dac sudo[26781]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:51 volumio-dac sudo[26783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:51 volumio-dac sudo[26783]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:51 volumio-dac systemd[1]: Stopping Music Player Daemon... Dec 26 13:17:52 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:52 volumio-dac volumio[26659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:52 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:52 volumio-dac volumio[26659]: info: ___________ START PLUGINS ___________ Dec 26 13:17:52 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:52 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1325. Dec 26 13:17:52 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:52 volumio-dac volumio[26659]: info: ControllerMpd::onStart: Initializing MPD Dec 26 13:17:52 volumio-dac volumio[26659]: info: Creating MPD Configuration file Dec 26 13:17:52 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:52 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:52 volumio-dac sudo[26808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 13:17:52 volumio-dac sudo[26808]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:52 volumio-dac sudo[26808]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:52 volumio-dac volumio[26659]: info: [1766747872167] CoreMusicLibrary::Adding element Media Servers Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:52 volumio-dac volumio[26798]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:52 volumio-dac volumio[26659]: info: UPNP Browser: Client initialized successfully Dec 26 13:17:52 volumio-dac sudo[26811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 13:17:52 volumio-dac sudo[26811]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:52 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:52 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:52 volumio-dac systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 26 13:17:52 volumio-dac systemd[1]: mpd.service: Succeeded. Dec 26 13:17:52 volumio-dac systemd[1]: Stopped Music Player Daemon. Dec 26 13:17:52 volumio-dac systemd[1]: Starting Music Player Daemon... Dec 26 13:17:52 volumio-dac volumio[26659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:52 volumio-dac volumio[26659]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:52 volumio-dac volumio[26659]: info: [1766747872362] CoreMusicLibrary::Adding element Last_100 Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:52 volumio-dac volumio[26659]: info: [1766747872367] CoreMusicLibrary::Adding element Webradio Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:52 volumio-dac volumio[26659]: info: Initializing BBC Radios Dec 26 13:17:52 volumio-dac sudo[26813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 13:17:52 volumio-dac sudo[26813]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:52 volumio-dac sudo[26813]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 13:17:52 volumio-dac volumio[26659]: info: [1766747872473] CoreMusicLibrary::Adding element Mixcloud Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 13:17:52 volumio-dac volumio[26659]: Cannot find translation for source Mixcloud Dec 26 13:17:52 volumio-dac volumio[26659]: info: Creating Spotify config file Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:52 volumio-dac volumio[26659]: info: Loading i18n strings for locale es Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:52 volumio-dac volumio[26659]: info: FusionDsp - mixtype--------------------- Hardware Dec 26 13:17:52 volumio-dac volumio[26659]: info: Volumio Calling Home Dec 26 13:17:52 volumio-dac volumio[26659]: info: Preparing to generate the ALSA configuration file Dec 26 13:17:52 volumio-dac volumio[26659]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 26 13:17:52 volumio-dac volumio[26659]: info: Reading ALSA contributions from plugins. Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:52 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 13:17:53 volumio-dac volumio[26659]: info: MPD Permissions set Dec 26 13:17:53 volumio-dac volumio[26659]: info: MPD Permissions set Dec 26 13:17:53 volumio-dac volumio[26659]: info: Volumio called home Dec 26 13:17:53 volumio-dac volumio[26659]: info: Spotify config file written Dec 26 13:17:53 volumio-dac sudo[26845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 26 13:17:53 volumio-dac sudo[26845]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 13:17:53 volumio-dac volumio[26659]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 26 13:17:53 volumio-dac systemd[1]: Stopping go-librespot Daemon... Dec 26 13:17:53 volumio-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Dec 26 13:17:53 volumio-dac systemd[1]: go-librespot-daemon.service: Succeeded. Dec 26 13:17:53 volumio-dac systemd[1]: Stopped go-librespot Daemon. Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac systemd[1]: Started go-librespot Daemon. Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac go-librespot[26847]: go-librespot daemon starting... Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac sudo[26845]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 13:17:53 volumio-dac volumio[26659]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 13:17:53 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:53+02:00" level=info msg="running go-librespot 0.4.0" Dec 26 13:17:53 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:53+02:00" level=debug msg="app state loaded" Dec 26 13:17:53 volumio-dac volumio[26659]: info: No need to fix Spotify hosts Dec 26 13:17:53 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:53+02:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 13:17:53 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:53 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:53+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 26 13:17:53 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:53+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 26 13:17:53 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:53+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 26 13:17:53 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:53+02:00" level=info msg="zeroconf server listening on port 41665" Dec 26 13:17:53 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:53 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:53+02:00" level=debug msg="obtained new client token: AAB1Ij+p3ScpVZUqXImV6lXu8PI1/jSK/CB09/Bxxi41NWEqsn2e+TlhxcqUVs/tI6mxf+k16r7oPGWbOCSmZg+FXYwmCqfvqc8F7LZdye7G+NjAZOJsB+bUJDg/+aQi8x1nFrp1MrUO6dbJdXTB6sA6U9tWT2IyTPoFfpdRs1cyZmeMHmtKmOhB4+XwfwbyvglX3WLkSUxHoh4vlvoD6VWg5SC0DQbyborl3Q8UFqBPH7VDUaFVHCzK" Dec 26 13:17:53 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:53+02:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 26 13:17:54 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="completed keyexchange" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="completed challenge" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=info msg="authenticated AP" username="me***53" Dec 26 13:17:54 volumio-dac mpd[26836]: Dec 26 13:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 13:17:54 volumio-dac systemd[1]: Started Music Player Daemon. Dec 26 13:17:54 volumio-dac sudo[26811]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:54 volumio-dac sudo[26783]: pam_unix(sudo:session): session closed for user root Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=info msg="authenticated Login5" username="me***53" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="initializing zeroconf session" username="me***53" Dec 26 13:17:54 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:54 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1326. Dec 26 13:17:54 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:54 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="dealer connection opened" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=trace msg="starting accesspoint recv loop" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=trace msg="starting dealer recv loop" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=trace msg="received accesspoint ping" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="received connection id: ZTY4N2U0YTYtMzZk...Nzk4NjZERERDNw==" Dec 26 13:17:54 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=trace msg="received accesspoint pong ack" Dec 26 13:17:54 volumio-dac volumio[26865]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:54 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:54 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:54 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:54 volumio-dac go-librespot[26847]: time="2025-12-26T13:17:54+02:00" level=debug msg="put connect state because NEW_DEVICE" Dec 26 13:17:54 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:54 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:55 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:55 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:55 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:55 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:56 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:56 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:56 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:56 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:56 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:56 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1327. Dec 26 13:17:56 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:56 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:56 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:56 volumio-dac volumio[26867]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:56 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:56 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:56 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:57 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:57 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:57 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:57 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:57 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:57 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:57 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:58 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:58 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:58 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:58 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:58 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:58 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:17:58 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1328. Dec 26 13:17:58 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:17:58 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:58 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:17:59 volumio-dac volumio[26884]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:17:59 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:17:59 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:17:59 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:59 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:59 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:59 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:59 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:59 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:17:59 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:00 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:00 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:00 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:00 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:00 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:00 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:01 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:01 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:18:01 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1329. Dec 26 13:18:01 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:18:01 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:18:01 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:01 volumio-dac volumio[26885]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:18:01 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:18:01 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:18:01 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:01 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:01 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:01 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:02 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:02 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:02 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:02 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:02 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:02 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:02 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:03 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:03 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:03 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:03 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:18:03 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1330. Dec 26 13:18:03 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:18:03 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:18:03 volumio-dac volumio[26886]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:18:03 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:18:03 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:18:03 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:03 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:03 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:04 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:04 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:04 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:04 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:04 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:04 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:04 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:05 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:05 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:05 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:05 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:05 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:18:05 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1331. Dec 26 13:18:05 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:18:05 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:18:05 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:05 volumio-dac volumio[26887]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:18:05 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:18:05 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:18:05 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:05 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:06 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:06 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:06 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:06 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:06 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:07 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:07 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:07 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:07 volumio-dac volumio[26659]: info: FusionDsp - Dec 26 13:18:07 volumio-dac volumio[26659]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:18:07 volumio-dac volumio[26659]: TypeError: Cannot read property 'off' of undefined Dec 26 13:18:07 volumio-dac volumio[26659]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Dec 26 13:18:07 volumio-dac volumio[26659]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Dec 26 13:18:07 volumio-dac volumio[26659]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Dec 26 13:18:07 volumio-dac volumio[26659]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Dec 26 13:18:07 volumio-dac volumio[26659]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) Dec 26 13:18:07 volumio-dac volumio[26659]: at Socket.emit (events.js:315:20) Dec 26 13:18:07 volumio-dac volumio[26659]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 26 13:18:07 volumio-dac volumio[26659]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 26 13:18:07 volumio-dac volumio[26659]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 13:18:07 volumio-dac systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Dec 26 13:18:07 volumio-dac systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1332. Dec 26 13:18:07 volumio-dac systemd[1]: Stopped FusionDsp Daemon. Dec 26 13:18:07 volumio-dac systemd[1]: Started FusionDsp Daemon. Dec 26 13:18:08 volumio-dac volumio[26909]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Dec 26 13:18:08 volumio-dac systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 13:18:08 volumio-dac systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Dec 26 13:18:08 volumio-dac sudo[26912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 13:17 Dec 26 13:18:08 volumio-dac sudo[26912]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="49c352e1d55e9b76c3bd7b0e3940507619bf455a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="1d32690fc900ac8c739e7eabd35ed0f570899eb8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 19 Dec 2025 02:07:26 PM CET" VOLUMIO_VERSION="3.887" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f8c1d964af93dfdda0f02ac4140eec2"