Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 25 17:13:08 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 25 17:13:13 volumio volumio[1411]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 25 17:13:13 volumio volumio[1411]: info: Preparing to save Alsa Options, stopping services first Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::volumioGetState Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::volumioPause Jan 25 17:13:13 volumio volumio[1411]: info: CoreStateMachine::pause Jan 25 17:13:13 volumio volumio[1411]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 17:13:13 volumio volumio[1411]: info: Found match in Cards Database: setting mixer for card HDMI 0 Out Jan 25 17:13:13 volumio volumio[1411]: info: Device 0 does not have any Mixer Control Available Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 25 17:13:13 volumio volumio[1411]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 25 17:13:13 volumio volumio[1411]: info: Disabling external Volume Control Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 17:13:13 volumio volumio[1411]: info: CoreStateMachine::pushState Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::volumioPushState Jan 25 17:13:13 volumio volumio[1411]: info: Preparing to generate the ALSA configuration file Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::volumioGetQueue Jan 25 17:13:13 volumio volumio[1411]: info: CoreStateMachine::getQueue Jan 25 17:13:13 volumio volumio[1411]: info: CorePlayQueue::getQueue Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 25 17:13:13 volumio volumio[1411]: info: Asound.conf file written Jan 25 17:13:13 volumio sudo[4268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 25 17:13:13 volumio sudo[4268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:13 volumio sudo[4268]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:13 volumio volumio[1411]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 25 17:13:13 volumio volumio[1411]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 25 17:13:13 volumio volumio[1411]: info: Output device has changed, restarting MPD Jan 25 17:13:13 volumio volumio[1411]: info: Output device has changed, restarting Shairport Sync Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 17:13:13 volumio sudo[4274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 17:13:13 volumio sudo[4274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:13 volumio sudo[4277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 17:13:13 volumio sudo[4274]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:13 volumio sudo[4277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:13 volumio volumio[1411]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 17:13:13 volumio volumio[1411]: info: Output device has changed, restarting MPD Jan 25 17:13:13 volumio volumio[1411]: info: Output device has changed, restarting Shairport Sync Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 17:13:13 volumio sudo[4286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 17:13:13 volumio sudo[4286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:13 volumio sudo[4284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 17:13:13 volumio sudo[4284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:13 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 25 17:13:13 volumio sudo[4284]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:13 volumio volumio[1411]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 17:13:13 volumio volumio[1411]: info: MPD Permissions set Jan 25 17:13:13 volumio volumio[1411]: info: MPD Permissions set Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 25 17:13:13 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 25 17:13:13 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 25 17:13:13 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:13 volumio volumio[1411]: info: Starting Shairport Sync Jan 25 17:13:13 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 17:13:13 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 17:13:13 volumio volumio[1411]: info: Starting Shairport Sync Jan 25 17:13:13 volumio sudo[4306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 17:13:13 volumio sudo[4306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:13 volumio sudo[4308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 17:13:13 volumio sudo[4308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 25 17:13:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 25 17:13:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 17:13:13 volumio systemd[1]: shairport-sync.service: Consumed 1.556s CPU time. Jan 25 17:13:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 17:13:13 volumio sudo[4308]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:13 volumio sudo[4306]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:13 volumio volumio[1411]: info: Shairport-Sync Started Jan 25 17:13:13 volumio volumio[1411]: info: Shairport-Sync Started Jan 25 17:13:13 volumio volumio[1411]: (node:1411) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 25 17:13:13 volumio volumio[1411]: (Use `node --trace-warnings ...` to show where the warning was created) Jan 25 17:13:13 volumio volumio[1411]: (node:1411) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 25 17:13:13 volumio volumio[1411]: (node:1411) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 25 17:13:13 volumio volumio[1411]: (node:1411) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 25 17:13:13 volumio volumio[1411]: (node:1411) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jan 25 17:13:13 volumio sudo[4304]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 17:13:13 volumio sudo[4304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 17:13:13 volumio sudo[4304]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:13 volumio mpd[4327]: 2026-01-25T17:13:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 17:13:13 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 25 17:13:13 volumio sudo[4286]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:13 volumio sudo[4277]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:13 volumio volumio[1411]: error: updateQueue error: null Jan 25 17:13:13 volumio volumio[1411]: error: updateQueue error: null Jan 25 17:13:14 volumio volumio[1411]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 25 17:13:14 volumio volumio[1411]: info: Preparing to save Alsa Options, stopping services first Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::volumioGetState Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::volumioPause Jan 25 17:13:14 volumio volumio[1411]: info: CoreStateMachine::pause Jan 25 17:13:14 volumio volumio[1411]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 17:13:14 volumio volumio[1411]: info: Found match in Cards Database: setting mixer for card HDMI 0 Out Jan 25 17:13:14 volumio volumio[1411]: info: Device 0 does not have any Mixer Control Available Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 25 17:13:14 volumio volumio[1411]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 25 17:13:14 volumio volumio[1411]: info: Disabling external Volume Control Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 17:13:14 volumio volumio[1411]: info: CoreStateMachine::pushState Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::volumioPushState Jan 25 17:13:14 volumio volumio[1411]: info: Preparing to generate the ALSA configuration file Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::volumioGetQueue Jan 25 17:13:14 volumio volumio[1411]: info: CoreStateMachine::getQueue Jan 25 17:13:14 volumio volumio[1411]: info: CorePlayQueue::getQueue Jan 25 17:13:14 volumio volumio[1411]: info: Asound.conf file unchanged, so no further update is needed Jan 25 17:13:14 volumio volumio[1411]: info: Output device has changed, restarting MPD Jan 25 17:13:14 volumio sudo[4347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 17:13:14 volumio volumio[1411]: info: Output device has changed, restarting Shairport Sync Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 17:13:14 volumio sudo[4347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:14 volumio sudo[4347]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:14 volumio sudo[4349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 17:13:14 volumio sudo[4349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:14 volumio volumio[1411]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 17:13:14 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 25 17:13:14 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 25 17:13:14 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 25 17:13:14 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 25 17:13:14 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 25 17:13:14 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 25 17:13:14 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 17:13:14 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 17:13:14 volumio volumio[1411]: info: MPD Permissions set Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 17:13:14 volumio volumio[1411]: info: Starting Shairport Sync Jan 25 17:13:14 volumio sudo[4370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 17:13:14 volumio sudo[4370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 17:13:14 volumio sudo[4363]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 17:13:14 volumio sudo[4363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 17:13:14 volumio sudo[4363]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:14 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 25 17:13:14 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 25 17:13:14 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 17:13:14 volumio systemd[1]: shairport-sync.service: Consumed 1.277s CPU time. Jan 25 17:13:14 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 17:13:14 volumio sudo[4370]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:14 volumio volumio[1411]: info: Shairport-Sync Started Jan 25 17:13:15 volumio mpd[4373]: 2026-01-25T17:13:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 17:13:15 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 25 17:13:15 volumio sudo[4349]: pam_unix(sudo:session): session closed for user root Jan 25 17:13:15 volumio volumio[1411]: error: updateQueue error: null Jan 25 17:13:17 volumio volumio[1411]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 25 17:13:17 volumio volumio[1411]: info: CoreStateMachine::getcurrentVolume Jan 25 17:13:17 volumio volumio[1411]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 17:13:17 volumio volumio[1411]: info: CoreStateMachine::pushState Jan 25 17:13:17 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 17:13:17 volumio volumio[1411]: info: CoreCommandRouter::volumioPushState Jan 25 17:13:17 volumio volumio[1411]: info: CoreCommandRouter::volumioGetQueue Jan 25 17:13:17 volumio volumio[1411]: info: CoreStateMachine::getQueue Jan 25 17:13:17 volumio volumio[1411]: info: CorePlayQueue::getQueue Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::getcurrentVolume Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::pushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioPushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioGetQueue Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::getQueue Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::getQueue Jan 25 17:13:18 volumio volumio[1411]: info: Preload queue cleared Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::ClearQueue Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::stop Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::clearPlayQueue Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::saveQueue Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioPushQueue Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::addQueueItems Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::addQueueItems Jan 25 17:13:18 volumio volumio[1411]: info: Preload queue cleared Jan 25 17:13:18 volumio volumio[1411]: info: Adding Item to queue: upnp/folder/http://192.168.11.188:9001/plugins/UPnP/MediaServer/ContentDirectory/control@/a/3823/l/4931/t Jan 25 17:13:18 volumio volumio[1411]: info: Using cached record of: upnp/folder/http://192.168.11.188:9001/plugins/UPnP/MediaServer/ContentDirectory/control@/a/3823/l/4931/t Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioPushQueue Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::saveQueue Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::updateTrackBlock Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::getTrackBlock Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioPlay Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::play index 0 Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::stop Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::play index undefined Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::getTrack 0 Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::startPlaybackTimer Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::getTrack 0 Jan 25 17:13:18 volumio volumio[1411]: info: [1769361198426] ControllerUPNPBrowser::clearAddPlayTrack Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand stop took 4 milliseconds Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 17:13:18 volumio volumio[1411]: info: Jan 25 17:13:18 volumio volumio[1411]: ---------------------------- MPD announces system playlist update Jan 25 17:13:18 volumio volumio[1411]: info: Ignoring MPD Status Update Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand clear took 1 milliseconds Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.11.188:9001/music/82905/download.flc" Jan 25 17:13:18 volumio volumio[1411]: error: updateQueue error: null Jan 25 17:13:18 volumio volumio[1411]: info: ------------------------------ 1ms Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.11.188:9001/music/82905/download.flc" Jan 25 17:13:18 volumio volumio[1411]: info: Jan 25 17:13:18 volumio volumio[1411]: ---------------------------- MPD announces system playlist update Jan 25 17:13:18 volumio volumio[1411]: info: Ignoring MPD Status Update Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand add "http://192.168.11.188:9001/music/82905/download.flc" took 4 milliseconds Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand play Jan 25 17:13:18 volumio volumio[1411]: info: ------------------------------ 1ms Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand play took 4 milliseconds Jan 25 17:13:18 volumio volumio[1411]: info: Jan 25 17:13:18 volumio volumio[1411]: ---------------------------- MPD announces state update: player Jan 25 17:13:18 volumio volumio[1411]: info: ControllerMpd::getState Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand status Jan 25 17:13:18 volumio volumio[1411]: info: Jan 25 17:13:18 volumio volumio[1411]: ---------------------------- MPD announces state update: player Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand status took 5 milliseconds Jan 25 17:13:18 volumio volumio[1411]: info: ControllerMpd::getState Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand status Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::parseState Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand status took 1 milliseconds Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::parseState Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::parseTrackInfo Jan 25 17:13:18 volumio volumio[1411]: info: ControllerMpd::pushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::servicePushState Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::getTrack 0 Jan 25 17:13:18 volumio volumio[1411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":null,"album":null,"uri":"http://192.168.11.188:9001/music/82905/download.flc","trackType":"flc"} Jan 25 17:13:18 volumio volumio[1411]: verbose: CURRENT POSITION 0 Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::syncState stateService play Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::syncState currentStatus stop Jan 25 17:13:18 volumio volumio[1411]: info: ------------------------------ 10ms Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::parseTrackInfo Jan 25 17:13:18 volumio volumio[1411]: info: ControllerMpd::pushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::servicePushState Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::getTrack 0 Jan 25 17:13:18 volumio volumio[1411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"download.flc","artist":null,"album":null,"uri":"http://192.168.11.188:9001/music/82905/download.flc","trackType":"flc"} Jan 25 17:13:18 volumio volumio[1411]: verbose: CURRENT POSITION 0 Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::syncState stateService play Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::syncState currentStatus play Jan 25 17:13:18 volumio volumio[1411]: info: Received an update from plugin. extracting info from payload Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::pushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioPushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::pushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioPushState Jan 25 17:13:18 volumio volumio[1411]: info: ------------------------------ 16ms Jan 25 17:13:18 volumio volumio[1411]: info: Jan 25 17:13:18 volumio volumio[1411]: ---------------------------- MPD announces system playlist update Jan 25 17:13:18 volumio volumio[1411]: info: Ignoring MPD Status Update Jan 25 17:13:18 volumio volumio[1411]: info: Jan 25 17:13:18 volumio volumio[1411]: ---------------------------- MPD announces state update: player Jan 25 17:13:18 volumio volumio[1411]: info: ControllerMpd::getState Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand status Jan 25 17:13:18 volumio volumio[1411]: info: ------------------------------ 1ms Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand status took 2 milliseconds Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::parseState Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 17:13:18 volumio volumio[1411]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 25 17:13:18 volumio volumio[1411]: verbose: ControllerMpd::parseTrackInfo Jan 25 17:13:18 volumio volumio[1411]: info: ControllerMpd::pushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::servicePushState Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::getTrack 0 Jan 25 17:13:18 volumio volumio[1411]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dancing Queen","artist":"ABBA","album":"ABBA Gold Greatest Hits","uri":"http://192.168.11.188:9001/music/82905/download.flc","trackType":"flc"} Jan 25 17:13:18 volumio volumio[1411]: verbose: CURRENT POSITION 0 Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::syncState stateService play Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::syncState currentStatus play Jan 25 17:13:18 volumio volumio[1411]: info: Received an update from plugin. extracting info from payload Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::pushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioPushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::pushState Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioPushState Jan 25 17:13:18 volumio volumio[1411]: info: ------------------------------ 14ms Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioGetQueue Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::getQueue Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::getQueue Jan 25 17:13:18 volumio volumio[1411]: info: CoreCommandRouter::volumioGetQueue Jan 25 17:13:18 volumio volumio[1411]: info: CoreStateMachine::getQueue Jan 25 17:13:18 volumio volumio[1411]: info: CorePlayQueue::getQueue Jan 25 17:13:51 volumio kernel: usb 3-2: USB disconnect, device number 2 Jan 25 17:13:51 volumio thd[944]: Error reading device '/dev/input/event5' Jan 25 17:13:51 volumio thd[944]: Error reading device '/dev/input/event5' Jan 25 17:13:53 volumio kernel: usb 3-2: new full-speed USB device number 3 using xhci-hcd Jan 25 17:13:53 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00 Jan 25 17:13:53 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 25 17:13:53 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL Jan 25 17:13:53 volumio kernel: usb 3-2: Manufacturer: yldzkj Jan 25 17:13:53 volumio kernel: input: yldzkj USB2IIC_CTP_CONTROL as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:1A86:E5E3.0003/input/input8 Jan 25 17:13:53 volumio kernel: hid-multitouch 0003:1A86:E5E3.0003: input,hidraw0: USB HID v1.00 Device [yldzkj USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0 Jan 25 17:14:21 volumio volumio[1411]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 17:14:21 volumio volumio[1411]: TypeError: Cannot read properties of null (reading 'slice') Jan 25 17:14:21 volumio volumio[1411]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 25 17:14:21 volumio volumio[1411]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 25 17:14:21 volumio volumio[1411]: at Object.onceWrapper (node:events:629:26) Jan 25 17:14:21 volumio volumio[1411]: at ClientRequest.emit (node:events:514:28) Jan 25 17:14:21 volumio volumio[1411]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Jan 25 17:14:21 volumio volumio[1411]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Jan 25 17:14:21 volumio volumio[1411]: at TLSSocket.socketOnData (node:_http_client:535:22) Jan 25 17:14:21 volumio volumio[1411]: at TLSSocket.emit (node:events:514:28) Jan 25 17:14:21 volumio volumio[1411]: at addChunk (node:internal/streams/readable:343:12) Jan 25 17:14:21 volumio volumio[1411]: at readableAddChunk (node:internal/streams/readable:316:9) Jan 25 17:14:21 volumio volumio[1411]: at Readable.push (node:internal/streams/readable:253:10) Jan 25 17:14:21 volumio volumio[1411]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Jan 25 17:14:21 volumio volumio[1411]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 17:14:21 volumio sudo[4529]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 17:13' Jan 25 17:14:21 volumio sudo[4529]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"