-- Logs begin at Sun 2026-02-22 23:15:49 CST, end at Sun 2026-02-22 23:18:54 CST. -- Feb 22 23:17:02 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:17:02 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:17:08 volumio-x86-784 volumio[1431]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES



 

 

 



NEW ADDITIONS



 

 

 

","title":"Update v3.886","updateavailable":true} Feb 22 23:17:08 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 22 23:17:09 volumio-x86-784 volumio[1431]: info: MyVolumio login type: Token Feb 22 23:17:13 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:17:13 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:17:14 volumio-x86-784 ntpd[1420]: Soliciting pool server 193.182.111.14 Feb 22 23:17:14 volumio-x86-784 ntpd[1420]: Soliciting pool server 162.159.200.1 Feb 22 23:17:17 volumio-x86-784 ntpd[1420]: Soliciting pool server 119.28.206.193 Feb 22 23:17:24 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:17:24 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:17:25 volumio-x86-784 ntpd[1420]: Soliciting pool server 193.182.111.141 Feb 22 23:17:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:17:36 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:17:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:17:36 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 23:17:39 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 23:17:40 volumio-x86-784 volumio[1431]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 22 23:17:40 volumio-x86-784 volumio[1431]: info: Retrying Login Due to Network Error Feb 22 23:17:47 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:17:47 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:17:50 volumio-x86-784 volumio[1431]: info: MyVolumio login type: Token Feb 22 23:17:58 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:17:58 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Preparing to save Alsa Options, stopping services first Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPause Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pause Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Saving Audio Output to: {"output_device":{"value":"2,0","label":"Analog Out"}} Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Found match in Cards Database: setting mixer for card Analog Out Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Setting mixer Master for card Analog Out Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Updating Volume Controller Parameters: Device: 2,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Disabling external Volume Control Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Preparing to generate the ALSA configuration file Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Reading ALSA contributions from plugins. Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=41 Mute =false Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Asound.conf file written Feb 22 23:18:04 volumio-x86-784 sudo[2840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 22 23:18:04 volumio-x86-784 sudo[2840]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:04 volumio-x86-784 sudo[2840]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting MPD Feb 22 23:18:04 volumio-x86-784 sudo[2845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 23:18:04 volumio-x86-784 sudo[2845]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting Shairport Sync Feb 22 23:18:04 volumio-x86-784 sudo[2845]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:04 volumio-x86-784 sudo[2848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 23:18:04 volumio-x86-784 sudo[2848]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:04 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 23:18:04 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting MPD Feb 22 23:18:04 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Feb 22 23:18:04 volumio-x86-784 sudo[2856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 23:18:04 volumio-x86-784 sudo[2856]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting Shairport Sync Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: MPD Permissions set Feb 22 23:18:04 volumio-x86-784 sudo[2856]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:04 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Feb 22 23:18:04 volumio-x86-784 sudo[2858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 23:18:04 volumio-x86-784 sudo[2858]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 22 23:18:04 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Feb 22 23:18:04 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Feb 22 23:18:04 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:04 volumio-x86-784 sudo[2865]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 23:18:04 volumio-x86-784 sudo[2865]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Starting Shairport Sync Feb 22 23:18:04 volumio-x86-784 sudo[2865]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Starting Shairport Sync Feb 22 23:18:04 volumio-x86-784 sudo[2880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 23:18:04 volumio-x86-784 sudo[2880]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:04 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 22 23:18:04 volumio-x86-784 sudo[2883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 23:18:04 volumio-x86-784 sudo[2883]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:04 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Feb 22 23:18:04 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:04 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:04 volumio-x86-784 sudo[2880]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Shairport-Sync Started Feb 22 23:18:04 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 22 23:18:04 volumio-x86-784 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 22 23:18:04 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Feb 22 23:18:04 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:04 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:04 volumio-x86-784 sudo[2883]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:04 volumio-x86-784 volumio[1431]: info: Shairport-Sync Started Feb 22 23:18:04 volumio-x86-784 mpd[2879]: Feb 22 23:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 23:18:04 volumio-x86-784 systemd[1]: Started Music Player Daemon. Feb 22 23:18:04 volumio-x86-784 sudo[2848]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:04 volumio-x86-784 sudo[2858]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:04 volumio-x86-784 volumio[1431]: error: MPD error: The expression evaluated to a falsy value: Feb 22 23:18:04 volumio-x86-784 volumio[1431]: assert.ok(self.idling) Feb 22 23:18:04 volumio-x86-784 volumio[1431]: error: The expression evaluated to a falsy value: Feb 22 23:18:04 volumio-x86-784 volumio[1431]: assert.ok(self.idling) Feb 22 23:18:04 volumio-x86-784 volumio[1431]: error: updateQueue error: null Feb 22 23:18:05 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:05 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:05 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:05 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Preparing to save Alsa Options, stopping services first Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPause Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pause Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Saving Audio Output to: {"output_device":{"value":"2,0","label":"Analog Out"}} Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Found match in Cards Database: setting mixer for card Analog Out Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Setting mixer Master for card Analog Out Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Updating Volume Controller Parameters: Device: 2,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Disabling external Volume Control Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Preparing to generate the ALSA configuration file Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Reading ALSA contributions from plugins. Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Asound.conf file unchanged, so no further update is needed Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting MPD Feb 22 23:18:06 volumio-x86-784 sudo[2906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 23:18:06 volumio-x86-784 sudo[2906]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting Shairport Sync Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:06 volumio-x86-784 sudo[2906]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:06 volumio-x86-784 sudo[2909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 23:18:06 volumio-x86-784 sudo[2909]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:06 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 23:18:06 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Feb 22 23:18:06 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: MPD Permissions set Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=41 Mute =false Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Starting Shairport Sync Feb 22 23:18:06 volumio-x86-784 sudo[2922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 23:18:06 volumio-x86-784 sudo[2922]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:06 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Feb 22 23:18:06 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 22 23:18:06 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Feb 22 23:18:06 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:06 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:06 volumio-x86-784 sudo[2922]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:06 volumio-x86-784 volumio[1431]: info: Shairport-Sync Started Feb 22 23:18:06 volumio-x86-784 sudo[2914]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 23:18:06 volumio-x86-784 sudo[2914]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:06 volumio-x86-784 sudo[2914]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:06 volumio-x86-784 mpd[2928]: Feb 22 23:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 23:18:06 volumio-x86-784 systemd[1]: Started Music Player Daemon. Feb 22 23:18:06 volumio-x86-784 sudo[2909]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:06 volumio-x86-784 volumio[1431]: error: updateQueue error: null Feb 22 23:18:07 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:07 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreStateMachine::getcurrentVolume Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=41 Mute =false Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreStateMachine::updateTrackBlock Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrackBlock Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=41 Mute =false Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:08 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:09 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:09 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreStateMachine::getcurrentVolume Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=41 Mute =false Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreStateMachine::updateTrackBlock Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrackBlock Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=41 Mute =false Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:10 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPlay Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: CoreStateMachine::play index undefined Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: CoreStateMachine::startPlaybackTimer Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:11 volumio-x86-784 volumio[1431]: verbose: ControllerMpd::clearAddPlayTracks USB/云净U盘/酷我音乐3/一生何求 - 陈佳.mp3 Feb 22 23:18:11 volumio-x86-784 volumio[1431]: verbose: ControllerMpd::sendMpdCommand stop Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: sendMpdCommand stop took 1 milliseconds Feb 22 23:18:11 volumio-x86-784 volumio[1431]: verbose: ControllerMpd::sendMpdCommand clear Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: Feb 22 23:18:11 volumio-x86-784 volumio[1431]: ---------------------------- MPD announces system playlist update Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: Ignoring MPD Status Update Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: sendMpdCommand clear took 0 milliseconds Feb 22 23:18:11 volumio-x86-784 volumio[1431]: verbose: ControllerMpd::sendMpdCommand add "USB/云净U盘/酷我音乐3/一生何求 - 陈佳.mp3" Feb 22 23:18:11 volumio-x86-784 volumio[1431]: error: updateQueue error: null Feb 22 23:18:11 volumio-x86-784 volumio[1431]: info: ------------------------------ 0ms Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Preparing to save Alsa Options, stopping services first Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPause Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pause Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Saving Audio Output to: {"output_device":{"value":"2,0","label":"Analog Out"}} Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Found match in Cards Database: setting mixer for card Analog Out Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Setting mixer Master for card Analog Out Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Updating Volume Controller Parameters: Device: 2,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Disabling external Volume Control Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Preparing to generate the ALSA configuration file Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Reading ALSA contributions from plugins. Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Asound.conf file unchanged, so no further update is needed Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting MPD Feb 22 23:18:16 volumio-x86-784 sudo[2975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 23:18:16 volumio-x86-784 sudo[2975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting Shairport Sync Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:16 volumio-x86-784 sudo[2975]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:16 volumio-x86-784 sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 23:18:16 volumio-x86-784 sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:16 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 23:18:16 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: MPD Permissions set Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=41 Mute =false Feb 22 23:18:16 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Starting Shairport Sync Feb 22 23:18:16 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Feb 22 23:18:16 volumio-x86-784 sudo[2991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 23:18:16 volumio-x86-784 sudo[2991]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:16 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 22 23:18:16 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Feb 22 23:18:16 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:16 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:16 volumio-x86-784 sudo[2991]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: Shairport-Sync Started Feb 22 23:18:16 volumio-x86-784 sudo[2983]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 23:18:16 volumio-x86-784 sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:16 volumio-x86-784 sudo[2983]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:16 volumio-x86-784 mpd[2997]: Feb 22 23:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 23:18:16 volumio-x86-784 systemd[1]: Started Music Player Daemon. Feb 22 23:18:16 volumio-x86-784 sudo[2978]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:16 volumio-x86-784 volumio[1431]: error: updateQueue error: null Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:16 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:19 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:19 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreStateMachine::getcurrentVolume Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=41 Mute =false Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreStateMachine::updateTrackBlock Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrackBlock Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=41 Mute =false Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:20 volumio-x86-784 volumio[1431]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: Retrying Login Due to Network Error Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:20 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:22 volumio-x86-784 ntpd[1420]: Soliciting pool server 193.182.111.143 Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioSeek Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: CoreStateMachine::seek Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: TRACKBLOCK {"uri":"mnt/USB/云净U盘/酷我音乐3/一生何求 - 陈佳.mp3","service":"mpd","name":"一生何求 - 陈佳.mp3","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=946&web=//extralarge&path=%2FUSB%2F%E4%BA%91%E5%87%80U%E7%9B%98%2F%E9%85%B7%E6%88%91%E9%9F%B3%E4%B9%903&metadata=false","duration":262,"trackType":"mp3"} Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: CoreStateMachine::startPlaybackTimer Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: ControllerMpd::seek Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:22 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:22 volumio-x86-784 volumio[1431]: error: [2@0] {seek} Bad song index Feb 22 23:18:23 volumio-x86-784 ntpd[1420]: Soliciting pool server 106.54.244.49 Feb 22 23:18:24 volumio-x86-784 ntpd[1420]: Soliciting pool server 162.159.200.123 Feb 22 23:18:25 volumio-x86-784 ntpd[1420]: Soliciting pool server 193.182.111.142 Feb 22 23:18:25 volumio-x86-784 ntpd[1420]: Soliciting pool server 139.199.214.202 Feb 22 23:18:26 volumio-x86-784 ntpd[1420]: Soliciting pool server 162.159.200.123 Feb 22 23:18:26 volumio-x86-784 ntpd[1420]: Soliciting pool server 84.16.67.12 Feb 22 23:18:27 volumio-x86-784 ntpd[1420]: Soliciting pool server 2a03:8600::bb Feb 22 23:18:27 volumio-x86-784 ntpd[1420]: Soliciting pool server 162.159.200.1 Feb 22 23:18:28 volumio-x86-784 ntpd[1420]: Soliciting pool server 84.16.73.33 Feb 22 23:18:29 volumio-x86-784 ntpd[1420]: Soliciting pool server 210.28.130.4 Feb 22 23:18:30 volumio-x86-784 ntpd[1420]: Soliciting pool server 119.28.183.184 Feb 22 23:18:30 volumio-x86-784 volumio[1431]: info: MyVolumio login type: Token Feb 22 23:18:31 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:31 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 23:18:36 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Preparing to save Alsa Options, stopping services first Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPause Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pause Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Saving Audio Output to: {"output_device":{"value":"7","label":"Dummy"}} Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Setting mixer Master for card Dummy Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Updating Volume Controller Parameters: Device: 7 Name: Dummy Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Disabling external Volume Control Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Preparing to generate the ALSA configuration file Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Reading ALSA contributions from plugins. Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Unable to parse: Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Simple mixer control 'Master',0 Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Capabilities: volume cswitch Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Playback channels: Front Left - Front Right Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Capture channels: Front Left - Front Right Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Limits: -50 - 100 Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Front Left: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Front Right: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:41 volumio-x86-784 volumio[1431]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Unable to parse: Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Simple mixer control 'Master',0 Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Capabilities: volume cswitch Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Playback channels: Front Left - Front Right Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Capture channels: Front Left - Front Right Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Limits: -50 - 100 Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Front Left: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:41 volumio-x86-784 volumio[1431]: Front Right: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=undefined Mute =false Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Asound.conf file written Feb 22 23:18:41 volumio-x86-784 sudo[3074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 22 23:18:41 volumio-x86-784 sudo[3074]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:41 volumio-x86-784 sudo[3074]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting MPD Feb 22 23:18:41 volumio-x86-784 sudo[3079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 23:18:41 volumio-x86-784 sudo[3079]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting Shairport Sync Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:41 volumio-x86-784 sudo[3079]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:41 volumio-x86-784 sudo[3082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 23:18:41 volumio-x86-784 sudo[3082]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:41 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting MPD Feb 22 23:18:41 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Feb 22 23:18:41 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Feb 22 23:18:41 volumio-x86-784 sudo[3089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 23:18:41 volumio-x86-784 sudo[3089]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Output device has changed, restarting Shairport Sync Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: MPD Permissions set Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 22 23:18:41 volumio-x86-784 sudo[3089]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:41 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Feb 22 23:18:41 volumio-x86-784 sudo[3092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 23:18:41 volumio-x86-784 sudo[3092]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:41 volumio-x86-784 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 22 23:18:41 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Feb 22 23:18:41 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Feb 22 23:18:41 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Starting Shairport Sync Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Starting Shairport Sync Feb 22 23:18:41 volumio-x86-784 sudo[3111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 23:18:41 volumio-x86-784 sudo[3111]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:41 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 22 23:18:41 volumio-x86-784 sudo[3114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 23:18:41 volumio-x86-784 sudo[3114]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:41 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Feb 22 23:18:41 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:41 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:41 volumio-x86-784 sudo[3111]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Shairport-Sync Started Feb 22 23:18:41 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 22 23:18:41 volumio-x86-784 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 22 23:18:41 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Feb 22 23:18:41 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:41 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 22 23:18:41 volumio-x86-784 sudo[3114]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:41 volumio-x86-784 sudo[3108]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 23:18:41 volumio-x86-784 sudo[3108]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 22 23:18:41 volumio-x86-784 volumio[1431]: info: Shairport-Sync Started Feb 22 23:18:41 volumio-x86-784 sudo[3108]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:41 volumio-x86-784 mpd[3119]: Feb 22 23:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 23:18:41 volumio-x86-784 systemd[1]: Started Music Player Daemon. Feb 22 23:18:41 volumio-x86-784 sudo[3092]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:41 volumio-x86-784 sudo[3082]: pam_unix(sudo:session): session closed for user root Feb 22 23:18:41 volumio-x86-784 volumio[1431]: error: MPD error: The expression evaluated to a falsy value: Feb 22 23:18:41 volumio-x86-784 volumio[1431]: assert.ok(self.idling) Feb 22 23:18:41 volumio-x86-784 volumio[1431]: error: The expression evaluated to a falsy value: Feb 22 23:18:41 volumio-x86-784 volumio[1431]: assert.ok(self.idling) Feb 22 23:18:41 volumio-x86-784 volumio[1431]: error: updateQueue error: null Feb 22 23:18:42 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:42 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:42 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:42 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 23:18:42 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:42 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreStateMachine::getcurrentVolume Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Unable to parse: Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Simple mixer control 'Master',0 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Capabilities: volume cswitch Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Playback channels: Front Left - Front Right Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Capture channels: Front Left - Front Right Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Limits: -50 - 100 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Front Left: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Front Right: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:45 volumio-x86-784 volumio[1431]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Unable to parse: Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Simple mixer control 'Master',0 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Capabilities: volume cswitch Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Playback channels: Front Left - Front Right Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Capture channels: Front Left - Front Right Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Limits: -50 - 100 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Front Left: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Front Right: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=undefined Mute =false Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreStateMachine::updateTrackBlock Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrackBlock Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Unable to parse: Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Simple mixer control 'Master',0 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Capabilities: volume cswitch Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Playback channels: Front Left - Front Right Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Capture channels: Front Left - Front Right Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Limits: -50 - 100 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Front Left: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Front Right: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:45 volumio-x86-784 volumio[1431]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Unable to parse: Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Simple mixer control 'Master',0 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Capabilities: volume cswitch Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Playback channels: Front Left - Front Right Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Capture channels: Front Left - Front Right Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Limits: -50 - 100 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Front Left: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:45 volumio-x86-784 volumio[1431]: Front Right: 0 [17%] [-30.00dB] Capture [off] Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: VolumeController:: Volume=undefined Mute =false Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreStateMachine::pushState Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 23:18:45 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioPushState Feb 22 23:18:53 volumio-x86-784 volumio[1431]: info: CoreCommandRouter::volumioGetState Feb 22 23:18:53 volumio-x86-784 volumio[1431]: info: CorePlayQueue::getTrack 8 Feb 22 23:18:54 volumio-x86-784 volumio[1431]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 23:18:54 volumio-x86-784 volumio[1431]: Error: connect ETIMEDOUT 157.240.1.33:443 Feb 22 23:18:54 volumio-x86-784 volumio[1431]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Feb 22 23:18:54 volumio-x86-784 volumio[1431]: errno: -110, Feb 22 23:18:54 volumio-x86-784 volumio[1431]: code: 'ETIMEDOUT', Feb 22 23:18:54 volumio-x86-784 volumio[1431]: syscall: 'connect', Feb 22 23:18:54 volumio-x86-784 volumio[1431]: address: '157.240.1.33', Feb 22 23:18:54 volumio-x86-784 volumio[1431]: port: 443 Feb 22 23:18:54 volumio-x86-784 volumio[1431]: } Feb 22 23:18:54 volumio-x86-784 volumio[1431]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 23:18:54 volumio-x86-784 sudo[3186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-22 23:17 Feb 22 23:18:54 volumio-x86-784 sudo[3186]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"