-- Logs begin at Wed 2026-08-26 17:07:01 CST, end at Wed 2026-08-26 17:20:05 CST. -- Aug 26 17:19:02 volumio ntpd[955]: 193.182.111.141 local addr 192.168.110.203 -> Aug 26 17:19:26 volumio volumio[3036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:19:26 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 17:19:26 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:19:26 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 17:19:26 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:19:26 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:19:26 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 26 17:19:26 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 17:19:32 volumio volumio[3036]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 26 17:19:32 volumio volumio[3036]: info: Preparing to save Alsa Options, stopping services first Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::volumioGetState Aug 26 17:19:32 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::volumioPause Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::pause Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::stPlaybackTimer Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::servicePause Aug 26 17:19:32 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::servicePause Aug 26 17:19:32 volumio volumio[3036]: info: ControllerMpd::pause Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::sendMpdCommand pause Aug 26 17:19:32 volumio volumio[3036]: info: Saving Audio Output to: {"output_device":{"value":"1,0","label":"Analog Out"}} Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 17:19:32 volumio volumio[3036]: info: Found match in Cards Database: setting mixer for card Analog Out Aug 26 17:19:32 volumio volumio[3036]: info: Setting mixer Master for card Analog Out Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 26 17:19:32 volumio volumio[3036]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 26 17:19:32 volumio volumio[3036]: info: Disabling external Volume Control Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:19:32 volumio volumio[3036]: info: Preparing to generate the ALSA configuration file Aug 26 17:19:32 volumio volumio[3036]: info: Aug 26 17:19:32 volumio volumio[3036]: ---------------------------- MPD announces state update: player Aug 26 17:19:32 volumio volumio[3036]: info: sendMpdCommand pause took 177 milliseconds Aug 26 17:19:32 volumio volumio[3036]: info: ControllerMpd::getState Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:19:32 volumio volumio[3036]: info: Aug 26 17:19:32 volumio volumio[3036]: ---------------------------- MPD announces state update: player Aug 26 17:19:32 volumio volumio[3036]: info: ControllerMpd::getState Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:19:32 volumio volumio[3036]: info: Aug 26 17:19:32 volumio volumio[3036]: ---------------------------- MPD announces state update: player Aug 26 17:19:32 volumio volumio[3036]: info: ControllerMpd::getState Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:19:32 volumio volumio[3036]: info: sendMpdCommand status took 18 milliseconds Aug 26 17:19:32 volumio volumio[3036]: info: sendMpdCommand status took 18 milliseconds Aug 26 17:19:32 volumio volumio[3036]: info: sendMpdCommand status took 17 milliseconds Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::parseState Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::parseState Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::parseState Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:19:32 volumio volumio[3036]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 17:19:32 volumio volumio[3036]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 17:19:32 volumio volumio[3036]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:19:32 volumio volumio[3036]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:19:32 volumio volumio[3036]: info: ControllerMpd::pushState Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::servicePushState Aug 26 17:19:32 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:32 volumio volumio[3036]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":51998,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"910 Kbps","isStreaming":false,"title":"一万个理由(深情版)","artist":"石川小町","album":"一万个理由(深情版)","uri":"USB/未命名/石川小町/E8230F4B2895EC8C.flac","trackType":"flac"} Aug 26 17:19:32 volumio volumio[3036]: verbose: CURRENT POSITION 0 Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::syncState stateService pause Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::syncState currentStatus pause Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::pushState Aug 26 17:19:32 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::volumioPushState Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::stPlaybackTimer Aug 26 17:19:32 volumio volumio[3036]: info: ControllerMpd::pushState Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::servicePushState Aug 26 17:19:32 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:32 volumio volumio[3036]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":51998,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"910 Kbps","isStreaming":false,"title":"一万个理由(深情版)","artist":"石川小町","album":"一万个理由(深情版)","uri":"USB/未命名/石川小町/E8230F4B2895EC8C.flac","trackType":"flac"} Aug 26 17:19:32 volumio volumio[3036]: verbose: CURRENT POSITION 0 Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::syncState stateService pause Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::syncState currentStatus pause Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::pushState Aug 26 17:19:32 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::volumioPushState Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::stPlaybackTimer Aug 26 17:19:32 volumio volumio[3036]: info: ControllerMpd::pushState Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::servicePushState Aug 26 17:19:32 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:32 volumio volumio[3036]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":51998,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"910 Kbps","isStreaming":false,"title":"一万个理由(深情版)","artist":"石川小町","album":"一万个理由(深情版)","uri":"USB/未命名/石川小町/E8230F4B2895EC8C.flac","trackType":"flac"} Aug 26 17:19:32 volumio volumio[3036]: verbose: CURRENT POSITION 0 Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::syncState stateService pause Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::syncState currentStatus pause Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::pushState Aug 26 17:19:32 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::volumioPushState Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::stPlaybackTimer Aug 26 17:19:32 volumio volumio[3036]: info: ------------------------------ 44ms Aug 26 17:19:32 volumio volumio[3036]: info: ------------------------------ 44ms Aug 26 17:19:32 volumio volumio[3036]: info: ------------------------------ 43ms Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 17:19:32 volumio volumio[3036]: info: VolumeController:: Volume=50 Mute =false Aug 26 17:19:32 volumio volumio[3036]: info: CoreStateMachine::pushState Aug 26 17:19:32 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 17:19:32 volumio volumio[3036]: info: CoreCommandRouter::volumioPushState Aug 26 17:19:33 volumio volumio[3036]: info: Asound.conf file written Aug 26 17:19:33 volumio sudo[3564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 17:19:33 volumio sudo[3564]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:19:33 volumio sudo[3564]: pam_unix(sudo:session): session closed for user root Aug 26 17:19:33 volumio volumio[3036]: info: Output device has changed, restarting MPD Aug 26 17:19:33 volumio sudo[3569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 17:19:33 volumio sudo[3569]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:19:33 volumio volumio[3036]: info: Output device has changed, restarting Shairport Sync Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:19:33 volumio sudo[3569]: pam_unix(sudo:session): session closed for user root Aug 26 17:19:33 volumio sudo[3572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 17:19:33 volumio sudo[3572]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:19:33 volumio systemd[1]: Stopping Music Player Daemon... Aug 26 17:19:33 volumio volumio[3036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 17:19:33 volumio volumio[3036]: info: Output device has changed, restarting MPD Aug 26 17:19:33 volumio systemd[1]: mpd.service: Succeeded. Aug 26 17:19:33 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 17:19:33 volumio sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 17:19:33 volumio sudo[3579]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:19:33 volumio volumio[3036]: info: Output device has changed, restarting Shairport Sync Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:19:33 volumio sudo[3579]: pam_unix(sudo:session): session closed for user root Aug 26 17:19:33 volumio systemd[1]: Starting Music Player Daemon... Aug 26 17:19:33 volumio sudo[3582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 17:19:33 volumio sudo[3582]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:19:33 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 26 17:19:33 volumio systemd[1]: mpd.service: Succeeded. Aug 26 17:19:33 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 17:19:33 volumio systemd[1]: Starting Music Player Daemon... Aug 26 17:19:33 volumio volumio[3036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 17:19:33 volumio volumio[3036]: info: MPD Permissions set Aug 26 17:19:33 volumio volumio[3036]: info: MPD Permissions set Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio sudo[3587]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 17:19:33 volumio sudo[3587]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:19:33 volumio sudo[3587]: pam_unix(sudo:session): session closed for user root Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 26 17:19:33 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:19:33 volumio volumio[3036]: info: Starting Shairport Sync Aug 26 17:19:33 volumio volumio[3036]: info: Starting Shairport Sync Aug 26 17:19:33 volumio sudo[3603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 17:19:33 volumio sudo[3603]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:19:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 17:19:33 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 26 17:19:33 volumio sudo[3606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 17:19:33 volumio sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:19:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 17:19:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 17:19:33 volumio sudo[3603]: pam_unix(sudo:session): session closed for user root Aug 26 17:19:33 volumio volumio[3036]: info: Shairport-Sync Started Aug 26 17:19:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 17:19:33 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 26 17:19:33 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 26 17:19:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 17:19:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 17:19:33 volumio sudo[3606]: pam_unix(sudo:session): session closed for user root Aug 26 17:19:33 volumio volumio[3036]: info: Shairport-Sync Started Aug 26 17:19:33 volumio mpd[3599]: Aug 26 17:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 17:19:33 volumio systemd[1]: Started Music Player Daemon. Aug 26 17:19:33 volumio sudo[3572]: pam_unix(sudo:session): session closed for user root Aug 26 17:19:33 volumio sudo[3582]: pam_unix(sudo:session): session closed for user root Aug 26 17:19:33 volumio volumio[3036]: error: MPD error: The expression evaluated to a falsy value: Aug 26 17:19:33 volumio volumio[3036]: assert.ok(self.idling) Aug 26 17:19:33 volumio volumio[3036]: error: The expression evaluated to a falsy value: Aug 26 17:19:33 volumio volumio[3036]: assert.ok(self.idling) Aug 26 17:19:33 volumio volumio[3036]: error: updateQueue error: null Aug 26 17:19:35 volumio ntpd[955]: 5.79.108.34 local addr 192.168.110.203 -> Aug 26 17:19:36 volumio volumio[3036]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 26 17:19:36 volumio volumio[3036]: info: CoreStateMachine::getcurrentVolume Aug 26 17:19:36 volumio volumio[3036]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 17:19:36 volumio volumio[3036]: info: VolumeController:: Volume=50 Mute =false Aug 26 17:19:36 volumio volumio[3036]: info: CoreStateMachine::pushState Aug 26 17:19:36 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:36 volumio volumio[3036]: info: CoreCommandRouter::volumioPushState Aug 26 17:19:36 volumio volumio[3036]: info: CoreStateMachine::updateTrackBlock Aug 26 17:19:36 volumio volumio[3036]: info: CorePlayQueue::getTrackBlock Aug 26 17:19:36 volumio volumio[3036]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 17:19:36 volumio volumio[3036]: info: VolumeController:: Volume=50 Mute =false Aug 26 17:19:36 volumio volumio[3036]: info: CoreStateMachine::pushState Aug 26 17:19:36 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:19:36 volumio volumio[3036]: info: CoreCommandRouter::volumioPushState Aug 26 17:19:59 volumio ntpd[955]: 193.182.111.12 local addr 192.168.110.203 -> Aug 26 17:20:00 volumio volumio[3036]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::volumioGetState Aug 26 17:20:00 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Aug 26 17:20:00 volumio sudo[3667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 17:20:00 volumio sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:20:00 volumio volumio[3036]: info: Restoring Previous Volume level: 50 false false Aug 26 17:20:00 volumio sudo[3667]: pam_unix(sudo:session): session closed for user root Aug 26 17:20:00 volumio volumio[3036]: info: Volume configurations have been set Aug 26 17:20:00 volumio sudo[3670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 17:20:00 volumio sudo[3670]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:20:00 volumio systemd[1]: Stopping Music Player Daemon... Aug 26 17:20:00 volumio systemd[1]: mpd.service: Succeeded. Aug 26 17:20:00 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 26 17:20:00 volumio volumio[3036]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 26 17:20:00 volumio volumio[3036]: info: Disabling external Volume Control Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:20:00 volumio volumio[3036]: info: MPD Permissions set Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:00 volumio systemd[1]: Starting Music Player Daemon... Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:00 volumio sudo[3674]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 17:20:00 volumio sudo[3674]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:20:00 volumio sudo[3674]: pam_unix(sudo:session): session closed for user root Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 17:20:00 volumio volumio[3036]: info: VolumeController:: Volume=50 Mute =false Aug 26 17:20:00 volumio volumio[3036]: info: CoreStateMachine::pushState Aug 26 17:20:00 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:20:00 volumio volumio[3036]: info: CoreCommandRouter::volumioPushState Aug 26 17:20:01 volumio mpd[3685]: Aug 26 17:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 17:20:01 volumio systemd[1]: Started Music Player Daemon. Aug 26 17:20:01 volumio sudo[3670]: pam_unix(sudo:session): session closed for user root Aug 26 17:20:01 volumio volumio[3036]: error: updateQueue error: null Aug 26 17:20:04 volumio volumio[3036]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::volumioGetState Aug 26 17:20:04 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Aug 26 17:20:04 volumio sudo[3730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 17:20:04 volumio sudo[3730]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:20:04 volumio volumio[3036]: info: Restoring Previous Volume level: 50 false false Aug 26 17:20:04 volumio volumio[3036]: info: Volume configurations have been set Aug 26 17:20:04 volumio sudo[3730]: pam_unix(sudo:session): session closed for user root Aug 26 17:20:04 volumio sudo[3733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 17:20:04 volumio sudo[3733]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:20:04 volumio systemd[1]: Stopping Music Player Daemon... Aug 26 17:20:04 volumio systemd[1]: mpd.service: Succeeded. Aug 26 17:20:04 volumio systemd[1]: Stopped Music Player Daemon. Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 26 17:20:04 volumio volumio[3036]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 26 17:20:04 volumio volumio[3036]: info: Disabling external Volume Control Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:20:04 volumio volumio[3036]: info: MPD Permissions set Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:04 volumio systemd[1]: Starting Music Player Daemon... Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:20:04 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:20:05 volumio volumio[3036]: info: VolumeController::SetAlsaVolume50 Aug 26 17:20:05 volumio volumio[3036]: info: CoreStateMachine::pushState Aug 26 17:20:05 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::volumioPushState Aug 26 17:20:05 volumio sudo[3737]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 17:20:05 volumio sudo[3737]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:20:05 volumio sudo[3737]: pam_unix(sudo:session): session closed for user root Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 17:20:05 volumio volumio[3036]: info: VolumeController:: Volume=50 Mute =false Aug 26 17:20:05 volumio volumio[3036]: info: CoreStateMachine::pushState Aug 26 17:20:05 volumio volumio[3036]: info: CorePlayQueue::getTrack 0 Aug 26 17:20:05 volumio volumio[3036]: info: CoreCommandRouter::volumioPushState Aug 26 17:20:05 volumio volumio[3036]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:20:05 volumio volumio[3036]: Error: connect ETIMEDOUT 104.244.43.136:443 Aug 26 17:20:05 volumio volumio[3036]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 26 17:20:05 volumio volumio[3036]: errno: -110, Aug 26 17:20:05 volumio volumio[3036]: code: 'ETIMEDOUT', Aug 26 17:20:05 volumio volumio[3036]: syscall: 'connect', Aug 26 17:20:05 volumio volumio[3036]: address: '104.244.43.136', Aug 26 17:20:05 volumio volumio[3036]: port: 443 Aug 26 17:20:05 volumio volumio[3036]: } Aug 26 17:20:05 volumio volumio[3036]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:20:05 volumio sudo[3762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-26 17:19 Aug 26 17:20:05 volumio sudo[3762]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"