Aug 31 20:14:00 volumio volumio[1219]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Aug 31 20:14:00 volumio sudo[6203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 20:14:00 volumio sudo[6203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:00 volumio sudo[6203]: pam_unix(sudo:session): session closed for user root Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:00 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:00 volumio sudo[6205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 20:14:00 volumio sudo[6205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:14:00 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:14:00 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:00 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:00 volumio volumio[1219]: info: MPD Permissions set Aug 31 20:14:00 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 31 20:14:00 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 20:14:00 volumio systemd[1]: mpd.service: Consumed 37.076s CPU time. Aug 31 20:14:00 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 31 20:14:00 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 20:14:00 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 20:14:00 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 20:14:00 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 20:14:01 volumio sudo[6214]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 20:14:01 volumio sudo[6214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 20:14:01 volumio sudo[6214]: pam_unix(sudo:session): session closed for user root Aug 31 20:14:03 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 20:14:03 volumio mpd[6216]: 2026-08-31T20:14:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 20:14:03 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 20:14:03 volumio sudo[6205]: pam_unix(sudo:session): session closed for user root Aug 31 20:14:03 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:14:05 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::volumioNext Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::next Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::stop Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::stPlaybackTimer Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::updateTrackBlock Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrackBlock Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 2 Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::serviceStop Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 2 Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::serviceStop Aug 31 20:14:06 volumio volumio[1219]: info: ControllerMpd::stop Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:14:06 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:06.523+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:14:06 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:06.524+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/03-In a Mellotone_ In A Mellow Tone.dsf" title="In a Mellotone: In A Mellow Tone" Aug 31 20:14:06 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:06 volumio volumio[1219]: info: sendMpdCommand stop took 18 milliseconds Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::play index undefined Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 4 Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::startPlaybackTimer Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 4 Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::clearAddPlayTracks USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/05-High Life (Take 2).dsf Aug 31 20:14:06 volumio volumio[1219]: info: Setting Volume to 100 automatically for DSD Aug 31 20:14:06 volumio volumio[1219]: info: VolumeController::SetAlsaVolume100 Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 4 Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::updateTrackBlock Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrackBlock Aug 31 20:14:06 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:06.571+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:14:06 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:06.571+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/05-High Life (Take 2).dsf" title="High Life (Take 2)" Aug 31 20:14:06 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:06 volumio volumio[1219]: info: sendMpdCommand stop took 25 milliseconds Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 20:14:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 31 20:14:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:06 volumio volumio[1219]: info: Aug 31 20:14:06 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:06 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:06 volumio volumio[1219]: info: sendMpdCommand clear took 4 milliseconds Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/05-High Life (Take 2).dsf" Aug 31 20:14:06 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:14:06 volumio volumio[1219]: info: Aug 31 20:14:06 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:06 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:06 volumio volumio[1219]: info: ------------------------------ 11ms Aug 31 20:14:06 volumio volumio[1219]: info: sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/05-High Life (Take 2).dsf" took 10 milliseconds Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand play Aug 31 20:14:06 volumio volumio[1219]: info: ------------------------------ 3ms Aug 31 20:14:06 volumio volumio[1219]: info: sendMpdCommand play took 8 milliseconds Aug 31 20:14:06 volumio volumio[1219]: info: Aug 31 20:14:06 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:14:06 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:14:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Aug 31 20:14:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:06 volumio volumio[1219]: info: Aug 31 20:14:06 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:14:06 volumio volumio[1219]: info: sendMpdCommand status took 72 milliseconds Aug 31 20:14:06 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:14:06 volumio volumio[1219]: info: sendMpdCommand status took 2 milliseconds Aug 31 20:14:06 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:14:06 volumio volumio[1219]: info: ControllerMpd::pushState Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 4 Aug 31 20:14:06 volumio volumio[1219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":348,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"High Life (Take 2)","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/05-High Life (Take 2).dsf","trackType":"dsf"} Aug 31 20:14:06 volumio volumio[1219]: verbose: CURRENT POSITION 4 Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::syncState stateService play Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:14:06 volumio volumio[1219]: info: ------------------------------ 79ms Aug 31 20:14:06 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 20:14:06 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:14:06 volumio volumio[1219]: info: ControllerMpd::pushState Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 4 Aug 31 20:14:06 volumio volumio[1219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":348,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"High Life (Take 2)","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/05-High Life (Take 2).dsf","trackType":"dsf"} Aug 31 20:14:06 volumio volumio[1219]: verbose: CURRENT POSITION 4 Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::syncState stateService play Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:14:06 volumio volumio[1219]: info: Received an update from plugin. extracting info from payload Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 4 Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:06 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:06 volumio volumio[1219]: info: CorePlayQueue::getTrack 4 Aug 31 20:14:06 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:06 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:06.977+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_PLAYING positionMs=11 volume=60 Aug 31 20:14:06 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:06.980+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_PLAYING positionMs=11 volume=60 Aug 31 20:14:06 volumio volumio[1219]: info: ------------------------------ 29ms Aug 31 20:14:06 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:06.987+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/05-High Life (Take 2).dsf" title="High Life (Take 2)" Aug 31 20:14:06 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:06.992+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/05-High Life (Take 2).dsf" title="High Life (Take 2)" Aug 31 20:14:07 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:07 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:08 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:12 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:13 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:17 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:17 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:20 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:23 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:23 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:24 volumio volumio[1219]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:24 volumio sudo[6299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 20:14:24 volumio sudo[6299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:14:24 volumio sudo[6299]: pam_unix(sudo:session): session closed for user root Aug 31 20:14:24 volumio sudo[6301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 20:14:24 volumio sudo[6301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:14:24 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:24 volumio volumio[1219]: info: MPD Permissions set Aug 31 20:14:24 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 20:14:24 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:25 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 31 20:14:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 20:14:25 volumio systemd[1]: mpd.service: Consumed 23.857s CPU time. Aug 31 20:14:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 31 20:14:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 20:14:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 20:14:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 20:14:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 20:14:25 volumio sudo[6310]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 20:14:25 volumio sudo[6310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 20:14:25 volumio sudo[6310]: pam_unix(sudo:session): session closed for user root Aug 31 20:14:28 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 20:14:28 volumio mpd[6326]: 2026-08-31T20:14:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 20:14:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 20:14:28 volumio sudo[6301]: pam_unix(sudo:session): session closed for user root Aug 31 20:14:28 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:14:30 volumio volumio[1219]: info: CoreCommandRouter::volumioNext Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::next Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::stop Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::stPlaybackTimer Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::updateTrackBlock Aug 31 20:14:30 volumio volumio[1219]: info: CorePlayQueue::getTrackBlock Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 4 Aug 31 20:14:30 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:14:30 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::serviceStop Aug 31 20:14:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 4 Aug 31 20:14:30 volumio volumio[1219]: info: CoreCommandRouter::serviceStop Aug 31 20:14:30 volumio volumio[1219]: info: ControllerMpd::stop Aug 31 20:14:30 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:14:30 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:30.941+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:14:30 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:30.941+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/05-High Life (Take 2).dsf" title="High Life (Take 2)" Aug 31 20:14:30 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:30 volumio volumio[1219]: info: sendMpdCommand stop took 6 milliseconds Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::play index undefined Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:14:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::startPlaybackTimer Aug 31 20:14:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:30 volumio volumio[1219]: verbose: ControllerMpd::clearAddPlayTracks USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf Aug 31 20:14:30 volumio volumio[1219]: info: Setting Volume to 100 automatically for DSD Aug 31 20:14:30 volumio volumio[1219]: info: VolumeController::SetAlsaVolume100 Aug 31 20:14:30 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:30 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:30 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:14:30 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:30 volumio volumio[1219]: info: CoreStateMachine::updateTrackBlock Aug 31 20:14:30 volumio volumio[1219]: info: CorePlayQueue::getTrackBlock Aug 31 20:14:30 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:30.977+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:14:30 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:30.978+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf" title="The Big Show: Poor Butterfly" Aug 31 20:14:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 31 20:14:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:30 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:30 volumio volumio[1219]: info: sendMpdCommand stop took 26 milliseconds Aug 31 20:14:30 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 20:14:30 volumio volumio[1219]: info: Aug 31 20:14:30 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:30 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:30 volumio volumio[1219]: info: sendMpdCommand clear took 3 milliseconds Aug 31 20:14:30 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf" Aug 31 20:14:30 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:14:30 volumio volumio[1219]: info: Aug 31 20:14:30 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:30 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:30 volumio volumio[1219]: info: ------------------------------ 5ms Aug 31 20:14:30 volumio volumio[1219]: info: sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf" took 4 milliseconds Aug 31 20:14:30 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand play Aug 31 20:14:30 volumio volumio[1219]: info: ------------------------------ 6ms Aug 31 20:14:30 volumio volumio[1219]: info: sendMpdCommand play took 6 milliseconds Aug 31 20:14:32 volumio volumio[1219]: info: CoreCommandRouter::volumioPlay Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::play index undefined Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:14:32 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::startPlaybackTimer Aug 31 20:14:32 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::clearAddPlayTracks USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf Aug 31 20:14:32 volumio volumio[1219]: info: Setting Volume to 100 automatically for DSD Aug 31 20:14:32 volumio volumio[1219]: info: VolumeController::SetAlsaVolume100 Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:32 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:32 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:32 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:32.167+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:14:32 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:32.168+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf" title="The Big Show: Poor Butterfly" Aug 31 20:14:32 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:32 volumio volumio[1219]: info: Aug 31 20:14:32 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand stop took 11 milliseconds Aug 31 20:14:32 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 20:14:32 volumio volumio[1219]: info: Aug 31 20:14:32 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:32 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand status took 3 milliseconds Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand clear took 2 milliseconds Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf" Aug 31 20:14:32 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:14:32 volumio volumio[1219]: info: Aug 31 20:14:32 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:32 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:32 volumio volumio[1219]: info: ------------------------------ 3ms Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf" took 3 milliseconds Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand play Aug 31 20:14:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 31 20:14:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:32 volumio volumio[1219]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 20:14:32 volumio volumio[1219]: info: ------------------------------ 8ms Aug 31 20:14:32 volumio volumio[1219]: info: ------------------------------ 3ms Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand play took 4 milliseconds Aug 31 20:14:32 volumio volumio[1219]: info: Aug 31 20:14:32 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:14:32 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:14:32 volumio volumio[1219]: info: Aug 31 20:14:32 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand status took 22 milliseconds Aug 31 20:14:32 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:14:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Aug 31 20:14:32 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand status took 6 milliseconds Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:14:32 volumio volumio[1219]: info: ControllerMpd::pushState Aug 31 20:14:32 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Aug 31 20:14:32 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:32 volumio volumio[1219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Big Show: Poor Butterfly","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf","trackType":"dsf"} Aug 31 20:14:32 volumio volumio[1219]: verbose: CURRENT POSITION 5 Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::syncState stateService play Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:14:32 volumio volumio[1219]: info: ------------------------------ 34ms Aug 31 20:14:32 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 20:14:32 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:14:32 volumio volumio[1219]: info: ControllerMpd::pushState Aug 31 20:14:32 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Aug 31 20:14:32 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:32 volumio volumio[1219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":236,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Big Show: Poor Butterfly","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf","trackType":"dsf"} Aug 31 20:14:32 volumio volumio[1219]: verbose: CURRENT POSITION 5 Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::syncState stateService play Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:14:32 volumio volumio[1219]: info: Received an update from plugin. extracting info from payload Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:32 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:32 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:14:32 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:32 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:32 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:32 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:32 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:32.632+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_PLAYING positionMs=0 volume=60 Aug 31 20:14:32 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:32.633+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf" title="The Big Show: Poor Butterfly" Aug 31 20:14:32 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:32.637+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_PLAYING positionMs=0 volume=60 Aug 31 20:14:32 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:32.637+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf" title="The Big Show: Poor Butterfly" Aug 31 20:14:32 volumio volumio[1219]: info: ------------------------------ 39ms Aug 31 20:14:32 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:32 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:34 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 20:14:38 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 20:14:47 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 20:14:48 volumio volumio[1219]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:48 volumio sudo[6412]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 20:14:48 volumio sudo[6412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:48 volumio sudo[6412]: pam_unix(sudo:session): session closed for user root Aug 31 20:14:48 volumio sudo[6414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 20:14:48 volumio sudo[6414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:14:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:14:48 volumio volumio[1219]: info: MPD Permissions set Aug 31 20:14:48 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 20:14:48 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:48 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 31 20:14:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 20:14:48 volumio systemd[1]: mpd.service: Consumed 12.995s CPU time. Aug 31 20:14:48 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 31 20:14:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 20:14:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 20:14:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 20:14:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 20:14:49 volumio sudo[6423]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 20:14:49 volumio sudo[6423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 20:14:49 volumio sudo[6423]: pam_unix(sudo:session): session closed for user root Aug 31 20:14:51 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 20:14:52 volumio mpd[6425]: 2026-08-31T20:14:51 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 20:14:52 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 20:14:52 volumio sudo[6414]: pam_unix(sudo:session): session closed for user root Aug 31 20:14:52 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:14:54 volumio volumio[1219]: info: CoreCommandRouter::volumioNext Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::next Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::stop Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::stPlaybackTimer Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::updateTrackBlock Aug 31 20:14:54 volumio volumio[1219]: info: CorePlayQueue::getTrackBlock Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:54 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:54 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:14:54 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::serviceStop Aug 31 20:14:54 volumio volumio[1219]: info: CorePlayQueue::getTrack 5 Aug 31 20:14:54 volumio volumio[1219]: info: CoreCommandRouter::serviceStop Aug 31 20:14:54 volumio volumio[1219]: info: ControllerMpd::stop Aug 31 20:14:54 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:14:54 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:54.976+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:14:54 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:54.976+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/06-The Big Show_ Poor Butterfly.dsf" title="The Big Show: Poor Butterfly" Aug 31 20:14:54 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:54 volumio volumio[1219]: info: sendMpdCommand stop took 6 milliseconds Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::play index undefined Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:14:54 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::startPlaybackTimer Aug 31 20:14:54 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:54 volumio volumio[1219]: verbose: ControllerMpd::clearAddPlayTracks USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf Aug 31 20:14:54 volumio volumio[1219]: info: Setting Volume to 100 automatically for DSD Aug 31 20:14:54 volumio volumio[1219]: info: VolumeController::SetAlsaVolume100 Aug 31 20:14:54 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:54 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:54 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:14:54 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:54 volumio volumio[1219]: info: CoreStateMachine::updateTrackBlock Aug 31 20:14:54 volumio volumio[1219]: info: CorePlayQueue::getTrackBlock Aug 31 20:14:54 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:54.999+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:14:55 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:55.000+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf" title="Exactly Like You" Aug 31 20:14:55 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:55 volumio volumio[1219]: info: sendMpdCommand stop took 12 milliseconds Aug 31 20:14:55 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 20:14:55 volumio volumio[1219]: info: Aug 31 20:14:55 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:55 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:55 volumio volumio[1219]: info: sendMpdCommand clear took 1 milliseconds Aug 31 20:14:55 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf" Aug 31 20:14:55 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:14:55 volumio volumio[1219]: info: Aug 31 20:14:55 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:55 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:55 volumio volumio[1219]: info: ------------------------------ 3ms Aug 31 20:14:55 volumio volumio[1219]: info: sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf" took 2 milliseconds Aug 31 20:14:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 31 20:14:55 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:55 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand play Aug 31 20:14:55 volumio volumio[1219]: info: ------------------------------ 2ms Aug 31 20:14:55 volumio volumio[1219]: info: sendMpdCommand play took 5 milliseconds Aug 31 20:14:56 volumio volumio[1219]: info: CoreCommandRouter::volumioPlay Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::play index undefined Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:14:56 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::startPlaybackTimer Aug 31 20:14:56 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::clearAddPlayTracks USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf Aug 31 20:14:56 volumio volumio[1219]: info: Setting Volume to 100 automatically for DSD Aug 31 20:14:56 volumio volumio[1219]: info: VolumeController::SetAlsaVolume100 Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:56 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:56 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:56 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:56.022+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:14:56 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:56.024+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf" title="Exactly Like You" Aug 31 20:14:56 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:56 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 31 20:14:56 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:56 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:56 volumio volumio[1219]: info: Aug 31 20:14:56 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand stop took 24 milliseconds Aug 31 20:14:56 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 20:14:56 volumio volumio[1219]: info: Aug 31 20:14:56 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:56 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand status took 3 milliseconds Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand clear took 3 milliseconds Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf" Aug 31 20:14:56 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:14:56 volumio volumio[1219]: info: Aug 31 20:14:56 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:14:56 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:14:56 volumio volumio[1219]: info: ------------------------------ 8ms Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf" took 5 milliseconds Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand play Aug 31 20:14:56 volumio volumio[1219]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 20:14:56 volumio volumio[1219]: info: ------------------------------ 15ms Aug 31 20:14:56 volumio volumio[1219]: info: ------------------------------ 6ms Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand play took 3 milliseconds Aug 31 20:14:56 volumio volumio[1219]: info: Aug 31 20:14:56 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:14:56 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:14:56 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Aug 31 20:14:56 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:14:56 volumio volumio[1219]: info: Aug 31 20:14:56 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand status took 28 milliseconds Aug 31 20:14:56 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand status took 3 milliseconds Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:14:56 volumio volumio[1219]: info: ControllerMpd::pushState Aug 31 20:14:56 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Aug 31 20:14:56 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:56 volumio volumio[1219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":359,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exactly Like You","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf","trackType":"dsf"} Aug 31 20:14:56 volumio volumio[1219]: verbose: CURRENT POSITION 6 Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::syncState stateService play Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:14:56 volumio volumio[1219]: info: ------------------------------ 38ms Aug 31 20:14:56 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 20:14:56 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:14:56 volumio volumio[1219]: info: ControllerMpd::pushState Aug 31 20:14:56 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Aug 31 20:14:56 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:56 volumio volumio[1219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":359,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Exactly Like You","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf","trackType":"dsf"} Aug 31 20:14:56 volumio volumio[1219]: verbose: CURRENT POSITION 6 Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::syncState stateService play Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:14:56 volumio volumio[1219]: info: Received an update from plugin. extracting info from payload Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:56 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:56 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:14:56 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:56 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:14:56 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:14:56 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:14:56 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:56.305+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_PLAYING positionMs=0 volume=60 Aug 31 20:14:56 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:56.308+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf" title="Exactly Like You" Aug 31 20:14:56 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:56.308+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_PLAYING positionMs=0 volume=60 Aug 31 20:14:56 volumio volumio5-onboarding[1642]: time=2026-08-31T20:14:56.309+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf" title="Exactly Like You" Aug 31 20:14:56 volumio volumio[1219]: info: ------------------------------ 29ms Aug 31 20:14:56 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:56 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:14:57 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 20:15:01 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 20:15:11 volumio volumio[1219]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:15:11 volumio sudo[6509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 20:15:11 volumio sudo[6509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:15:11 volumio sudo[6509]: pam_unix(sudo:session): session closed for user root Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:15:11 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 20:15:11 volumio sudo[6511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 20:15:11 volumio volumio[1219]: info: MPD Permissions set Aug 31 20:15:11 volumio sudo[6511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 20:15:11 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 20:15:11 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:15:11 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 31 20:15:11 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 20:15:11 volumio systemd[1]: mpd.service: Consumed 11.029s CPU time. Aug 31 20:15:11 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 31 20:15:11 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 20:15:11 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 20:15:11 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 20:15:11 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 20:15:12 volumio sudo[6520]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 20:15:12 volumio sudo[6520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 20:15:12 volumio sudo[6520]: pam_unix(sudo:session): session closed for user root Aug 31 20:15:14 volumio mpd[6522]: 2026-08-31T20:15:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 20:15:14 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 20:15:14 volumio sudo[6511]: pam_unix(sudo:session): session closed for user root Aug 31 20:15:14 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:15:18 volumio volumio[1219]: info: CoreCommandRouter::volumioNext Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::next Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::stop Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::stPlaybackTimer Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::updateTrackBlock Aug 31 20:15:18 volumio volumio[1219]: info: CorePlayQueue::getTrackBlock Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:15:18 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:15:18 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:15:18 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::serviceStop Aug 31 20:15:18 volumio volumio[1219]: info: CorePlayQueue::getTrack 6 Aug 31 20:15:18 volumio volumio[1219]: info: CoreCommandRouter::serviceStop Aug 31 20:15:18 volumio volumio[1219]: info: ControllerMpd::stop Aug 31 20:15:18 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:15:18 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:15:18 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:18.096+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:15:18 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:18.096+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/07-Exactly Like You.dsf" title="Exactly Like You" Aug 31 20:15:18 volumio volumio[1219]: info: sendMpdCommand stop took 6 milliseconds Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::play index undefined Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:15:18 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::startPlaybackTimer Aug 31 20:15:18 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:18 volumio volumio[1219]: verbose: ControllerMpd::clearAddPlayTracks USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf Aug 31 20:15:18 volumio volumio[1219]: info: Setting Volume to 100 automatically for DSD Aug 31 20:15:18 volumio volumio[1219]: info: VolumeController::SetAlsaVolume100 Aug 31 20:15:18 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:15:18 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:18 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:15:18 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:15:18 volumio volumio[1219]: info: CoreStateMachine::updateTrackBlock Aug 31 20:15:18 volumio volumio[1219]: info: CorePlayQueue::getTrackBlock Aug 31 20:15:18 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:18.118+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:15:18 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:18.119+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf" title="Gubben och Kallingen" Aug 31 20:15:18 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:15:18 volumio volumio[1219]: info: sendMpdCommand stop took 10 milliseconds Aug 31 20:15:18 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 20:15:18 volumio volumio[1219]: info: Aug 31 20:15:18 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:15:18 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:15:18 volumio volumio[1219]: info: sendMpdCommand clear took 5 milliseconds Aug 31 20:15:18 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf" Aug 31 20:15:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:15:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 31 20:15:18 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:15:18 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:15:18 volumio volumio[1219]: info: Aug 31 20:15:18 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:15:18 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:15:18 volumio volumio[1219]: info: ------------------------------ 3ms Aug 31 20:15:18 volumio volumio[1219]: info: sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf" took 3 milliseconds Aug 31 20:15:18 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand play Aug 31 20:15:18 volumio volumio[1219]: info: ------------------------------ 4ms Aug 31 20:15:18 volumio volumio[1219]: info: sendMpdCommand play took 7 milliseconds Aug 31 20:15:19 volumio volumio[1219]: info: CoreCommandRouter::volumioPlay Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::play index undefined Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:15:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::startPlaybackTimer Aug 31 20:15:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::clearAddPlayTracks USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf Aug 31 20:15:19 volumio volumio[1219]: info: Setting Volume to 100 automatically for DSD Aug 31 20:15:19 volumio volumio[1219]: info: VolumeController::SetAlsaVolume100 Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:15:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:19 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:15:19 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:19.129+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:15:19 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:19.130+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf" title="Gubben och Kallingen" Aug 31 20:15:19 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:15:19 volumio volumio[1219]: info: Aug 31 20:15:19 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand stop took 11 milliseconds Aug 31 20:15:19 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 20:15:19 volumio volumio[1219]: info: Aug 31 20:15:19 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:15:19 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand status took 2 milliseconds Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand clear took 2 milliseconds Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf" Aug 31 20:15:19 volumio volumio[1219]: error: updateQueue error: null Aug 31 20:15:19 volumio volumio[1219]: info: Aug 31 20:15:19 volumio volumio[1219]: ---------------------------- MPD announces system playlist update Aug 31 20:15:19 volumio volumio[1219]: info: Ignoring MPD Status Update Aug 31 20:15:19 volumio volumio[1219]: info: ------------------------------ 6ms Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand add "USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf" took 5 milliseconds Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand play Aug 31 20:15:19 volumio volumio[1219]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 20:15:19 volumio volumio[1219]: info: ------------------------------ 10ms Aug 31 20:15:19 volumio volumio[1219]: info: ------------------------------ 4ms Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand play took 2 milliseconds Aug 31 20:15:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:15:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 31 20:15:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:15:19 volumio volumio[1219]: info: Aug 31 20:15:19 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:15:19 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:15:19 volumio volumio[1219]: info: Aug 31 20:15:19 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand status took 26 milliseconds Aug 31 20:15:19 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand status took 2 milliseconds Aug 31 20:15:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000010] -121 Aug 31 20:15:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::parseState Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:15:19 volumio volumio[1219]: info: ControllerMpd::pushState Aug 31 20:15:19 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Aug 31 20:15:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:19 volumio volumio[1219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":445,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gubben och Kallingen","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf","trackType":"dsf"} Aug 31 20:15:19 volumio volumio[1219]: verbose: CURRENT POSITION 1 Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::syncState stateService play Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus stop Aug 31 20:15:19 volumio volumio[1219]: info: ------------------------------ 32ms Aug 31 20:15:19 volumio volumio[1219]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 20:15:19 volumio volumio[1219]: verbose: ControllerMpd::parseTrackInfo Aug 31 20:15:19 volumio volumio[1219]: info: ControllerMpd::pushState Aug 31 20:15:19 volumio volumio[1219]: info: CoreCommandRouter::servicePushState Aug 31 20:15:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:19 volumio volumio[1219]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":445,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Gubben och Kallingen","artist":"Arne Domnerus","album":"Jazz At The Pawnshop 2","uri":"USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf","trackType":"dsf"} Aug 31 20:15:19 volumio volumio[1219]: verbose: CURRENT POSITION 1 Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::syncState stateService play Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::syncState currentStatus play Aug 31 20:15:19 volumio volumio[1219]: info: Received an update from plugin. extracting info from payload Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:15:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:19 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:15:19 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:15:19 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:15:19 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:19 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:15:19 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:19.626+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_PLAYING positionMs=0 volume=60 Aug 31 20:15:19 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:19.627+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf" title="Gubben och Kallingen" Aug 31 20:15:19 volumio volumio[1219]: info: ------------------------------ 23ms Aug 31 20:15:19 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:19.631+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_PLAYING positionMs=0 volume=60 Aug 31 20:15:19 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:19.635+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf" title="Gubben och Kallingen" Aug 31 20:15:19 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:15:19 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:15:20 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 20:15:26 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 20:15:34 volumio volumio[1219]: info: Getting Alsa Cards List without I2S DAC Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:15:34 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:15:34 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 31 20:15:34 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 20:15:34 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 20:15:39 volumio volumio[1219]: info: CoreCommandRouter::volumioGetQueue Aug 31 20:15:39 volumio volumio[1219]: info: CoreStateMachine::getQueue Aug 31 20:15:39 volumio volumio[1219]: info: CorePlayQueue::getQueue Aug 31 20:15:43 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 20:15:47 volumio kernel: hwmon hwmon1: Voltage normalised Aug 31 20:15:48 volumio volumio[1219]: info: Preload queue cleared Aug 31 20:15:48 volumio volumio[1219]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 20:15:48 volumio volumio[1219]: info: CoreStateMachine::ClearQueue Aug 31 20:15:48 volumio volumio[1219]: info: CoreStateMachine::stop Aug 31 20:15:48 volumio volumio[1219]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 20:15:48 volumio volumio[1219]: info: CoreStateMachine::stPlaybackTimer Aug 31 20:15:48 volumio volumio[1219]: info: CoreStateMachine::updateTrackBlock Aug 31 20:15:48 volumio volumio[1219]: info: CorePlayQueue::getTrackBlock Aug 31 20:15:48 volumio volumio[1219]: info: CoreStateMachine::pushState Aug 31 20:15:48 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:48 volumio volumio[1219]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 20:15:48 volumio volumio[1219]: info: CoreCommandRouter::volumioPushState Aug 31 20:15:48 volumio volumio[1219]: info: CoreStateMachine::serviceStop Aug 31 20:15:48 volumio volumio[1219]: info: CorePlayQueue::getTrack 1 Aug 31 20:15:48 volumio volumio[1219]: info: CoreCommandRouter::serviceStop Aug 31 20:15:48 volumio volumio[1219]: info: ControllerMpd::stop Aug 31 20:15:48 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 20:15:48 volumio volumio[1219]: info: CorePlayQueue::clearPlayQueue Aug 31 20:15:48 volumio volumio[1219]: info: CorePlayQueue::saveQueue Aug 31 20:15:48 volumio volumio[1219]: info: CoreCommandRouter::volumioPushQueue Aug 31 20:15:48 volumio volumio[1219]: info: CoreStateMachine::addQueueItems Aug 31 20:15:48 volumio volumio[1219]: info: CorePlayQueue::addQueueItems Aug 31 20:15:48 volumio volumio[1219]: info: Preload queue cleared Aug 31 20:15:48 volumio volumio[1219]: info: Adding Item to queue: music-library/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/(Smooth Jazz) George Benson - Inspiration, A Tribute To Nat King Cole Aug 31 20:15:48 volumio volumio[1219]: info: Exploding uri music-library/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/(Smooth Jazz) George Benson - Inspiration, A Tribute To Nat King Cole in service mpd Aug 31 20:15:48 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:48.690+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" state=STATUS_STOPPED positionMs=0 volume=60 Aug 31 20:15:48 volumio volumio5-onboarding[1642]: time=2026-08-31T20:15:48.690+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.184:48706 @ 0x288a6c0" id="mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/Arne_Domnerus_Jazz_At_The_Pawnshop2/02-Gubben och Kallingen.dsf" title="Gubben och Kallingen" Aug 31 20:15:48 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 31 20:15:48 volumio volumio[1219]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Aug 31 20:15:48 volumio volumio[1219]: info: Aug 31 20:15:48 volumio volumio[1219]: ---------------------------- MPD announces state update: player Aug 31 20:15:48 volumio volumio[1219]: info: sendMpdCommand stop took 76 milliseconds Aug 31 20:15:48 volumio volumio[1219]: info: ControllerMpd::getState Aug 31 20:15:48 volumio volumio[1219]: verbose: ControllerMpd::sendMpdCommand status Aug 31 20:15:48 volumio volumio[1219]: info: ALBUMART /albumart?cacheid=570&web=/Inspiration%2C%20A%20Tribute%20To%20Nat%20King%20Cole/extralarge&path=%2Fmnt%2FUSB%2F01D9B32F8A1706E0%2F1-(Vocal-Jazz)%2F(Smooth%20Jazz)%20George%20Benson%20-%20Inspiration%2C%20A%20Tribute%20To%20Nat%20King%20Cole%2Fgeorge_benson-inspiration_0888072345188.cue&metadata=false Aug 31 20:15:48 volumio volumio[1219]: info: URI /mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/(Smooth Jazz) George Benson - Inspiration, A Tribute To Nat King Cole/george_benson-inspiration_0888072345188.cue Aug 31 20:15:48 volumio volumio[1219]: info: ALBUMART /albumart?cacheid=570&web=/Inspiration%2C%20A%20Tribute%20To%20Nat%20King%20Cole/extralarge&path=%2Fmnt%2FUSB%2F01D9B32F8A1706E0%2F1-(Vocal-Jazz)%2F(Smooth%20Jazz)%20George%20Benson%20-%20Inspiration%2C%20A%20Tribute%20To%20Nat%20King%20Cole%2Fgeorge_benson-inspiration_0888072345188.cue&metadata=false Aug 31 20:15:48 volumio volumio[1219]: info: URI /mnt/USB/01D9B32F8A1706E0/1-(Vocal-Jazz)/(Smooth Jazz) George Benson - Inspiration, A Tribute To Nat King Cole/george_benson-inspiration_0888072345188.cue Aug 31 20:15:48 volumio volumio[1219]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 20:15:48 volumio volumio[1219]: Error: Unable to resolve or reject the same promise twice Aug 31 20:15:48 volumio volumio[1219]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 31 20:15:48 volumio volumio[1219]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 31 20:15:48 volumio volumio[1219]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 31 20:15:48 volumio volumio[1219]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 31 20:15:48 volumio volumio[1219]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 31 20:15:48 volumio volumio[1219]: at Socket.emit (node:events:514:28) Aug 31 20:15:48 volumio volumio[1219]: at addChunk (node:internal/streams/readable:343:12) Aug 31 20:15:48 volumio volumio[1219]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 31 20:15:48 volumio volumio[1219]: at Readable.push (node:internal/streams/readable:253:10) Aug 31 20:15:48 volumio volumio[1219]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 31 20:15:48 volumio volumio[1219]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 20:15:49 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 31 20:15:50 volumio sudo[6693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 20:14' Aug 31 20:15:50 volumio sudo[6693]: 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"