May 28 20:36:57 music volumio[1728]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::volumioGetState May 28 20:36:57 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 28 20:36:57 music volumio[1728]: info: Restoring Previous Volume level: 100 false false May 28 20:36:57 music volumio[1728]: info: Output device has changed, restarting MPD May 28 20:36:57 music sudo[3947]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 20:36:57 music sudo[3947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 20:36:57 music sudo[3947]: pam_unix(sudo:session): session closed for user root May 28 20:36:57 music sudo[3949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 20:36:57 music sudo[3949]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 20:36:57 music sudo[3951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 20:36:57 music sudo[3951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 20:36:57 music volumio[1728]: info: Output device has changed, restarting Shairport Sync May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:36:57 music sudo[3955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 20:36:57 music sudo[3955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 20:36:57 music sudo[3951]: pam_unix(sudo:session): session closed for user root May 28 20:36:57 music volumio[1728]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 20:36:57 music volumio[1728]: info: Volume configurations have been set May 28 20:36:57 music systemd[1]: Stopping mpd.service - Music Player Daemon... May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 28 20:36:57 music volumio[1728]: info: Updating Volume Controller Parameters: Device: 5 Name: SXW-MDL7601-INTCLK_A2 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 28 20:36:57 music volumio[1728]: info: Disabling external Volume Control May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 20:36:57 music systemd[1]: mpd.service: Deactivated successfully. May 28 20:36:57 music systemd[1]: Stopped mpd.service - Music Player Daemon. May 28 20:36:57 music systemd[1]: mpd.service: Consumed 1.233s CPU time. May 28 20:36:57 music systemd[1]: mpd.socket: Deactivated successfully. May 28 20:36:57 music systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 28 20:36:57 music systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 28 20:36:57 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:36:57 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:36:57 music volumio[1728]: info: MPD Permissions set May 28 20:36:57 music volumio[1728]: info: MPD Permissions set May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 28 20:36:57 music systemd[1]: Starting mpd.service - Music Player Daemon... May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 28 20:36:57 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 28 20:36:57 music volumio[1728]: info: Starting Shairport Sync May 28 20:36:57 music sudo[3978]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 20:36:57 music sudo[3978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 20:36:57 music sudo[3978]: pam_unix(sudo:session): session closed for user root May 28 20:36:57 music sudo[3988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 20:36:57 music sudo[3988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 20:36:57 music systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 28 20:36:57 music systemd[1]: shairport-sync.service: Deactivated successfully. May 28 20:36:57 music systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 20:36:57 music systemd[1]: shairport-sync.service: Consumed 1.824s CPU time. May 28 20:36:57 music systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 20:36:57 music sudo[3988]: pam_unix(sudo:session): session closed for user root May 28 20:36:57 music volumio[1728]: info: Shairport-Sync Started May 28 20:36:57 music mpd[3987]: 2026-05-28T20:36:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 20:36:57 music systemd[1]: Started mpd.service - Music Player Daemon. May 28 20:36:57 music sudo[3955]: pam_unix(sudo:session): session closed for user root May 28 20:36:57 music sudo[3949]: pam_unix(sudo:session): session closed for user root May 28 20:36:57 music volumio[1728]: error: MPD error: The expression evaluated to a falsy value: May 28 20:36:57 music volumio[1728]: assert.ok(self.idling) May 28 20:36:57 music volumio[1728]: error: The expression evaluated to a falsy value: May 28 20:36:57 music volumio[1728]: assert.ok(self.idling) May 28 20:36:57 music volumio[1728]: error: updateQueue error: null May 28 20:37:01 music volumio[1728]: info: VolumeController::SetAlsaVolume100 May 28 20:37:01 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:01 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:01 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:01 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:06 music volumio[1728]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::volumioGetState May 28 20:37:06 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:06 music volumio[1728]: info: Restoring Previous Volume level: 100 false false May 28 20:37:06 music volumio[1728]: info: Volume configurations have been set May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 28 20:37:06 music volumio[1728]: info: Updating Volume Controller Parameters: Device: 5 Name: SXW-MDL7601-INTCLK_A2 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 28 20:37:06 music volumio[1728]: info: Disabling external Volume Control May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 28 20:37:06 music volumio[1728]: info: VolumeController:: Volume=100 Mute =false May 28 20:37:06 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:06 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:06 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:11 music volumio[1728]: info: VolumeController::SetAlsaVolume100 May 28 20:37:11 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:11 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:11 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:11 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:11 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:11 music volumio[1728]: info: VolumeController:: Setting startup Volume 100 May 28 20:37:11 music volumio[1728]: info: VolumeController::SetAlsaVolume100 May 28 20:37:11 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:11 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:11 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:11 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:13 music systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... May 28 20:37:13 music systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. May 28 20:37:13 music systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. May 28 20:37:13 music systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. May 28 20:37:15 music volumio[1728]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::volumioGetState May 28 20:37:15 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 28 20:37:15 music volumio[1728]: info: Restoring Previous Volume level: 100 false false May 28 20:37:15 music volumio[1728]: info: Volume configurations have been set May 28 20:37:15 music sudo[4058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 20:37:15 music sudo[4058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 20:37:15 music sudo[4058]: pam_unix(sudo:session): session closed for user root May 28 20:37:15 music sudo[4060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 20:37:15 music sudo[4060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 28 20:37:15 music volumio[1728]: info: Updating Volume Controller Parameters: Device: 5 Name: SXW-MDL7601-INTCLK_A2 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 28 20:37:15 music volumio[1728]: info: Disabling external Volume Control May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 20:37:15 music volumio[1728]: info: MPD Permissions set May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:15 music systemd[1]: Stopping mpd.service - Music Player Daemon... May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:15 music systemd[1]: mpd.service: Deactivated successfully. May 28 20:37:15 music systemd[1]: Stopped mpd.service - Music Player Daemon. May 28 20:37:15 music systemd[1]: mpd.socket: Deactivated successfully. May 28 20:37:15 music systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 28 20:37:15 music systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 28 20:37:15 music volumio[1728]: info: VolumeController:: Volume=100 Mute =false May 28 20:37:15 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:15 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:15 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:15 music systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 28 20:37:15 music systemd[1]: Starting mpd.service - Music Player Daemon... May 28 20:37:15 music sudo[4081]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 20:37:15 music sudo[4081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 20:37:15 music sudo[4081]: pam_unix(sudo:session): session closed for user root May 28 20:37:15 music mpd[4083]: 2026-05-28T20:37:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 20:37:15 music systemd[1]: Started mpd.service - Music Player Daemon. May 28 20:37:15 music sudo[4060]: pam_unix(sudo:session): session closed for user root May 28 20:37:15 music volumio[1728]: error: updateQueue error: null May 28 20:37:19 music volumio[1728]: info: VolumeController::SetAlsaVolume100 May 28 20:37:19 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:19 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:19 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:19 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:32 music volumio[1728]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::volumioGetState May 28 20:37:32 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:32 music volumio[1728]: info: Restoring Previous Volume level: 100 false false May 28 20:37:32 music volumio[1728]: info: Volume configurations have been set May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 28 20:37:32 music volumio[1728]: info: Updating Volume Controller Parameters: Device: 5 Name: SXW-MDL7601-INTCLK_A2 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 28 20:37:32 music volumio[1728]: info: Disabling external Volume Control May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 28 20:37:32 music volumio[1728]: info: VolumeController:: Volume=100 Mute =false May 28 20:37:32 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:32 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:32 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:35 music volumio[1728]: info: CoreCommandRouter::volumioNext May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::next May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::stop May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::stPlaybackTimer May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::updateTrackBlock May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrackBlock May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:35 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:35 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::serviceStop May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrack 2 May 28 20:37:35 music volumio[1728]: info: CoreCommandRouter::serviceStop May 28 20:37:35 music volumio[1728]: info: ControllerMpd::stop May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:37:35 music volumio[1728]: info: sendMpdCommand stop took 2 milliseconds May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::play index undefined May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::startPlaybackTimer May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::clearAddPlayTracks USB/S15-32G/胡彦斌_灵感-大家差不多.flac May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::updateTrackBlock May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrackBlock May 28 20:37:35 music volumio[1728]: info: sendMpdCommand stop took 1 milliseconds May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::sendMpdCommand clear May 28 20:37:35 music volumio[1728]: info: May 28 20:37:35 music volumio[1728]: ---------------------------- MPD announces system playlist update May 28 20:37:35 music volumio[1728]: info: Ignoring MPD Status Update May 28 20:37:35 music volumio[1728]: info: sendMpdCommand clear took 0 milliseconds May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::sendMpdCommand add "USB/S15-32G/胡彦斌_灵感-大家差不多.flac" May 28 20:37:35 music volumio[1728]: error: updateQueue error: null May 28 20:37:35 music volumio[1728]: info: May 28 20:37:35 music volumio[1728]: ---------------------------- MPD announces system playlist update May 28 20:37:35 music volumio[1728]: info: Ignoring MPD Status Update May 28 20:37:35 music volumio[1728]: info: ------------------------------ 1ms May 28 20:37:35 music volumio[1728]: info: sendMpdCommand add "USB/S15-32G/胡彦斌_灵感-大家差不多.flac" took 1 milliseconds May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::sendMpdCommand play May 28 20:37:35 music volumio[1728]: info: ------------------------------ 0ms May 28 20:37:35 music volumio[1728]: info: sendMpdCommand play took 0 milliseconds May 28 20:37:35 music volumio[1728]: info: May 28 20:37:35 music volumio[1728]: ---------------------------- MPD announces state update: player May 28 20:37:35 music volumio[1728]: info: ControllerMpd::getState May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::sendMpdCommand status May 28 20:37:35 music volumio[1728]: info: May 28 20:37:35 music volumio[1728]: ---------------------------- MPD announces state update: player May 28 20:37:35 music volumio[1728]: info: sendMpdCommand status took 9 milliseconds May 28 20:37:35 music volumio[1728]: info: ControllerMpd::getState May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::sendMpdCommand status May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::parseState May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:37:35 music volumio[1728]: info: sendMpdCommand status took 1 milliseconds May 28 20:37:35 music volumio[1728]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::parseState May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::parseTrackInfo May 28 20:37:35 music volumio[1728]: info: ControllerMpd::pushState May 28 20:37:35 music volumio[1728]: info: CoreCommandRouter::servicePushState May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:35 music volumio[1728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"583 Kbps","isStreaming":false,"title":"大家差不多","artist":"胡彦斌&灵感","album":"我为歌狂 原声歌曲全记录","uri":"USB/S15-32G/胡彦斌_灵感-大家差不多.flac","trackType":"flac"} May 28 20:37:35 music volumio[1728]: verbose: CURRENT POSITION 3 May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::syncState stateService play May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::syncState currentStatus stop May 28 20:37:35 music volumio[1728]: info: ------------------------------ 10ms May 28 20:37:35 music volumio[1728]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:37:35 music volumio[1728]: verbose: ControllerMpd::parseTrackInfo May 28 20:37:35 music volumio[1728]: info: ControllerMpd::pushState May 28 20:37:35 music volumio[1728]: info: CoreCommandRouter::servicePushState May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:35 music volumio[1728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"471 Kbps","isStreaming":false,"title":"大家差不多","artist":"胡彦斌&灵感","album":"我为歌狂 原声歌曲全记录","uri":"USB/S15-32G/胡彦斌_灵感-大家差不多.flac","trackType":"flac"} May 28 20:37:35 music volumio[1728]: verbose: CURRENT POSITION 3 May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::syncState stateService play May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::syncState currentStatus play May 28 20:37:35 music volumio[1728]: info: Received an update from plugin. extracting info from payload May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:35 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:35 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:35 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:35 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:35 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:35 music volumio[1728]: info: ------------------------------ 5ms May 28 20:37:37 music volumio[1728]: info: VolumeController::SetAlsaVolume100 May 28 20:37:37 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:37 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:37 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:37 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:43 music volumio[1728]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::volumioGetState May 28 20:37:43 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions May 28 20:37:43 music volumio[1728]: info: Restoring Previous Volume level: 100 false false May 28 20:37:43 music volumio[1728]: info: Volume configurations have been set May 28 20:37:43 music sudo[4148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 20:37:43 music sudo[4148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 20:37:43 music sudo[4148]: pam_unix(sudo:session): session closed for user root May 28 20:37:43 music sudo[4150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 20:37:43 music sudo[4150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 28 20:37:43 music volumio[1728]: info: Updating Volume Controller Parameters: Device: 5 Name: SXW-MDL7601-INTCLK_A2 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 28 20:37:43 music volumio[1728]: info: Disabling external Volume Control May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::getUIConfigOnPlugin May 28 20:37:43 music systemd[1]: Stopping mpd.service - Music Player Daemon... May 28 20:37:43 music volumio[1728]: info: MPD Permissions set May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 20:37:43 music systemd[1]: mpd.service: Deactivated successfully. May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 28 20:37:43 music systemd[1]: Stopped mpd.service - Music Player Daemon. May 28 20:37:43 music systemd[1]: mpd.socket: Deactivated successfully. May 28 20:37:43 music systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 28 20:37:43 music systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 28 20:37:43 music systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 28 20:37:43 music systemd[1]: Starting mpd.service - Music Player Daemon... May 28 20:37:43 music volumio[1728]: info: VolumeController:: Volume=100 Mute =false May 28 20:37:43 music volumio[1728]: info: CoreStateMachine::pushState May 28 20:37:43 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:37:43 music volumio[1728]: info: CoreCommandRouter::volumioPushState May 28 20:37:43 music sudo[4171]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 20:37:43 music sudo[4171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 20:37:43 music sudo[4171]: pam_unix(sudo:session): session closed for user root May 28 20:37:44 music mpd[4173]: 2026-05-28T20:37:44 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 20:37:44 music systemd[1]: Started mpd.service - Music Player Daemon. May 28 20:37:44 music sudo[4150]: pam_unix(sudo:session): session closed for user root May 28 20:37:44 music volumio[1728]: error: updateQueue error: null May 28 20:37:46 music volumio[1728]: info: CoreCommandRouter::volumioPrevious May 28 20:37:46 music volumio[1728]: info: CoreStateMachine::previous May 28 20:37:46 music volumio[1728]: info: CorePlayQueue::getTrack 3 May 28 20:37:46 music volumio[1728]: info: ControllerMpd::seek May 28 20:37:46 music volumio[1728]: error: [2@0] {seek} Bad song index May 28 20:37:46 music volumio[1728]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 20:37:46 music volumio[1728]: Error: connect ETIMEDOUT 157.240.7.8:443 May 28 20:37:46 music volumio[1728]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { May 28 20:37:46 music volumio[1728]: errno: -110, May 28 20:37:46 music volumio[1728]: code: 'ETIMEDOUT', May 28 20:37:46 music volumio[1728]: syscall: 'connect', May 28 20:37:46 music volumio[1728]: address: '157.240.7.8', May 28 20:37:46 music volumio[1728]: port: 443 May 28 20:37:46 music volumio[1728]: } May 28 20:37:46 music volumio[1728]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 20:37:47 music sudo[4190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-28 20:36' May 28 20:37:47 music sudo[4190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"