-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2025-05-02 15:54:58 CEST. -- May 02 15:53:02 horst volumio[948]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::volumioGetState May 02 15:53:02 horst volumio[948]: info: Restoring Previous Volume level: 100 false false May 02 15:53:02 horst volumio[948]: info: Disable softmixer device for audio device May 02 15:53:02 horst volumio[948]: info: Output device has changed, restarting MPD May 02 15:53:02 horst sudo[18653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 15:53:02 horst sudo[18653]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:53:02 horst volumio[948]: info: Output device has changed, restarting Shairport Sync May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:53:02 horst sudo[18653]: pam_unix(sudo:session): session closed for user root May 02 15:53:02 horst sudo[18656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 15:53:02 horst sudo[18656]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:53:02 horst systemd[1]: Stopping Music Player Daemon... May 02 15:53:02 horst volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 15:53:02 horst volumio[948]: info: Volume configurations have been set May 02 15:53:02 horst systemd[1]: mpd.service: Succeeded. May 02 15:53:02 horst systemd[1]: Stopped Music Player Daemon. May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 02 15:53:02 horst volumio[948]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus DSP Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 02 15:53:02 horst volumio[948]: info: Disabling external Volume Control May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:53:02 horst volumio[948]: info: CoreStateMachine::pushState May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::volumioPushState May 02 15:53:02 horst systemd[1]: Starting Music Player Daemon... May 02 15:53:02 horst volumio[948]: info: Soft Volume ALSA configuration file deleted May 02 15:53:02 horst volumio[948]: info: Preparing to generate the ALSA configuration file May 02 15:53:02 horst volumio[948]: info: MPD Permissions set May 02 15:53:02 horst volumio[948]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 02 15:53:02 horst volumio[948]: info: Reading ALSA contributions from plugins. May 02 15:53:02 horst sudo[18661]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 15:53:02 horst sudo[18661]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:53:02 horst sudo[18661]: pam_unix(sudo:session): session closed for user root May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 15:53:02 horst volumio[948]: info: Getting Alsa Cards List without I2S DAC May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 02 15:53:02 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 02 15:53:03 horst volumio[948]: info: Starting Shairport Sync May 02 15:53:03 horst sudo[18708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 15:53:03 horst sudo[18708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:53:03 horst systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 15:53:03 horst systemd[1]: shairport-sync.service: Succeeded. May 02 15:53:03 horst systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 15:53:03 horst systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 15:53:03 horst volumio[948]: info: Asound.conf file written May 02 15:53:03 horst sudo[18708]: pam_unix(sudo:session): session closed for user root May 02 15:53:03 horst go-librespot[1160]: time="2025-05-02T15:53:03+02:00" level=debug msg="prefetching next track" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:03 horst sudo[18726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 02 15:53:03 horst sudo[18726]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:53:03 horst sudo[18726]: pam_unix(sudo:session): session closed for user root May 02 15:53:03 horst volumio[948]: info: Output device has changed, restarting MPD May 02 15:53:03 horst sudo[18733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 15:53:03 horst sudo[18733]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:53:03 horst sudo[18733]: pam_unix(sudo:session): session closed for user root May 02 15:53:03 horst volumio[948]: info: Output device has changed, restarting Shairport Sync May 02 15:53:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:53:03 horst go-librespot[1160]: time="2025-05-02T15:53:03+02:00" level=debug msg="selected format OGG_VORBIS_320 (feb55aeb705a464c9175e5ac75db22b856357e4b)" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:03 horst go-librespot[1160]: time="2025-05-02T15:53:03+02:00" level=debug msg="requested aes key for file feb55aeb705a464c9175e5ac75db22b856357e4b, gid: 1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:03 horst sudo[18736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 15:53:03 horst sudo[18736]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:53:03 horst systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 02 15:53:03 horst systemd[1]: mpd.service: Succeeded. May 02 15:53:03 horst systemd[1]: Stopped Music Player Daemon. May 02 15:53:03 horst volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 15:53:03 horst volumio[948]: info: MPD Permissions set May 02 15:53:03 horst volumio[948]: info: Shairport-Sync Started May 02 15:53:03 horst systemd[1]: Starting Music Player Daemon... May 02 15:53:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:53:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:53:03 horst sudo[18741]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 15:53:03 horst sudo[18741]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:53:03 horst sudo[18741]: pam_unix(sudo:session): session closed for user root May 02 15:53:03 horst volumio[948]: info: Starting Shairport Sync May 02 15:53:03 horst sudo[18784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 15:53:03 horst sudo[18784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:53:03 horst go-librespot[1160]: time="2025-05-02T15:53:03+02:00" level=debug msg="fetched first chunk of 15, total size is 7830144 bytes" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:03 horst go-librespot[1160]: time="2025-05-02T15:53:03+02:00" level=info msg="prefetched track \"Top Of The World\" (duration: 179173ms)" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:03 horst systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 15:53:03 horst systemd[1]: shairport-sync.service: Succeeded. May 02 15:53:03 horst systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 15:53:03 horst systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 15:53:03 horst sudo[18784]: pam_unix(sudo:session): session closed for user root May 02 15:53:03 horst volumio[948]: info: Shairport-Sync Started May 02 15:53:03 horst go-librespot[1160]: time="2025-05-02T15:53:03+02:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:04 horst go-librespot[1160]: time="2025-05-02T15:53:04+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:04 horst go-librespot[1160]: time="2025-05-02T15:53:04+02:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:04 horst mpd[18768]: May 02 15:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 15:53:04 horst systemd[1]: Started Music Player Daemon. May 02 15:53:04 horst sudo[18736]: pam_unix(sudo:session): session closed for user root May 02 15:53:04 horst sudo[18656]: pam_unix(sudo:session): session closed for user root May 02 15:53:04 horst volumio[948]: error: MPD error: The expression evaluated to a falsy value: May 02 15:53:04 horst volumio[948]: assert.ok(self.idling) May 02 15:53:04 horst volumio[948]: error: The expression evaluated to a falsy value: May 02 15:53:04 horst volumio[948]: assert.ok(self.idling) May 02 15:53:04 horst volumio[948]: error: updateQueue error: null May 02 15:53:07 horst volumio[948]: info: VolumeController::SetAlsaVolume100 May 02 15:53:07 horst volumio[948]: info: CoreStateMachine::pushState May 02 15:53:07 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 15:53:07 horst volumio[948]: info: CoreCommandRouter::volumioPushState May 02 15:53:07 horst go-librespot[1160]: time="2025-05-02T15:53:07+02:00" level=trace msg="received accesspoint ping" May 02 15:53:07 horst go-librespot[1160]: time="2025-05-02T15:53:07+02:00" level=trace msg="received accesspoint pong ack" May 02 15:53:07 horst go-librespot[1160]: time="2025-05-02T15:53:07+02:00" level=trace msg="sent dealer ping" May 02 15:53:07 horst go-librespot[1160]: time="2025-05-02T15:53:07+02:00" level=trace msg="received dealer pong" May 02 15:53:19 horst volumio[948]: info: CALLMETHOD: audio_interface fusiondsp usethispreset [object Object] May 02 15:53:19 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , usethispreset May 02 15:53:19 horst volumio[948]: info: FusionDsp - Value for usedpreset: May 02 15:53:19 horst volumio[948]: error: FusionDsp - Error reading JSON file: ENOENT: no such file or directory, open '/data/INTERNAL/FusionDsp/presets/EQ3/undefined' {"errno":-2,"code":"ENOENT","syscall":"open","path":"/data/INTERNAL/FusionDsp/presets/EQ3/undefined","stack":"Error: ENOENT: no such file or directory, open '/data/INTERNAL/FusionDsp/presets/EQ3/undefined'"} May 02 15:53:19 horst volumio[948]: error: FusionDsp - Value reading JSON file: May 02 15:53:19 horst volumio[948]: error: FusionDsp - failed processing JSON value: TypeError: Cannot read property 'geq15' of undefined May 02 15:53:19 horst volumio[948]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 02 15:53:19 horst volumio[948]: info: FusionDsp - else 1 -2.5 May 02 15:53:19 horst volumio[948]: error: FusionDsp - WebSocket error: [object Object] May 02 15:53:20 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:53:20 horst volumio[948]: info: CoreCommandRouter::Close All Modals sent May 02 15:53:20 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:53:20 horst volumio[948]: info: FusionDsp - no preset used May 02 15:53:20 horst volumio[948]: info: FusionDsp - undefined May 02 15:53:20 horst volumio[948]: info: FusionDsp - no preset used May 02 15:53:20 horst volumio[948]: info: FusionDsp - undefined May 02 15:53:28 horst volumio[948]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] May 02 15:53:28 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq May 02 15:53:29 horst volumio[948]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 02 15:53:29 horst volumio[948]: info: FusionDsp - else 1 -2.5 May 02 15:53:29 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:53:29 horst volumio[948]: info: CoreCommandRouter::Close All Modals sent May 02 15:53:29 horst volumio[948]: info: FusionDsp - no preset used May 02 15:53:29 horst volumio[948]: info: FusionDsp - undefined May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=trace msg="emitting websocket event: not_playing" May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:32 horst volumio[948]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:0h3Xy4V4apMraB5NuM8U7Z","play_origin":"collection/songs"}} May 02 15:53:32 horst volumio[948]: error: Failed to decode event: not_playing May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=trace msg="emitting websocket event: will_play" May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=info msg="loaded track \"Top Of The World\" (paused: false, position: 0ms, duration: 179173ms, prefetched: true)" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:32 horst volumio[948]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Ehdm1PDlKrdfyBsjwEvd1","play_origin":"collection/songs"}} May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=trace msg="scheduling prefetch in 149s" May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=trace msg="emitting websocket event: metadata" May 02 15:53:32 horst volumio[948]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1Ehdm1PDlKrdfyBsjwEvd1","name":"Top Of The World","artist_names":["Carpenters"],"album_name":"A Song For You","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0275fc0e961ba2028df4787966","position":0,"duration":179173,"release_date":"year:1972","track_number":2,"disc_number":1}} May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 02 15:53:32 horst go-librespot[1160]: time="2025-05-02T15:53:32+02:00" level=trace msg="emitting websocket event: playing" May 02 15:53:32 horst volumio[948]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1Ehdm1PDlKrdfyBsjwEvd1","play_origin":"collection/songs"}} May 02 15:53:32 horst volumio[948]: SPOTIFY: PUSH STATE SPOTIFY May 02 15:53:32 horst volumio[948]: SPOTIFY: {"status":"play","service":"spop","title":"Top Of The World","artist":"Carpenters","album":"A Song For You","albumart":"https://i.scdn.co/image/ab67616d00001e0275fc0e961ba2028df4787966","uri":"spotify:track:1Ehdm1PDlKrdfyBsjwEvd1","trackType":"spotify","seek":0,"duration":179,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 02 15:53:32 horst volumio[948]: info: CoreCommandRouter::servicePushState May 02 15:53:32 horst volumio[948]: info: CoreStateMachine::pushState May 02 15:53:32 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 15:53:32 horst volumio[948]: info: CoreCommandRouter::volumioPushState May 02 15:53:33 horst volumio[948]: SPOTIFY: PUSH STATE SPOTIFY May 02 15:53:33 horst volumio[948]: SPOTIFY: {"status":"play","service":"spop","title":"Top Of The World","artist":"Carpenters","album":"A Song For You","albumart":"https://i.scdn.co/image/ab67616d00001e0275fc0e961ba2028df4787966","uri":"spotify:track:1Ehdm1PDlKrdfyBsjwEvd1","trackType":"spotify","seek":1000,"duration":179,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} May 02 15:53:33 horst volumio[948]: info: CoreCommandRouter::servicePushState May 02 15:53:33 horst volumio[948]: info: CoreStateMachine::pushState May 02 15:53:33 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 15:53:33 horst volumio[948]: info: CoreCommandRouter::volumioPushState May 02 15:53:37 horst volumio[948]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] May 02 15:53:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq May 02 15:53:37 horst go-librespot[1160]: time="2025-05-02T15:53:37+02:00" level=trace msg="sent dealer ping" May 02 15:53:37 horst go-librespot[1160]: time="2025-05-02T15:53:37+02:00" level=trace msg="received dealer pong" May 02 15:53:38 horst volumio[948]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 02 15:53:38 horst volumio[948]: error: FusionDsp - WebSocket error: [object Object] May 02 15:53:38 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:53:38 horst volumio[948]: info: CoreCommandRouter::Close All Modals sent May 02 15:53:38 horst volumio[948]: info: FusionDsp - no preset used May 02 15:53:38 horst volumio[948]: info: FusionDsp - undefined May 02 15:53:42 horst volumio[948]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] May 02 15:53:42 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq May 02 15:53:43 horst volumio[948]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 02 15:53:43 horst volumio[948]: error: FusionDsp - WebSocket error: [object Object] May 02 15:53:43 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:53:43 horst volumio[948]: info: CoreCommandRouter::Close All Modals sent May 02 15:53:43 horst volumio[948]: info: FusionDsp - no preset used May 02 15:53:43 horst volumio[948]: info: FusionDsp - undefined May 02 15:53:45 horst go-librespot[1160]: time="2025-05-02T15:53:45+02:00" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 15:53:51 horst volumio[948]: info: Getting Alsa Cards List without I2S DAC May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 02 15:53:51 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 02 15:53:57 horst go-librespot[1160]: time="2025-05-02T15:53:57+02:00" level=debug msg="fetched chunk 5/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:54:03 horst volumio[948]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 02 15:54:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 02 15:54:03 horst sudo[6421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 15:54:03 horst sudo[6421]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:03 horst sudo[6421]: pam_unix(sudo:session): session closed for user root May 02 15:54:03 horst sudo[6424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 15:54:03 horst sudo[6424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:03 horst volumio[948]: info: MPD Permissions set May 02 15:54:03 horst systemd[1]: Stopping Music Player Daemon... May 02 15:54:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:03 horst systemd[1]: mpd.service: Succeeded. May 02 15:54:03 horst systemd[1]: Stopped Music Player Daemon. May 02 15:54:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:03 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:03 horst systemd[1]: Starting Music Player Daemon... May 02 15:54:03 horst sudo[6441]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 15:54:03 horst sudo[6441]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:03 horst sudo[6441]: pam_unix(sudo:session): session closed for user root May 02 15:54:04 horst mpd[6501]: May 02 15:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 15:54:04 horst systemd[1]: Started Music Player Daemon. May 02 15:54:04 horst sudo[6424]: pam_unix(sudo:session): session closed for user root May 02 15:54:04 horst volumio[948]: error: updateQueue error: null May 02 15:54:05 horst volumio[948]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] May 02 15:54:05 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions May 02 15:54:05 horst sudo[6886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 15:54:05 horst sudo[6886]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:05 horst sudo[6886]: pam_unix(sudo:session): session closed for user root May 02 15:54:05 horst sudo[6889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 15:54:05 horst sudo[6889]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:05 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:05 horst systemd[1]: Stopping Music Player Daemon... May 02 15:54:05 horst systemd[1]: mpd.service: Succeeded. May 02 15:54:05 horst systemd[1]: Stopped Music Player Daemon. May 02 15:54:05 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:05 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:05 horst volumio[948]: info: MPD Permissions set May 02 15:54:05 horst systemd[1]: Starting Music Player Daemon... May 02 15:54:05 horst sudo[6909]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 15:54:05 horst sudo[6909]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:05 horst sudo[6909]: pam_unix(sudo:session): session closed for user root May 02 15:54:06 horst mpd[6947]: May 02 15:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 15:54:06 horst systemd[1]: Started Music Player Daemon. May 02 15:54:06 horst sudo[6889]: pam_unix(sudo:session): session closed for user root May 02 15:54:06 horst volumio[948]: error: updateQueue error: null May 02 15:54:07 horst go-librespot[1160]: time="2025-05-02T15:54:07+02:00" level=trace msg="sent dealer ping" May 02 15:54:07 horst go-librespot[1160]: time="2025-05-02T15:54:07+02:00" level=trace msg="received dealer pong" May 02 15:54:08 horst go-librespot[1160]: time="2025-05-02T15:54:08+02:00" level=debug msg="fetched chunk 6/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 15:54:17 horst volumio[948]: info: Getting Alsa Cards List without I2S DAC May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 02 15:54:17 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 02 15:54:20 horst go-librespot[1160]: time="2025-05-02T15:54:20+02:00" level=debug msg="fetched chunk 7/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:30 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:30 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:30 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:30 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:30 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:30 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:31 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:31 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:31 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:31 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:31 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:31 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:31 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:31 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:31 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:31 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:31 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:31 horst go-librespot[1160]: time="2025-05-02T15:54:31+02:00" level=debug msg="fetched chunk 8/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:54:34 horst volumio[948]: info: FusionDsp - Stopping FusionDsp service May 02 15:54:34 horst volumio[948]: info: camilladsp stopping service pid 16197... May 02 15:54:34 horst volumio[948]: info: camilladsp service terminated, instance 2 May 02 15:54:34 horst volumio[948]: info: Disabling plugin fusiondsp May 02 15:54:34 horst volumio[948]: info: Preparing to generate the ALSA configuration file May 02 15:54:34 horst sudo[16203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 02 15:54:34 horst sudo[16203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:34 horst systemd[1]: Stopping FusionDsp Daemon... May 02 15:54:34 horst volumio[6317]: Backend configuration: May 02 15:54:34 horst volumio[6317]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 02 15:54:34 horst volumio[6317]: active_config_txt: null May 02 15:54:34 horst volumio[6317]: camilla_host: 127.0.0.1 May 02 15:54:34 horst volumio[6317]: camilla_port: 9876 May 02 15:54:34 horst volumio[6317]: coeff_dir: /data/INTERNAL/FusionDsp/filters May 02 15:54:34 horst volumio[6317]: config_dir: /data/configuration/audio_interface/fusiondsp May 02 15:54:34 horst volumio[6317]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 02 15:54:34 horst volumio[6317]: log_file: /tmp/camilladsp.log May 02 15:54:34 horst volumio[6317]: on_get_active_config: null May 02 15:54:34 horst volumio[6317]: on_set_active_config: null May 02 15:54:34 horst volumio[6317]: port: 5011 May 02 15:54:34 horst volumio[6317]: supported_capture_types: null May 02 15:54:34 horst volumio[6317]: supported_playback_types: null May 02 15:54:34 horst volumio[6317]: update_config_symlink: false May 02 15:54:34 horst volumio[6317]: update_config_txt: false May 02 15:54:34 horst volumio[6317]: ======== Running on http://0.0.0.0:5011 ======== May 02 15:54:34 horst volumio[6317]: (Press CTRL+C to quit) May 02 15:54:34 horst volumio[948]: info: Asound.conf file written May 02 15:54:34 horst sudo[16213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 02 15:54:34 horst sudo[16213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:34 horst sudo[16213]: pam_unix(sudo:session): session closed for user root May 02 15:54:34 horst systemd[1]: fusiondsp.service: Succeeded. May 02 15:54:34 horst systemd[1]: Stopped FusionDsp Daemon. May 02 15:54:34 horst volumio[948]: info: Output device has changed, restarting MPD May 02 15:54:34 horst sudo[16203]: pam_unix(sudo:session): session closed for user root May 02 15:54:34 horst sudo[16218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 15:54:34 horst sudo[16218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:34 horst sudo[16218]: pam_unix(sudo:session): session closed for user root May 02 15:54:34 horst volumio[948]: info: Output device has changed, restarting Shairport Sync May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:34 horst sudo[16221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 15:54:34 horst sudo[16221]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:34 horst systemd[1]: Stopping Music Player Daemon... May 02 15:54:34 horst volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 15:54:34 horst volumio[948]: info: Done. May 02 15:54:34 horst systemd[1]: mpd.service: Succeeded. May 02 15:54:34 horst systemd[1]: Stopped Music Player Daemon. May 02 15:54:34 horst volumio[948]: info: MPD Permissions set May 02 15:54:34 horst volumio[948]: info: FusionDsp - Reporting Fusion DSP Disabled May 02 15:54:34 horst volumio[948]: info: Removing fusiondspeq DSP Signal Path Element May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:34 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:34 horst volumio[948]: info: Starting Shairport Sync May 02 15:54:34 horst sudo[16239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 15:54:34 horst sudo[16239]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:34 horst systemd[1]: Starting Music Player Daemon... May 02 15:54:34 horst systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 15:54:34 horst systemd[1]: shairport-sync.service: Succeeded. May 02 15:54:34 horst systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 15:54:34 horst systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 15:54:34 horst sudo[16239]: pam_unix(sudo:session): session closed for user root May 02 15:54:34 horst volumio[948]: info: Shairport-Sync Started May 02 15:54:34 horst sudo[16226]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 15:54:34 horst sudo[16226]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:34 horst sudo[16226]: pam_unix(sudo:session): session closed for user root May 02 15:54:35 horst mpd[16259]: May 02 15:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 15:54:35 horst systemd[1]: Started Music Player Daemon. May 02 15:54:35 horst sudo[16221]: pam_unix(sudo:session): session closed for user root May 02 15:54:35 horst volumio[948]: error: updateQueue error: null May 02 15:54:37 horst go-librespot[1160]: time="2025-05-02T15:54:37+02:00" level=trace msg="sent dealer ping" May 02 15:54:37 horst volumio[948]: info: Enabling plugin fusiondsp May 02 15:54:37 horst volumio[948]: info: Loading plugin "fusiondsp"... May 02 15:54:37 horst volumio[948]: info: Applying required configuration parameters for plugin fusiondsp May 02 15:54:37 horst volumio[948]: info: Preparing to generate the ALSA configuration file May 02 15:54:37 horst go-librespot[1160]: time="2025-05-02T15:54:37+02:00" level=trace msg="received dealer pong" May 02 15:54:37 horst volumio[948]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 02 15:54:37 horst volumio[948]: info: Reading ALSA contributions from plugins. May 02 15:54:37 horst volumio[948]: info: Asound.conf file written May 02 15:54:37 horst sudo[16824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 02 15:54:37 horst sudo[16824]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:37 horst sudo[16824]: pam_unix(sudo:session): session closed for user root May 02 15:54:37 horst volumio[948]: info: Output device has changed, restarting MPD May 02 15:54:37 horst sudo[16829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 15:54:37 horst sudo[16829]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:37 horst sudo[16829]: pam_unix(sudo:session): session closed for user root May 02 15:54:37 horst volumio[948]: info: Output device has changed, restarting Shairport Sync May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:37 horst sudo[16832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 15:54:37 horst sudo[16832]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:37 horst systemd[1]: Stopping Music Player Daemon... May 02 15:54:37 horst volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 15:54:37 horst volumio[948]: info: PLUGIN START: fusiondsp May 02 15:54:37 horst systemd[1]: mpd.service: Succeeded. May 02 15:54:37 horst systemd[1]: Stopped Music Player Daemon. May 02 15:54:37 horst volumio[948]: info: Loading i18n strings for locale nl May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:37 horst volumio[948]: info: FusionDsp - mixtype--------------------- None May 02 15:54:37 horst volumio[948]: info: Preparing to generate the ALSA configuration file May 02 15:54:37 horst volumio[948]: info: Done. May 02 15:54:37 horst volumio[948]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 02 15:54:37 horst volumio[948]: info: Reading ALSA contributions from plugins. May 02 15:54:37 horst volumio[948]: info: MPD Permissions set May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:37 horst systemd[1]: Starting Music Player Daemon... May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:37 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:37 horst volumio[948]: info: Starting Shairport Sync May 02 15:54:38 horst sudo[16837]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 15:54:38 horst sudo[16837]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:38 horst sudo[16857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 15:54:38 horst sudo[16857]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:38 horst sudo[16837]: pam_unix(sudo:session): session closed for user root May 02 15:54:38 horst volumio[948]: info: Asound.conf file unchanged, so no further update is needed May 02 15:54:38 horst volumio[948]: info: Output device has changed, restarting MPD May 02 15:54:38 horst systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 15:54:38 horst systemd[1]: shairport-sync.service: Succeeded. May 02 15:54:38 horst systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 15:54:38 horst sudo[16862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 15:54:38 horst sudo[16862]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:38 horst sudo[16862]: pam_unix(sudo:session): session closed for user root May 02 15:54:38 horst systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 15:54:38 horst volumio[948]: info: Output device has changed, restarting Shairport Sync May 02 15:54:38 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:38 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:38 horst sudo[16857]: pam_unix(sudo:session): session closed for user root May 02 15:54:38 horst sudo[16866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 15:54:38 horst sudo[16866]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:38 horst systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 02 15:54:38 horst systemd[1]: mpd.service: Succeeded. May 02 15:54:38 horst systemd[1]: Stopped Music Player Daemon. May 02 15:54:38 horst systemd[1]: Starting Music Player Daemon... May 02 15:54:38 horst volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 15:54:38 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:38 horst sudo[16873]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 15:54:38 horst sudo[16873]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:38 horst sudo[16873]: pam_unix(sudo:session): session closed for user root May 02 15:54:38 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:38 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:38 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:38 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:38 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:38 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:38 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:38 horst volumio[948]: info: MPD Permissions set May 02 15:54:38 horst volumio[948]: info: Shairport-Sync Started May 02 15:54:38 horst volumio[948]: (node:948) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 02 15:54:38 horst volumio[948]: (Use `node --trace-warnings ...` to show where the warning was created) May 02 15:54:38 horst volumio[948]: (node:948) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 02 15:54:38 horst volumio[948]: (node:948) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 02 15:54:38 horst volumio[948]: (node:948) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 02 15:54:38 horst volumio[948]: (node:948) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit May 02 15:54:38 horst mpd[16875]: May 02 15:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 15:54:38 horst systemd[1]: Started Music Player Daemon. May 02 15:54:38 horst sudo[16866]: pam_unix(sudo:session): session closed for user root May 02 15:54:38 horst sudo[16832]: pam_unix(sudo:session): session closed for user root May 02 15:54:38 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:39 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:39 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:39 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:39 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:39 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:39 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:39 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:39 horst volumio[948]: error: MPD error: The expression evaluated to a falsy value: May 02 15:54:39 horst volumio[948]: assert.ok(self.idling) May 02 15:54:39 horst volumio[948]: error: The expression evaluated to a falsy value: May 02 15:54:39 horst volumio[948]: assert.ok(self.idling) May 02 15:54:39 horst volumio[948]: error: updateQueue error: null May 02 15:54:39 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:39 horst volumio[948]: info: Starting Shairport Sync May 02 15:54:39 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:39 horst sudo[16897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 15:54:39 horst sudo[16897]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:39 horst systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 15:54:39 horst systemd[1]: shairport-sync.service: Succeeded. May 02 15:54:39 horst systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 15:54:39 horst systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 15:54:39 horst sudo[16897]: pam_unix(sudo:session): session closed for user root May 02 15:54:39 horst volumio[948]: info: Shairport-Sync Started May 02 15:54:39 horst volumio[948]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 02 15:54:39 horst volumio[948]: 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 May 02 15:54:39 horst volumio[948]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 02 15:54:39 horst volumio[948]: info: camilladsp service started and running in background, instance 3 May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:39 horst volumio[948]: cannot open device 'volumioHw': Device or resource busy May 02 15:54:39 horst volumio[948]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 02 15:54:39 horst volumio[948]: cannot open device 'volumioHw': Device or resource busy May 02 15:54:39 horst volumio[948]: info: FusionDsp loaded May 02 15:54:39 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 15:54:40 horst sudo[17002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 02 15:54:40 horst sudo[17002]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:40 horst volumio[948]: info: FusionDsp - Reporting Fusion DSP Enabled May 02 15:54:40 horst volumio[948]: info: Adding Signal Path Element [object Object] May 02 15:54:40 horst volumio[948]: info: Adding fusiondspeq DSP Signal Path Element May 02 15:54:40 horst volumio[948]: info: FusionDsp - ---- installed callbackRead May 02 15:54:40 horst volumio[948]: info: FusionDsp - ---- read samplerate, raw: May 02 15:54:40 horst volumio[948]: error: FusionDsp - invalid sample rate May 02 15:54:40 horst volumio[948]: info: FusionDsp - ---- read samplerate, raw: May 02 15:54:40 horst volumio[948]: error: FusionDsp - invalid sample rate May 02 15:54:40 horst volumio[948]: info: FusionDsp - ---- read samplerate, raw: May 02 15:54:40 horst volumio[948]: error: FusionDsp - invalid sample rate May 02 15:54:40 horst volumio[948]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 02 15:54:40 horst systemd[1]: Started FusionDsp Daemon. May 02 15:54:40 horst sudo[17002]: pam_unix(sudo:session): session closed for user root May 02 15:54:40 horst volumio[948]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 02 15:54:44 horst go-librespot[1160]: time="2025-05-02T15:54:44+02:00" level=debug msg="fetched chunk 9/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:54:45 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 15:54:45 horst volumio[948]: info: Received Get System Info May 02 15:54:45 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 15:54:45 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 15:54:45 horst volumio[948]: info: Discovery: Getting this device information May 02 15:54:45 horst volumio[948]: info: CoreCommandRouter::volumioGetState May 02 15:54:45 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 15:54:45 horst volumio[948]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] May 02 15:54:45 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq May 02 15:54:46 horst volumio[948]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 02 15:54:46 horst volumio[948]: error: FusionDsp - WebSocket error: [object Object] May 02 15:54:46 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:46 horst volumio[948]: info: CoreCommandRouter::Close All Modals sent May 02 15:54:46 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:46 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:52 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 15:54:52 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:52 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:52 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:52 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:52 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:52 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:52 horst volumio[948]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 15:54:52 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 02 15:54:52 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:52 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:52 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:52 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:52 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:52 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:53 horst volumio[948]: info: FusionDsp - no preset used May 02 15:54:53 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:53 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:53 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:53 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:53 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:53 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:53 horst volumio[948]: info: FusionDsp - undefined May 02 15:54:56 horst volumio[948]: info: Starting Uninstall of plugin audio_interface - fusiondsp May 02 15:54:56 horst volumio[948]: info: Uninstalling plugin fusiondsp May 02 15:54:56 horst volumio[948]: info: FusionDsp - Stopping FusionDsp service May 02 15:54:56 horst volumio[948]: info: camilladsp stopping service pid 21901... May 02 15:54:56 horst volumio[948]: info: camilladsp service terminated, instance 3 May 02 15:54:56 horst volumio[948]: info: Disabling plugin fusiondsp May 02 15:54:56 horst volumio[948]: info: Preparing to generate the ALSA configuration file May 02 15:54:56 horst sudo[21909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 02 15:54:56 horst sudo[21909]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:56 horst volumio[17004]: Backend configuration: May 02 15:54:56 horst volumio[17004]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 02 15:54:56 horst volumio[17004]: active_config_txt: null May 02 15:54:56 horst volumio[17004]: camilla_host: 127.0.0.1 May 02 15:54:56 horst volumio[17004]: camilla_port: 9876 May 02 15:54:56 horst volumio[17004]: coeff_dir: /data/INTERNAL/FusionDsp/filters May 02 15:54:56 horst volumio[17004]: config_dir: /data/configuration/audio_interface/fusiondsp May 02 15:54:56 horst volumio[17004]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 02 15:54:56 horst volumio[17004]: log_file: /tmp/camilladsp.log May 02 15:54:56 horst volumio[17004]: on_get_active_config: null May 02 15:54:56 horst volumio[17004]: on_set_active_config: null May 02 15:54:56 horst volumio[17004]: port: 5011 May 02 15:54:56 horst volumio[17004]: supported_capture_types: null May 02 15:54:56 horst volumio[17004]: supported_playback_types: null May 02 15:54:56 horst volumio[17004]: update_config_symlink: false May 02 15:54:56 horst volumio[17004]: update_config_txt: false May 02 15:54:56 horst volumio[17004]: ======== Running on http://0.0.0.0:5011 ======== May 02 15:54:56 horst volumio[17004]: (Press CTRL+C to quit) May 02 15:54:56 horst systemd[1]: Stopping FusionDsp Daemon... May 02 15:54:56 horst volumio[948]: info: Asound.conf file written May 02 15:54:56 horst sudo[21919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 02 15:54:56 horst sudo[21919]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:56 horst sudo[21919]: pam_unix(sudo:session): session closed for user root May 02 15:54:56 horst systemd[1]: fusiondsp.service: Succeeded. May 02 15:54:56 horst systemd[1]: Stopped FusionDsp Daemon. May 02 15:54:56 horst sudo[21909]: pam_unix(sudo:session): session closed for user root May 02 15:54:56 horst volumio[948]: info: Output device has changed, restarting MPD May 02 15:54:56 horst sudo[21924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 02 15:54:56 horst sudo[21924]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:56 horst sudo[21924]: pam_unix(sudo:session): session closed for user root May 02 15:54:56 horst volumio[948]: info: Output device has changed, restarting Shairport Sync May 02 15:54:56 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:56 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:56 horst sudo[21927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 15:54:56 horst sudo[21927]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:56 horst systemd[1]: Stopping Music Player Daemon... May 02 15:54:56 horst volumio[948]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 02 15:54:56 horst volumio[948]: info: Checking if uninstall.sh is present May 02 15:54:56 horst systemd[1]: mpd.service: Succeeded. May 02 15:54:56 horst systemd[1]: Stopped Music Player Daemon. May 02 15:54:56 horst volumio[948]: info: MPD Permissions set May 02 15:54:56 horst volumio[948]: info: FusionDsp - Reporting Fusion DSP Disabled May 02 15:54:56 horst volumio[948]: info: Removing fusiondspeq DSP Signal Path Element May 02 15:54:56 horst volumio[948]: info: Executing uninstall.sh May 02 15:54:56 horst go-librespot[1160]: time="2025-05-02T15:54:56+02:00" level=debug msg="fetched chunk 10/14, size: 524288" uri="spotify:track:1Ehdm1PDlKrdfyBsjwEvd1" May 02 15:54:56 horst systemd[1]: Starting Music Player Daemon... May 02 15:54:56 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:56 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 15:54:56 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 15:54:56 horst volumio[948]: info: Starting Shairport Sync May 02 15:54:56 horst sudo[21935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh May 02 15:54:56 horst sudo[21935]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:56 horst sudo[21950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 02 15:54:56 horst sudo[21950]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:56 horst sudo[21932]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 15:54:56 horst sudo[21932]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:56 horst sudo[21932]: pam_unix(sudo:session): session closed for user root May 02 15:54:56 horst systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 02 15:54:56 horst systemd[1]: shairport-sync.service: Succeeded. May 02 15:54:56 horst systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 02 15:54:56 horst sudo[21956]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /lib/systemd/system/fusiondsp.service May 02 15:54:56 horst sudo[21956]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:57 horst systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 02 15:54:56 horst sudo[21956]: pam_unix(sudo:session): session closed for user root May 02 15:54:56 horst sudo[21965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 02 15:54:56 horst sudo[21965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 15:54:57 horst sudo[21950]: pam_unix(sudo:session): session closed for user root May 02 15:54:57 horst systemd[1]: Reloading. May 02 15:54:57 horst volumio[948]: info: Shairport-Sync Started May 02 15:54:57 horst systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 02 15:54:57 horst systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. May 02 15:54:57 horst sudo[21965]: pam_unix(sudo:session): session closed for user root May 02 15:54:57 horst sudo[21935]: pam_unix(sudo:session): session closed for user root May 02 15:54:57 horst volumio[948]: info: Uninstall script completed May 02 15:54:57 horst volumio[948]: info: Removing plugin fusiondsp from configuration May 02 15:54:57 horst mpd[21954]: May 02 15:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 15:54:57 horst systemd[1]: Started Music Player Daemon. May 02 15:54:57 horst sudo[21927]: pam_unix(sudo:session): session closed for user root May 02 15:54:57 horst volumio[948]: info: Successfully removed fusiondsp configuration files May 02 15:54:57 horst volumio[948]: info: Plugin folders cleanup May 02 15:54:57 horst volumio[948]: info: Scanning into folder /volumio/app/plugins/ May 02 15:54:57 horst volumio[948]: info: Scanning category audio_interface May 02 15:54:57 horst volumio[948]: info: Scanning category miscellanea May 02 15:54:57 horst volumio[948]: info: Scanning category music_service May 02 15:54:57 horst volumio[948]: info: Scanning category plugins.json May 02 15:54:57 horst volumio[948]: info: Scanning category system_controller May 02 15:54:57 horst volumio[948]: info: Scanning category user_interface May 02 15:54:57 horst volumio[948]: info: Scanning into folder /data/plugins/ May 02 15:54:57 horst volumio[948]: info: Scanning category audio_interface May 02 15:54:57 horst volumio[948]: info: Cleaning folder for fusiondsp May 02 15:54:57 horst volumio[948]: info: Scanning category music_service May 02 15:54:57 horst volumio[948]: info: Plugin folders cleanup completed May 02 15:54:57 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 15:54:57 horst volumio[948]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true}] May 02 15:54:57 horst volumio[948]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 02 15:54:58 horst volumio[948]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 15:54:58 horst volumio[948]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 02 15:54:58 horst volumio[948]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 02 15:54:58 horst volumio[948]: at onErrorNT (internal/child_process.js:465:16) May 02 15:54:58 horst volumio[948]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 02 15:54:58 horst volumio[948]: errno: -2, May 02 15:54:58 horst volumio[948]: code: 'ENOENT', May 02 15:54:58 horst volumio[948]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 02 15:54:58 horst volumio[948]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 02 15:54:58 horst volumio[948]: spawnargs: [ May 02 15:54:58 horst volumio[948]: '-p', May 02 15:54:58 horst volumio[948]: 9876, May 02 15:54:58 horst volumio[948]: '-o', May 02 15:54:58 horst volumio[948]: '/tmp/camilladsp.log', May 02 15:54:58 horst volumio[948]: '-l', May 02 15:54:58 horst volumio[948]: 'warn', May 02 15:54:58 horst volumio[948]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 02 15:54:58 horst volumio[948]: ] May 02 15:54:58 horst volumio[948]: } May 02 15:54:58 horst volumio[948]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 15:54:58 horst sudo[22105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 15:53 May 02 15:54:58 horst sudo[22105]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"