Feb 08 13:20:01 volumio go-librespot[1645]: time="2026-02-08T13:20:01+05:30" level=debug msg="fetched chunk 5/25, size: 524288" uri="spotify:track:6NmGIj2leVHRKkHEGyechJ"
Feb 08 13:20:14 volumio go-librespot[1645]: time="2026-02-08T13:20:14+05:30" level=debug msg="fetched chunk 6/25, size: 524288" uri="spotify:track:6NmGIj2leVHRKkHEGyechJ"
Feb 08 13:20:17 volumio go-librespot[1645]: time="2026-02-08T13:20:17+05:30" level=trace msg="received accesspoint ping"
Feb 08 13:20:18 volumio go-librespot[1645]: time="2026-02-08T13:20:18+05:30" level=trace msg="received accesspoint pong ack"
Feb 08 13:20:18 volumio go-librespot[1645]: time="2026-02-08T13:20:18+05:30" level=trace msg="sent dealer ping"
Feb 08 13:20:18 volumio go-librespot[1645]: time="2026-02-08T13:20:18+05:30" level=trace msg="received dealer pong"
Feb 08 13:20:26 volumio go-librespot[1645]: time="2026-02-08T13:20:26+05:30" level=debug msg="fetched chunk 7/25, size: 524288" uri="spotify:track:6NmGIj2leVHRKkHEGyechJ"
Feb 08 13:20:30 volumio volumio[1227]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 15; 2201122G Build/AQ3A.241006.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Feb 08 13:20:30 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 08 13:20:30 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 08 13:20:30 volumio volumio[1227]: info: Discovery: Getting this device information
Feb 08 13:20:30 volumio volumio[1227]: info: CoreCommandRouter::volumioGetState
Feb 08 13:20:30 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:20:30 volumio volumio[1227]: verbose: New Socket.io Connection to 192.168.1.72:3000 from 192.168.1.73 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9
Feb 08 13:20:30 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Feb 08 13:20:30 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Feb 08 13:20:30 volumio volumio[1227]: info: CoreCommandRouter::volumioGetState
Feb 08 13:20:30 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 08 13:20:30 volumio volumio[1227]: info: Listing playlists
Feb 08 13:20:30 volumio volumio[1227]: info: CoreCommandRouter::volumioGetQueue
Feb 08 13:20:30 volumio volumio[1227]: info: CoreStateMachine::getQueue
Feb 08 13:20:30 volumio volumio[1227]: info: CorePlayQueue::getQueue
Feb 08 13:20:37 volumio go-librespot[1645]: time="2026-02-08T13:20:37+05:30" level=debug msg="fetched chunk 8/25, size: 524288" uri="spotify:track:6NmGIj2leVHRKkHEGyechJ"
Feb 08 13:20:39 volumio volumio[1227]: info: FusionDsp - Stopping clipped samples monitor
Feb 08 13:20:39 volumio volumio[1227]: info: FusionDsp - Clipped samples monitor stopped
Feb 08 13:20:39 volumio volumio[1227]: info: FusionDsp - Stopping FusionDsp service
Feb 08 13:20:39 volumio volumio[1227]: info: camilladsp stopping service pid 11651...
Feb 08 13:20:39 volumio volumio[1227]: grep: /proc/11651/cmdline: binary file matches
Feb 08 13:20:39 volumio volumio[1227]: grep: /proc/11651/cmdline: binary file matches
Feb 08 13:20:39 volumio volumio[1227]: grep: /proc/11651/cmdline: binary file matches
Feb 08 13:20:39 volumio volumio[1227]: grep: /proc/11651/cmdline: binary file matches
Feb 08 13:20:39 volumio volumio[1227]: grep: /proc/11651/cmdline: binary file matches
Feb 08 13:20:39 volumio volumio[1227]: grep: /proc/11651/cmdline: binary file matches
Feb 08 13:20:39 volumio volumio[1227]: info: camilladsp service terminated, instance 2
Feb 08 13:20:39 volumio volumio[1227]: info: Disabling plugin fusiondsp
Feb 08 13:20:39 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::volumioPause
Feb 08 13:20:39 volumio volumio[1227]: info: CoreStateMachine::pause
Feb 08 13:20:39 volumio volumio[1227]: info: CoreStateMachine::stPlaybackTimer
Feb 08 13:20:39 volumio volumio[1227]: info: CoreStateMachine::servicePause
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::servicePause
Feb 08 13:20:39 volumio volumio[1227]: info: Spotify Received pause
Feb 08 13:20:39 volumio volumio[1227]: SPOTIFY: SPOTIFY PAUSE
Feb 08 13:20:39 volumio volumio[1227]: SPOTIFY: {"status":"play","title":"Yeh Haseen Vadiyan Yeh Khula Aasman","artist":"A.R. Rahman, S. P. Balasubrahmanyam, K. S. Chithra","album":"Roja (Original Motion Picture Soundtrack)","albumart":"https://i.scdn.co/image/ab67616d00001e020a6413faed4599fdcbf15c37","uri":"spotify:track:6NmGIj2leVHRKkHEGyechJ","trackType":"spotify","codec":"ogg","seek":0,"duration":320,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":false,"volatile":true,"service":"spop"}
Feb 08 13:20:39 volumio volumio[1227]: info: Sending Spotify command to local API: /player/pause
Feb 08 13:20:39 volumio sudo[12310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service
Feb 08 13:20:39 volumio sudo[12310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:39 volumio volumio[1227]: info: Asound.conf file written
Feb 08 13:20:39 volumio systemd[1]: Stopping fusiondsp.service - FusionDsp Daemon...
Feb 08 13:20:39 volumio sudo[12314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Feb 08 13:20:39 volumio volumio[11649]: ======== Running on http://0.0.0.0:5011 ========
Feb 08 13:20:39 volumio volumio[11649]: (Press CTRL+C to quit)
Feb 08 13:20:39 volumio sudo[12314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:39 volumio sudo[12314]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:39 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Feb 08 13:20:39 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Feb 08 13:20:39 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Feb 08 13:20:39 volumio volumio[1227]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy
Feb 08 13:20:39 volumio volumio[1227]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy
Feb 08 13:20:39 volumio volumio[1227]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy
Feb 08 13:20:39 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:20:39 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:39 volumio sudo[12322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:20:39 volumio sudo[12322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:39 volumio sudo[12322]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:39 volumio sudo[12324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:20:39 volumio sudo[12324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:39 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:20:39 volumio volumio[1227]: info: Done.
Feb 08 13:20:39 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:39 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:20:39 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:20:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:20:39 volumio systemd[1]: mpd.service: Consumed 1.386s CPU time.
Feb 08 13:20:39 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:20:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:20:39 volumio sudo[12340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:20:39 volumio sudo[12340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:20:40 volumio systemd[1]: fusiondsp.service: Deactivated successfully.
Feb 08 13:20:40 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon.
Feb 08 13:20:40 volumio systemd[1]: fusiondsp.service: Consumed 1.241s CPU time.
Feb 08 13:20:40 volumio sudo[12310]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:40 volumio volumio[1227]: info: FusionDsp - Reporting Fusion DSP Disabled
Feb 08 13:20:40 volumio volumio[1227]: info: Removing fusiondspeq DSP Signal Path Element
Feb 08 13:20:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:20:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:20:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:40 volumio systemd[1]: shairport-sync.service: Consumed 2.691s CPU time.
Feb 08 13:20:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:40 volumio sudo[12340]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:40 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:20:40 volumio sudo[12343]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 08 13:20:40 volumio sudo[12343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:20:40 volumio sudo[12343]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:41 volumio mpd[12360]: 2026-02-08T13:20:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 08 13:20:41 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 08 13:20:41 volumio sudo[12324]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:41 volumio volumio[1227]: error: updateQueue error: null
Feb 08 13:20:47 volumio volumio[1227]: info: Enabling plugin fusiondsp
Feb 08 13:20:47 volumio volumio[1227]: info: Loading plugin "fusiondsp"...
Feb 08 13:20:47 volumio volumio[1227]: info: Applying required configuration parameters for plugin fusiondsp
Feb 08 13:20:47 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:20:47 volumio volumio[1227]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Feb 08 13:20:47 volumio volumio[1227]: info: Reading ALSA contributions from plugins.
Feb 08 13:20:47 volumio volumio[1227]: info: Asound.conf file written
Feb 08 13:20:47 volumio sudo[12366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Feb 08 13:20:47 volumio sudo[12366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:47 volumio sudo[12366]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:47 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Feb 08 13:20:47 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Feb 08 13:20:47 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Feb 08 13:20:47 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:20:47 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:47 volumio sudo[12372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:20:47 volumio sudo[12372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:47 volumio sudo[12372]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:47 volumio sudo[12374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:20:47 volumio sudo[12374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:47 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:20:47 volumio volumio[1227]: info: PLUGIN START: fusiondsp
Feb 08 13:20:47 volumio volumio[1227]: info: FusionDsp - CamillaDSP binary found.
Feb 08 13:20:47 volumio volumio[1227]: info: Loading i18n strings for locale en
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: FusionDsp - mixtype--------------------- None
Feb 08 13:20:47 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:20:47 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 08 13:20:47 volumio volumio[1227]: info: Done.
Feb 08 13:20:47 volumio volumio[1227]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Feb 08 13:20:47 volumio volumio[1227]: info: Reading ALSA contributions from plugins.
Feb 08 13:20:47 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:20:47 volumio volumio[1227]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:20:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:20:47 volumio systemd[1]: mpd.service: Consumed 1.386s CPU time.
Feb 08 13:20:47 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:20:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:20:47 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:20:47 volumio volumio[1227]: info: Asound.conf file unchanged, so no further update is needed
Feb 08 13:20:47 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:20:47 volumio sudo[12389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:20:47 volumio sudo[12389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:47 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:20:47 volumio sudo[12391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:20:47 volumio sudo[12391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:47 volumio sudo[12391]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:47 volumio sudo[12394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:20:47 volumio sudo[12394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:47 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:20:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:20:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:20:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:47 volumio systemd[1]: shairport-sync.service: Consumed 1.573s CPU time.
Feb 08 13:20:47 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:47 volumio sudo[12389]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:47 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:20:47 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:20:47 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:20:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:20:47 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:20:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:20:47 volumio sudo[12412]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:20:47 volumio sudo[12412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:20:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:20:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:20:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:47 volumio sudo[12412]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:47 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:20:47 volumio volumio[1227]: (node:1227) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit
Feb 08 13:20:47 volumio volumio[1227]: (Use `node --trace-warnings ...` to show where the warning was created)
Feb 08 13:20:47 volumio volumio[1227]: (node:1227) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit
Feb 08 13:20:47 volumio volumio[1227]: (node:1227) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit
Feb 08 13:20:47 volumio volumio[1227]: (node:1227) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit
Feb 08 13:20:47 volumio volumio[1227]: (node:1227) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit
Feb 08 13:20:47 volumio sudo[12428]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 08 13:20:47 volumio sudo[12428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:20:47 volumio sudo[12428]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:48 volumio go-librespot[1645]: time="2026-02-08T13:20:48+05:30" level=trace msg="sent dealer ping"
Feb 08 13:20:48 volumio go-librespot[1645]: time="2026-02-08T13:20:48+05:30" level=trace msg="received dealer pong"
Feb 08 13:20:49 volumio mpd[12445]: 2026-02-08T13:20:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 08 13:20:49 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 08 13:20:49 volumio sudo[12374]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:49 volumio sudo[12394]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:49 volumio volumio[1227]: error: updateQueue error: null
Feb 08 13:20:49 volumio volumio[1227]: error: updateQueue error: null
Feb 08 13:20:49 volumio volumio[1227]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Feb 08 13:20:49 volumio volumio[1227]: 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
Feb 08 13:20:49 volumio volumio[1227]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Feb 08 13:20:49 volumio volumio[1227]: info: camilladsp service started and running in background, instance 3
Feb 08 13:20:49 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:49 volumio volumio[1227]: cannot open device 'volumioHw': Device or resource busy
Feb 08 13:20:49 volumio volumio[1227]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json
Feb 08 13:20:49 volumio volumio[1227]: cannot open device 'volumioHw': Device or resource busy
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp loaded
Feb 08 13:20:49 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - Reporting Fusion DSP Enabled
Feb 08 13:20:49 volumio volumio[1227]: info: Adding Signal Path Element [object Object]
Feb 08 13:20:49 volumio volumio[1227]: info: Adding fusiondspeq DSP Signal Path Element
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - ---- installed callbackRead
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:20:49 volumio go-librespot[1645]: time="2026-02-08T13:20:49+05:30" level=debug msg="pause track at 64458ms"
Feb 08 13:20:49 volumio sudo[12481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Feb 08 13:20:49 volumio sudo[12481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:20:49 volumio volumio[1227]: info: camilladsp stopping service pid 12469...
Feb 08 13:20:49 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon.
Feb 08 13:20:49 volumio sudo[12481]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:49 volumio volumio[1227]: grep: /proc/12469/cmdline: binary file matches
Feb 08 13:20:49 volumio volumio[1227]: info: camilladsp service terminated, instance 3
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:20:49 volumio volumio[1227]: info: camilladsp service started and running in background, instance 3
Feb 08 13:20:49 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:20:49 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:20:49 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:20:49 volumio go-librespot[1645]: time="2026-02-08T13:20:49+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Feb 08 13:20:49 volumio go-librespot[1645]: time="2026-02-08T13:20:49+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Feb 08 13:20:49 volumio go-librespot[1645]: time="2026-02-08T13:20:49+05:30" level=trace msg="emitting websocket event: paused"
Feb 08 13:20:49 volumio volumio[1227]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:7upC0b5HWBFS4kErzbDMwc","uri":"spotify:track:6NmGIj2leVHRKkHEGyechJ","play_origin":"playlist"}}
Feb 08 13:20:49 volumio volumio[1227]: SPOTIFY: PUSH STATE SPOTIFY
Feb 08 13:20:49 volumio volumio[1227]: SPOTIFY: {"status":"pause","service":"spop","title":"Yeh Haseen Vadiyan Yeh Khula Aasman","artist":"A.R. Rahman, S. P. Balasubrahmanyam, K. S. Chithra","album":"Roja (Original Motion Picture Soundtrack)","albumart":"https://i.scdn.co/image/ab67616d00001e020a6413faed4599fdcbf15c37","uri":"spotify:track:6NmGIj2leVHRKkHEGyechJ","trackType":"spotify","seek":75000,"duration":320,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Feb 08 13:20:49 volumio volumio[1227]: info: CoreCommandRouter::servicePushState
Feb 08 13:20:49 volumio volumio[1227]: info: CoreStateMachine::pushState
Feb 08 13:20:49 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:20:49 volumio volumio[1227]: info: CoreCommandRouter::volumioPushState
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - Volumio is not playing
Feb 08 13:20:49 volumio volumio[1227]: info: FusionDsp - Clipped samples monitor stopped
Feb 08 13:20:51 volumio volumio[1227]: info: FusionDsp - Stopping clipped samples monitor
Feb 08 13:20:51 volumio volumio[1227]: info: FusionDsp - Clipped samples monitor stopped
Feb 08 13:20:51 volumio volumio[1227]: info: FusionDsp - Stopping FusionDsp service
Feb 08 13:20:51 volumio volumio[1227]: info: camilladsp stopping service pid 12492...
Feb 08 13:20:51 volumio volumio[1227]: info: camilladsp service terminated, instance 3
Feb 08 13:20:51 volumio volumio[1227]: info: Disabling plugin fusiondsp
Feb 08 13:20:51 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::volumioPause
Feb 08 13:20:51 volumio volumio[1227]: info: CoreStateMachine::pause
Feb 08 13:20:51 volumio volumio[1227]: info: Asound.conf file written
Feb 08 13:20:51 volumio sudo[12500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service
Feb 08 13:20:51 volumio sudo[12500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:51 volumio sudo[12504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Feb 08 13:20:51 volumio sudo[12504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:51 volumio systemd[1]: Stopping fusiondsp.service - FusionDsp Daemon...
Feb 08 13:20:51 volumio volumio[12490]: ======== Running on http://0.0.0.0:5011 ========
Feb 08 13:20:51 volumio volumio[12490]: (Press CTRL+C to quit)
Feb 08 13:20:51 volumio sudo[12504]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:51 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Feb 08 13:20:51 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Feb 08 13:20:51 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Feb 08 13:20:51 volumio volumio[1227]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy
Feb 08 13:20:51 volumio volumio[1227]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy
Feb 08 13:20:51 volumio volumio[1227]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy
Feb 08 13:20:51 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:20:51 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:51 volumio sudo[12510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:20:51 volumio sudo[12510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:51 volumio sudo[12510]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:51 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:20:51 volumio volumio[1227]: info: Done.
Feb 08 13:20:51 volumio sudo[12513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:20:51 volumio sudo[12513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:51 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:51 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:20:51 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 08 13:20:51 volumio sudo[12525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:20:51 volumio sudo[12525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:51 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:20:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:20:51 volumio systemd[1]: mpd.service: Consumed 1.418s CPU time.
Feb 08 13:20:51 volumio systemd[1]: fusiondsp.service: Deactivated successfully.
Feb 08 13:20:51 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon.
Feb 08 13:20:51 volumio systemd[1]: fusiondsp.service: Consumed 1.220s CPU time.
Feb 08 13:20:51 volumio sudo[12500]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:51 volumio volumio[1227]: info: FusionDsp - Reporting Fusion DSP Disabled
Feb 08 13:20:51 volumio volumio[1227]: info: Removing fusiondspeq DSP Signal Path Element
Feb 08 13:20:51 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:20:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:20:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:20:52 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:20:52 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:20:52 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:52 volumio systemd[1]: shairport-sync.service: Consumed 1.577s CPU time.
Feb 08 13:20:52 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:52 volumio sudo[12525]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:52 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:20:52 volumio sudo[12529]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 08 13:20:52 volumio sudo[12529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:20:52 volumio sudo[12529]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:53 volumio mpd[12546]: 2026-02-08T13:20:53 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 08 13:20:53 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 08 13:20:53 volumio sudo[12513]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:53 volumio volumio[1227]: error: updateQueue error: null
Feb 08 13:20:54 volumio volumio[1227]: info: Enabling plugin fusiondsp
Feb 08 13:20:54 volumio volumio[1227]: info: Loading plugin "fusiondsp"...
Feb 08 13:20:54 volumio volumio[1227]: info: Applying required configuration parameters for plugin fusiondsp
Feb 08 13:20:54 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:20:54 volumio volumio[1227]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Feb 08 13:20:54 volumio volumio[1227]: info: Reading ALSA contributions from plugins.
Feb 08 13:20:54 volumio volumio[1227]: info: Asound.conf file written
Feb 08 13:20:54 volumio sudo[12551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Feb 08 13:20:54 volumio sudo[12551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:54 volumio sudo[12551]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:54 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Feb 08 13:20:54 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Feb 08 13:20:54 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Feb 08 13:20:54 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:20:54 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:54 volumio sudo[12557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:20:54 volumio sudo[12557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:54 volumio sudo[12557]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:54 volumio sudo[12559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:20:54 volumio sudo[12559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:54 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:20:54 volumio volumio[1227]: info: PLUGIN START: fusiondsp
Feb 08 13:20:54 volumio volumio[1227]: info: FusionDsp - CamillaDSP binary found.
Feb 08 13:20:54 volumio volumio[1227]: info: Loading i18n strings for locale en
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: FusionDsp - mixtype--------------------- None
Feb 08 13:20:54 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:20:54 volumio volumio[1227]: info: Done.
Feb 08 13:20:54 volumio volumio[1227]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Feb 08 13:20:54 volumio volumio[1227]: info: Reading ALSA contributions from plugins.
Feb 08 13:20:54 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:20:54 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 08 13:20:54 volumio volumio[1227]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:20:54 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:20:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:20:54 volumio systemd[1]: mpd.service: Consumed 1.383s CPU time.
Feb 08 13:20:54 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:20:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:20:54 volumio volumio[1227]: info: Asound.conf file unchanged, so no further update is needed
Feb 08 13:20:54 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:20:54 volumio sudo[12573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:20:54 volumio sudo[12573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:54 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:54 volumio sudo[12577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:20:54 volumio sudo[12577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:54 volumio sudo[12577]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:54 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:20:54 volumio sudo[12581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:20:54 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio sudo[12581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:20:54 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:55 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:20:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:20:55 volumio sudo[12593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:20:55 volumio sudo[12593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:55 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:20:55 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:20:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:55 volumio systemd[1]: shairport-sync.service: Consumed 1.565s CPU time.
Feb 08 13:20:55 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:20:55 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:20:55 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:20:55 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:55 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:20:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:20:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:20:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:55 volumio sudo[12573]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:55 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:20:55 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:20:55 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:20:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:20:55 volumio sudo[12593]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:55 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:20:55 volumio sudo[12596]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 08 13:20:55 volumio sudo[12596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:20:55 volumio sudo[12596]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:56 volumio mpd[12614]: 2026-02-08T13:20:56 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 08 13:20:56 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 08 13:20:56 volumio sudo[12581]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:56 volumio sudo[12559]: pam_unix(sudo:session): session closed for user root
Feb 08 13:20:56 volumio volumio[1227]: error: MPD error: The expression evaluated to a falsy value:
Feb 08 13:20:56 volumio volumio[1227]: assert.ok(self.idling)
Feb 08 13:20:56 volumio volumio[1227]: error: The expression evaluated to a falsy value:
Feb 08 13:20:56 volumio volumio[1227]: assert.ok(self.idling)
Feb 08 13:20:56 volumio volumio[1227]: error: updateQueue error: null
Feb 08 13:20:56 volumio volumio[1227]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Feb 08 13:20:56 volumio volumio[1227]: 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
Feb 08 13:20:56 volumio volumio[1227]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Feb 08 13:20:56 volumio volumio[1227]: info: camilladsp service started and running in background, instance 4
Feb 08 13:20:56 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp loaded
Feb 08 13:20:56 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - Reporting Fusion DSP Enabled
Feb 08 13:20:56 volumio volumio[1227]: info: Adding Signal Path Element [object Object]
Feb 08 13:20:56 volumio volumio[1227]: info: Adding fusiondspeq DSP Signal Path Element
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - ---- installed callbackRead
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:20:56 volumio volumio[1227]: info: camilladsp stopping service pid 12624...
Feb 08 13:20:56 volumio sudo[12631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Feb 08 13:20:56 volumio sudo[12631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:20:56 volumio volumio[1227]: info: camilladsp service terminated, instance 4
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:20:56 volumio volumio[1227]: info: camilladsp service started and running in background, instance 4
Feb 08 13:20:56 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:20:56 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:20:56 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:20:56 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:20:56 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:20:57 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon.
Feb 08 13:20:57 volumio sudo[12631]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:04 volumio volumio[1227]: info: CoreCommandRouter::volumioVolatilePlay
Feb 08 13:21:04 volumio volumio[1227]: info: CoreStateMachine::volatilePlay
Feb 08 13:21:04 volumio volumio[1227]: info: Spotify Play
Feb 08 13:21:04 volumio volumio[1227]: info: Sending Spotify command to local API: /player/resume
Feb 08 13:21:04 volumio go-librespot[1645]: time="2026-02-08T13:21:04+05:30" level=trace msg="seek to 64458ms (diff: 242ms, samples: 2842597, bytes: 2686134)" uri="spotify:track:6NmGIj2leVHRKkHEGyechJ"
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:04 volumio go-librespot[1645]: time="2026-02-08T13:21:04+05:30" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE"
Feb 08 13:21:04 volumio go-librespot[1645]: time="2026-02-08T13:21:04+05:30" level=debug msg="resume track at 64216ms"
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:04 volumio go-librespot[1645]: time="2026-02-08T13:21:04+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Feb 08 13:21:04 volumio go-librespot[1645]: time="2026-02-08T13:21:04+05:30" level=trace msg="scheduling prefetch in 225s"
Feb 08 13:21:04 volumio go-librespot[1645]: time="2026-02-08T13:21:04+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Feb 08 13:21:04 volumio go-librespot[1645]: time="2026-02-08T13:21:04+05:30" level=trace msg="emitting websocket event: playing"
Feb 08 13:21:04 volumio volumio[1227]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:7upC0b5HWBFS4kErzbDMwc","uri":"spotify:track:6NmGIj2leVHRKkHEGyechJ","resume":true,"play_origin":"playlist"}}
Feb 08 13:21:04 volumio volumio[1227]: SPOTIFY: PUSH STATE SPOTIFY
Feb 08 13:21:04 volumio volumio[1227]: SPOTIFY: {"status":"play","service":"spop","title":"Yeh Haseen Vadiyan Yeh Khula Aasman","artist":"A.R. Rahman, S. P. Balasubrahmanyam, K. S. Chithra","album":"Roja (Original Motion Picture Soundtrack)","albumart":"https://i.scdn.co/image/ab67616d00001e020a6413faed4599fdcbf15c37","uri":"spotify:track:6NmGIj2leVHRKkHEGyechJ","trackType":"spotify","seek":75000,"duration":320,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Feb 08 13:21:04 volumio volumio[1227]: info: CoreCommandRouter::servicePushState
Feb 08 13:21:04 volumio volumio[1227]: info: CoreStateMachine::pushState
Feb 08 13:21:04 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 08 13:21:04 volumio volumio[1227]: info: CoreCommandRouter::volumioPushState
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - Volumio is playing
Feb 08 13:21:04 volumio volumio[1227]: warn: FusionDsp - Monitor WebSocket not open, skipping commands
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - Clipping Monitor started
Feb 08 13:21:04 volumio volumio[1227]: SPOTIFY: PUSH STATE SPOTIFY
Feb 08 13:21:04 volumio volumio[1227]: SPOTIFY: {"status":"play","service":"spop","title":"Yeh Haseen Vadiyan Yeh Khula Aasman","artist":"A.R. Rahman, S. P. Balasubrahmanyam, K. S. Chithra","album":"Roja (Original Motion Picture Soundtrack)","albumart":"https://i.scdn.co/image/ab67616d00001e020a6413faed4599fdcbf15c37","uri":"spotify:track:6NmGIj2leVHRKkHEGyechJ","trackType":"spotify","seek":75000,"duration":320,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"}
Feb 08 13:21:04 volumio volumio[1227]: info: CoreCommandRouter::servicePushState
Feb 08 13:21:04 volumio volumio[1227]: info: CoreStateMachine::pushState
Feb 08 13:21:04 volumio volumio[1227]: info: CoreCommandRouter::volumioPushState
Feb 08 13:21:04 volumio volumio[1227]: info: FusionDsp - Volumio is playing
Feb 08 13:21:10 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 08 13:21:10 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Feb 08 13:21:12 volumio go-librespot[1645]: time="2026-02-08T13:21:12+05:30" level=debug msg="fetched chunk 9/25, size: 524288" uri="spotify:track:6NmGIj2leVHRKkHEGyechJ"
Feb 08 13:21:12 volumio volumio[1227]: info: FusionDsp - Stopping clipped samples monitor
Feb 08 13:21:12 volumio volumio[1227]: info: FusionDsp - Clipped samples monitor stopped
Feb 08 13:21:12 volumio volumio[1227]: info: FusionDsp - Stopping FusionDsp service
Feb 08 13:21:12 volumio volumio[1227]: info: camilladsp stopping service pid 12636...
Feb 08 13:21:13 volumio volumio[1227]: grep: /proc/12636/cmdline: binary file matches
Feb 08 13:21:13 volumio volumio[1227]: grep: /proc/12636/cmdline: binary file matches
Feb 08 13:21:13 volumio volumio[1227]: grep: /proc/12636/cmdline: binary file matches
Feb 08 13:21:13 volumio volumio[1227]: grep: /proc/12636/cmdline: binary file matches
Feb 08 13:21:13 volumio volumio[1227]: grep: /proc/12636/cmdline: binary file matches
Feb 08 13:21:13 volumio volumio[1227]: info: camilladsp service terminated, instance 4
Feb 08 13:21:13 volumio volumio[1227]: info: Disabling plugin fusiondsp
Feb 08 13:21:13 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::volumioPause
Feb 08 13:21:13 volumio volumio[1227]: info: CoreStateMachine::pause
Feb 08 13:21:13 volumio volumio[1227]: info: CoreStateMachine::stPlaybackTimer
Feb 08 13:21:13 volumio volumio[1227]: info: CoreStateMachine::servicePause
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::servicePause
Feb 08 13:21:13 volumio volumio[1227]: info: Spotify Received pause
Feb 08 13:21:13 volumio volumio[1227]: SPOTIFY: SPOTIFY PAUSE
Feb 08 13:21:13 volumio volumio[1227]: SPOTIFY: {"status":"play","title":"Yeh Haseen Vadiyan Yeh Khula Aasman","artist":"A.R. Rahman, S. P. Balasubrahmanyam, K. S. Chithra","album":"Roja (Original Motion Picture Soundtrack)","albumart":"https://i.scdn.co/image/ab67616d00001e020a6413faed4599fdcbf15c37","uri":"spotify:track:6NmGIj2leVHRKkHEGyechJ","trackType":"spotify","codec":"ogg","seek":75000,"duration":320,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":false,"volatile":true,"service":"spop"}
Feb 08 13:21:13 volumio volumio[1227]: info: Sending Spotify command to local API: /player/pause
Feb 08 13:21:13 volumio sudo[12701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service
Feb 08 13:21:13 volumio sudo[12701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:13 volumio systemd[1]: Stopping fusiondsp.service - FusionDsp Daemon...
Feb 08 13:21:13 volumio volumio[12640]: ======== Running on http://0.0.0.0:5011 ========
Feb 08 13:21:13 volumio volumio[12640]: (Press CTRL+C to quit)
Feb 08 13:21:13 volumio volumio[1227]: info: Asound.conf file written
Feb 08 13:21:13 volumio sudo[12705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Feb 08 13:21:13 volumio sudo[12705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:13 volumio sudo[12705]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:13 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Feb 08 13:21:13 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Feb 08 13:21:13 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Feb 08 13:21:13 volumio volumio[1227]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy
Feb 08 13:21:13 volumio volumio[1227]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy
Feb 08 13:21:13 volumio volumio[1227]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy
Feb 08 13:21:13 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:21:13 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:13 volumio sudo[12713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:21:13 volumio sudo[12713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:13 volumio sudo[12715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:21:13 volumio sudo[12715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:13 volumio sudo[12713]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:13 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:21:13 volumio volumio[1227]: info: Done.
Feb 08 13:21:13 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:13 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:21:13 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 08 13:21:13 volumio systemd[1]: fusiondsp.service: Deactivated successfully.
Feb 08 13:21:13 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon.
Feb 08 13:21:13 volumio systemd[1]: fusiondsp.service: Consumed 1.206s CPU time.
Feb 08 13:21:13 volumio sudo[12701]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:13 volumio volumio[1227]: info: FusionDsp - Reporting Fusion DSP Disabled
Feb 08 13:21:13 volumio volumio[1227]: info: Removing fusiondspeq DSP Signal Path Element
Feb 08 13:21:13 volumio sudo[12728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:21:13 volumio sudo[12728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:13 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:21:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:21:13 volumio systemd[1]: mpd.service: Consumed 1.379s CPU time.
Feb 08 13:21:13 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:21:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:21:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:21:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:21:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:21:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:13 volumio systemd[1]: shairport-sync.service: Consumed 1.611s CPU time.
Feb 08 13:21:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:13 volumio sudo[12728]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:13 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:21:13 volumio sudo[12731]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 08 13:21:13 volumio sudo[12731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:21:13 volumio sudo[12731]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:14 volumio mpd[12749]: 2026-02-08T13:21:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 08 13:21:14 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 08 13:21:14 volumio sudo[12715]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:14 volumio volumio[1227]: error: updateQueue error: null
Feb 08 13:21:15 volumio volumio[1227]: info: CoreCommandRouter::volumioPause
Feb 08 13:21:15 volumio volumio[1227]: info: CoreStateMachine::pause
Feb 08 13:21:17 volumio volumio[1227]: info: CoreCommandRouter::volumioPause
Feb 08 13:21:17 volumio volumio[1227]: info: CoreStateMachine::pause
Feb 08 13:21:18 volumio go-librespot[1645]: time="2026-02-08T13:21:18+05:30" level=trace msg="sent dealer ping"
Feb 08 13:21:18 volumio go-librespot[1645]: time="2026-02-08T13:21:18+05:30" level=trace msg="received dealer pong"
Feb 08 13:21:18 volumio volumio[1227]: info: CoreCommandRouter::volumioNext
Feb 08 13:21:18 volumio volumio[1227]: info: CoreStateMachine::next
Feb 08 13:21:18 volumio volumio[1227]: info: Spotify next
Feb 08 13:21:18 volumio volumio[1227]: info: Sending Spotify command to local API: /player/next
Feb 08 13:21:19 volumio volumio[1227]: info: CoreCommandRouter::volumioNext
Feb 08 13:21:19 volumio volumio[1227]: info: CoreStateMachine::next
Feb 08 13:21:19 volumio volumio[1227]: info: Spotify next
Feb 08 13:21:19 volumio volumio[1227]: info: Sending Spotify command to local API: /player/next
Feb 08 13:21:24 volumio volumio[1227]: info: CoreCommandRouter::volumioPrevious
Feb 08 13:21:24 volumio volumio[1227]: info: Spotify previous
Feb 08 13:21:24 volumio volumio[1227]: info: Sending Spotify command to local API: /player/prev
Feb 08 13:21:26 volumio volumio[1227]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 15; 2201122G Build/AQ3A.241006.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11
Feb 08 13:21:26 volumio volumio[1227]: info: CoreCommandRouter::volumioGetState
Feb 08 13:21:26 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 08 13:21:26 volumio volumio[1227]: info: Listing playlists
Feb 08 13:21:26 volumio volumio[1227]: info: CoreCommandRouter::volumioGetQueue
Feb 08 13:21:26 volumio volumio[1227]: info: CoreStateMachine::getQueue
Feb 08 13:21:26 volumio volumio[1227]: info: CorePlayQueue::getQueue
Feb 08 13:21:29 volumio volumio[1227]: info: CoreCommandRouter::volumioRandom
Feb 08 13:21:29 volumio volumio[1227]: info: CoreCommandRouter::writePlayerControls
Feb 08 13:21:29 volumio volumio[1227]: info: CoreStateMachine::setRepeat true single false
Feb 08 13:21:29 volumio volumio[1227]: info: Spotify Repeat: true - false
Feb 08 13:21:29 volumio volumio[1227]: info: Sending Spotify command with payload to local API: /player/repeat_context
Feb 08 13:21:30 volumio volumio[1227]: info: CoreCommandRouter::volumioRandom
Feb 08 13:21:30 volumio volumio[1227]: info: CoreCommandRouter::writePlayerControls
Feb 08 13:21:30 volumio volumio[1227]: info: CoreStateMachine::setRepeat true single false
Feb 08 13:21:30 volumio volumio[1227]: info: Spotify Repeat: true - false
Feb 08 13:21:30 volumio volumio[1227]: info: Sending Spotify command with payload to local API: /player/repeat_context
Feb 08 13:21:30 volumio volumio[1227]: info: CoreCommandRouter::volumioRandom
Feb 08 13:21:30 volumio volumio[1227]: info: CoreCommandRouter::writePlayerControls
Feb 08 13:21:30 volumio volumio[1227]: info: CoreStateMachine::setRepeat true single false
Feb 08 13:21:30 volumio volumio[1227]: info: Spotify Repeat: true - false
Feb 08 13:21:30 volumio volumio[1227]: info: Sending Spotify command with payload to local API: /player/repeat_context
Feb 08 13:21:30 volumio volumio[1227]: info: CoreCommandRouter::volumioRandom
Feb 08 13:21:30 volumio volumio[1227]: info: CoreCommandRouter::writePlayerControls
Feb 08 13:21:30 volumio volumio[1227]: info: CoreStateMachine::setRepeat true single false
Feb 08 13:21:30 volumio volumio[1227]: info: Spotify Repeat: true - false
Feb 08 13:21:30 volumio volumio[1227]: info: Sending Spotify command with payload to local API: /player/repeat_context
Feb 08 13:21:31 volumio volumio[1227]: info: CoreCommandRouter::volumioRandom
Feb 08 13:21:31 volumio volumio[1227]: info: CoreCommandRouter::writePlayerControls
Feb 08 13:21:31 volumio volumio[1227]: info: CoreStateMachine::setRepeat true single false
Feb 08 13:21:31 volumio volumio[1227]: info: Spotify Repeat: true - false
Feb 08 13:21:31 volumio volumio[1227]: info: Sending Spotify command with payload to local API: /player/repeat_context
Feb 08 13:21:31 volumio volumio[1227]: info: CoreCommandRouter::volumioPrevious
Feb 08 13:21:31 volumio volumio[1227]: info: Spotify previous
Feb 08 13:21:31 volumio volumio[1227]: info: Sending Spotify command to local API: /player/prev
Feb 08 13:21:31 volumio volumio[1227]: info: CoreCommandRouter::volumioRandom
Feb 08 13:21:31 volumio volumio[1227]: info: CoreCommandRouter::writePlayerControls
Feb 08 13:21:31 volumio volumio[1227]: info: CoreStateMachine::setRepeat true single false
Feb 08 13:21:31 volumio volumio[1227]: info: Spotify Repeat: true - false
Feb 08 13:21:31 volumio volumio[1227]: info: Sending Spotify command with payload to local API: /player/repeat_context
Feb 08 13:21:32 volumio volumio[1227]: info: CoreCommandRouter::volumioNext
Feb 08 13:21:32 volumio volumio[1227]: info: CoreStateMachine::next
Feb 08 13:21:32 volumio volumio[1227]: info: Spotify next
Feb 08 13:21:32 volumio volumio[1227]: info: Sending Spotify command to local API: /player/next
Feb 08 13:21:38 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 08 13:21:38 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Feb 08 13:21:40 volumio volumio[1227]: info: Enabling plugin fusiondsp
Feb 08 13:21:40 volumio volumio[1227]: info: Loading plugin "fusiondsp"...
Feb 08 13:21:40 volumio volumio[1227]: info: Applying required configuration parameters for plugin fusiondsp
Feb 08 13:21:40 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:21:40 volumio volumio[1227]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Feb 08 13:21:40 volumio volumio[1227]: info: Reading ALSA contributions from plugins.
Feb 08 13:21:40 volumio volumio[1227]: info: Asound.conf file written
Feb 08 13:21:40 volumio sudo[12800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Feb 08 13:21:40 volumio sudo[12800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:40 volumio sudo[12800]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:40 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Feb 08 13:21:40 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Feb 08 13:21:40 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Feb 08 13:21:40 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:21:40 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:40 volumio sudo[12806]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:21:40 volumio sudo[12806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:40 volumio sudo[12808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:21:40 volumio sudo[12808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:40 volumio sudo[12806]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:40 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:21:40 volumio volumio[1227]: info: PLUGIN START: fusiondsp
Feb 08 13:21:40 volumio volumio[1227]: info: FusionDsp - CamillaDSP binary found.
Feb 08 13:21:40 volumio volumio[1227]: info: Loading i18n strings for locale en
Feb 08 13:21:40 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: FusionDsp - mixtype--------------------- None
Feb 08 13:21:40 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:21:40 volumio volumio[1227]: info: Done.
Feb 08 13:21:40 volumio volumio[1227]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Feb 08 13:21:40 volumio volumio[1227]: info: Reading ALSA contributions from plugins.
Feb 08 13:21:40 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:21:40 volumio volumio[1227]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:21:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:21:40 volumio systemd[1]: mpd.service: Consumed 1.370s CPU time.
Feb 08 13:21:40 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:21:40 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:21:40 volumio volumio[1227]: info: Asound.conf file unchanged, so no further update is needed
Feb 08 13:21:40 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:21:40 volumio sudo[12822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:21:40 volumio sudo[12822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:40 volumio sudo[12824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:21:40 volumio sudo[12824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:40 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:40 volumio sudo[12824]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:21:40 volumio sudo[12827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:21:40 volumio sudo[12827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:21:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:21:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:40 volumio systemd[1]: shairport-sync.service: Consumed 1.637s CPU time.
Feb 08 13:21:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:40 volumio sudo[12822]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:40 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:21:40 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:21:40 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:21:40 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:21:40 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:21:40 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:40 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:21:40 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:40 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:40 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:21:40 volumio sudo[12858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:21:40 volumio sudo[12858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:21:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:21:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:40 volumio sudo[12858]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:40 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:21:40 volumio sudo[12854]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 08 13:21:40 volumio sudo[12854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:21:40 volumio sudo[12854]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:41 volumio volumio[1227]: info: FusionDsp - Stopping clipped samples monitor
Feb 08 13:21:41 volumio volumio[1227]: info: FusionDsp - Clipped samples monitor stopped
Feb 08 13:21:41 volumio volumio[1227]: info: FusionDsp - Stopping FusionDsp service
Feb 08 13:21:41 volumio volumio[1227]: info: Disabling plugin fusiondsp
Feb 08 13:21:41 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::volumioPause
Feb 08 13:21:41 volumio volumio[1227]: info: CoreStateMachine::pause
Feb 08 13:21:41 volumio volumio[1227]: info: Asound.conf file written
Feb 08 13:21:41 volumio sudo[12878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service
Feb 08 13:21:41 volumio sudo[12878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:41 volumio sudo[12882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Feb 08 13:21:41 volumio sudo[12882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:41 volumio sudo[12882]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:41 volumio sudo[12878]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:41 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Feb 08 13:21:41 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Feb 08 13:21:41 volumio volumio[1227]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Feb 08 13:21:41 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:21:41 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:41 volumio sudo[12890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:21:41 volumio sudo[12890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:41 volumio sudo[12889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:21:41 volumio sudo[12889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:41 volumio sudo[12889]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:41 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:21:41 volumio volumio[1227]: info: Done.
Feb 08 13:21:41 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:21:41 volumio volumio[1227]: info: FusionDsp - Reporting Fusion DSP Disabled
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:21:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:21:41 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:21:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:41 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:21:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:21:41 volumio sudo[12904]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:21:41 volumio sudo[12904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:21:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:21:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:41 volumio systemd[1]: shairport-sync.service: Consumed 1.030s CPU time.
Feb 08 13:21:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:41 volumio sudo[12904]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:41 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:21:41 volumio sudo[12905]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 08 13:21:41 volumio sudo[12905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:21:41 volumio sudo[12905]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:42 volumio volumio[1227]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Feb 08 13:21:42 volumio volumio[1227]: 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
Feb 08 13:21:42 volumio volumio[1227]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Feb 08 13:21:42 volumio volumio[1227]: info: camilladsp service started and running in background, instance 5
Feb 08 13:21:42 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp loaded
Feb 08 13:21:42 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - Reporting Fusion DSP Enabled
Feb 08 13:21:42 volumio volumio[1227]: info: Adding Signal Path Element [object Object]
Feb 08 13:21:42 volumio volumio[1227]: info: Adding fusiondspeq DSP Signal Path Element
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- installed callbackRead
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:42 volumio sudo[12943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - ---- read samplerate from file: 44100
Feb 08 13:21:42 volumio volumio[1227]: info: camilladsp stopping service pid 12931...
Feb 08 13:21:42 volumio sudo[12943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:42 volumio volumio[1227]: info: camilladsp service terminated, instance 5
Feb 08 13:21:42 volumio volumio[1227]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Feb 08 13:21:42 volumio volumio[1227]: info: camilladsp service started and running in background, instance 5
Feb 08 13:21:42 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:21:42 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:21:42 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:21:42 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:21:42 volumio volumio[1227]: error: FusionDsp - Reload WebSocket error: [object Object]
Feb 08 13:21:42 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon.
Feb 08 13:21:42 volumio sudo[12943]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:43 volumio mpd[12923]: 2026-02-08T13:21:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 08 13:21:43 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 08 13:21:43 volumio sudo[12890]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:43 volumio sudo[12808]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:43 volumio sudo[12827]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:43 volumio volumio[1227]: error: MPD error: The expression evaluated to a falsy value:
Feb 08 13:21:43 volumio volumio[1227]: assert.ok(self.idling)
Feb 08 13:21:43 volumio volumio[1227]: error: The expression evaluated to a falsy value:
Feb 08 13:21:43 volumio volumio[1227]: assert.ok(self.idling)
Feb 08 13:21:43 volumio volumio[1227]: error: MPD error: The expression evaluated to a falsy value:
Feb 08 13:21:43 volumio volumio[1227]: assert.ok(self.idling)
Feb 08 13:21:43 volumio volumio[1227]: error: The expression evaluated to a falsy value:
Feb 08 13:21:43 volumio volumio[1227]: assert.ok(self.idling)
Feb 08 13:21:43 volumio volumio[1227]: error: updateQueue error: null
Feb 08 13:21:43 volumio volumio[1227]: info: Starting Uninstall of plugin audio_interface - fusiondsp
Feb 08 13:21:43 volumio volumio[1227]: info: Uninstalling plugin fusiondsp
Feb 08 13:21:43 volumio volumio[1227]: info: Disabling plugin fusiondsp
Feb 08 13:21:43 volumio volumio[1227]: info: Preparing to generate the ALSA configuration file
Feb 08 13:21:43 volumio volumio[1227]: info: Asound.conf file unchanged, so no further update is needed
Feb 08 13:21:43 volumio volumio[1227]: info: Output device has changed, restarting MPD
Feb 08 13:21:43 volumio volumio[1227]: info: Output device has changed, restarting Shairport Sync
Feb 08 13:21:43 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:43 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:43 volumio sudo[13369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 08 13:21:43 volumio sudo[13369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:43 volumio sudo[13369]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:43 volumio sudo[13371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 08 13:21:43 volumio sudo[13371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:43 volumio volumio[1227]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 08 13:21:43 volumio volumio[1227]: info: Checking if uninstall.sh is present
Feb 08 13:21:43 volumio volumio[1227]: info: Executing uninstall.sh
Feb 08 13:21:43 volumio systemd[1]: Stopping mpd.service - Music Player Daemon...
Feb 08 13:21:43 volumio volumio[1227]: info: MPD Permissions set
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:44 volumio systemd[1]: mpd.service: Deactivated successfully.
Feb 08 13:21:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon.
Feb 08 13:21:44 volumio systemd[1]: mpd.service: Consumed 1.655s CPU time.
Feb 08 13:21:44 volumio systemd[1]: mpd.socket: Deactivated successfully.
Feb 08 13:21:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 08 13:21:44 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 08 13:21:44 volumio sudo[13388]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh
Feb 08 13:21:44 volumio sudo[13388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:44 volumio volumio[1227]: info: Starting Shairport Sync
Feb 08 13:21:44 volumio sudo[13415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 08 13:21:44 volumio sudo[13415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Feb 08 13:21:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon...
Feb 08 13:21:44 volumio systemd[1]: Stopping fusiondsp.service - FusionDsp Daemon...
Feb 08 13:21:44 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Feb 08 13:21:44 volumio volumio[12968]: ======== Running on http://0.0.0.0:5011 ========
Feb 08 13:21:44 volumio volumio[12968]: (Press CTRL+C to quit)
Feb 08 13:21:44 volumio systemd[1]: shairport-sync.service: Deactivated successfully.
Feb 08 13:21:44 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:44 volumio systemd[1]: shairport-sync.service: Consumed 1.498s CPU time.
Feb 08 13:21:44 volumio systemd[1]: fusiondsp.service: Deactivated successfully.
Feb 08 13:21:44 volumio systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon.
Feb 08 13:21:44 volumio systemd[1]: fusiondsp.service: Consumed 1.403s CPU time.
Feb 08 13:21:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Feb 08 13:21:44 volumio sudo[13438]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm /etc/systemd/system/fusiondsp.service
Feb 08 13:21:44 volumio sudo[13438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:21:44 volumio sudo[13415]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:44 volumio sudo[13438]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:44 volumio volumio[1227]: info: Shairport-Sync Started
Feb 08 13:21:44 volumio sudo[13445]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload
Feb 08 13:21:44 volumio sudo[13445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:21:44 volumio systemd[1]: Reloading.
Feb 08 13:21:44 volumio sudo[13426]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 08 13:21:44 volumio sudo[13426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Feb 08 13:21:44 volumio sudo[13426]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:45 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 08 13:21:45 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Feb 08 13:21:45 volumio sudo[13445]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:45 volumio sudo[13388]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:45 volumio volumio[1227]: info: Uninstall script completed
Feb 08 13:21:45 volumio volumio[1227]: info: Removing plugin fusiondsp from configuration
Feb 08 13:21:46 volumio mpd[13778]: 2026-02-08T13:21:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 08 13:21:46 volumio systemd[1]: Started mpd.service - Music Player Daemon.
Feb 08 13:21:46 volumio sudo[13371]: pam_unix(sudo:session): session closed for user root
Feb 08 13:21:46 volumio volumio[1227]: info: Successfully removed fusiondsp configuration files
Feb 08 13:21:46 volumio volumio[1227]: info: Plugin folders cleanup
Feb 08 13:21:46 volumio volumio[1227]: info: Scanning into folder /volumio/app/plugins/
Feb 08 13:21:46 volumio volumio[1227]: info: Scanning category audio_interface
Feb 08 13:21:46 volumio volumio[1227]: info: Scanning category miscellanea
Feb 08 13:21:46 volumio volumio[1227]: info: Scanning category music_service
Feb 08 13:21:46 volumio volumio[1227]: info: Scanning category plugins.json
Feb 08 13:21:46 volumio volumio[1227]: info: Scanning category system_controller
Feb 08 13:21:46 volumio volumio[1227]: info: Scanning category user_interface
Feb 08 13:21:46 volumio volumio[1227]: info: Scanning into folder /data/plugins/
Feb 08 13:21:46 volumio volumio[1227]: info: Scanning category audio_interface
Feb 08 13:21:46 volumio volumio[1227]: info: Cleaning folder for fusiondsp
Feb 08 13:21:47 volumio volumio[1227]: info: Scanning category music_service
Feb 08 13:21:47 volumio volumio[1227]: info: Scanning category system_hardware
Feb 08 13:21:47 volumio volumio[1227]: info: Plugin folders cleanup completed
Feb 08 13:21:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 08 13:21:47 volumio volumio[1227]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.3.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Bluetooth_Remote","name":"Bluetoothremote","category":"system_hardware","version":"0.0.13","icon":"fa-bluetooth","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics Evo Sabre","name":"audiophonics_evo_sabre","category":"system_hardware","version":"2.0.1","icon":"fa-hdd-o","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Pi Screen Setup","name":"pi_screen_setup","category":"system_hardware","version":"0.7.4","icon":"fa-desktop","isManuallyInstalled":false,"enabled":true,"active":true}]
Feb 08 13:21:47 volumio volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Feb 08 13:21:47 volumio volumio[1227]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 08 13:21:47 volumio volumio[1227]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT
Feb 08 13:21:47 volumio volumio[1227]: at ChildProcess._handle.onexit (node:internal/child_process:286:19)
Feb 08 13:21:47 volumio volumio[1227]: at onErrorNT (node:internal/child_process:484:16)
Feb 08 13:21:47 volumio volumio[1227]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) {
Feb 08 13:21:47 volumio volumio[1227]: errno: -2,
Feb 08 13:21:47 volumio volumio[1227]: code: 'ENOENT',
Feb 08 13:21:47 volumio volumio[1227]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp',
Feb 08 13:21:47 volumio volumio[1227]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp',
Feb 08 13:21:47 volumio volumio[1227]: spawnargs: [
Feb 08 13:21:47 volumio volumio[1227]: '-p',
Feb 08 13:21:47 volumio volumio[1227]: 9876,
Feb 08 13:21:47 volumio volumio[1227]: '-o',
Feb 08 13:21:47 volumio volumio[1227]: '/tmp/camilladsp.log',
Feb 08 13:21:47 volumio volumio[1227]: '-l',
Feb 08 13:21:47 volumio volumio[1227]: 'warn',
Feb 08 13:21:47 volumio volumio[1227]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml'
Feb 08 13:21:47 volumio volumio[1227]: ]
Feb 08 13:21:47 volumio volumio[1227]: }
Feb 08 13:21:47 volumio volumio[1227]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 08 13:21:48 volumio go-librespot[1645]: time="2026-02-08T13:21:48+05:30" level=trace msg="sent dealer ping"
Feb 08 13:21:48 volumio sudo[13823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 13:20'
Feb 08 13:21:48 volumio sudo[13823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 08 13:21:48 volumio go-librespot[1645]: time="2026-02-08T13:21:48+05:30" level=trace msg="received dealer pong"
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025"
VOLUMIO_VERSION="4.084"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"