-- Logs begin at Sun 2026-02-22 23:15:49 CST, end at Sun 2026-02-22 23:19:49 CST. --
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)
Feb 22 23:18:54 volumio-x86-784 sudo[3186]: pam_unix(sudo:session): session closed for user root
Feb 22 23:18:58 volumio-x86-784 volumio-remote-updater[1019]: [2026-02-22 23:18:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Feb 22 23:18:58 volumio-x86-784 volumio-remote-updater[1019]: [2026-02-22 23:18:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Feb 22 23:18:58 volumio-x86-784 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Feb 22 23:18:58 volumio-x86-784 systemd[1]: media-\xe4\xba\x91\xe5\x87\x80U\xe7\x9b\x98.mount: Succeeded.
Feb 22 23:18:58 volumio-x86-784 systemd[1]: media-1C400CC0400CA29E.mount: Succeeded.
Feb 22 23:18:58 volumio-x86-784 ntfs-3g[1740]: Unmounting /dev/sdc5 ()
Feb 22 23:18:58 volumio-x86-784 systemd[1]: media-18687A45687A2224.mount: Succeeded.
Feb 22 23:18:58 volumio-x86-784 ntfs-3g[1769]: Unmounting /dev/sda1 (云净U盘)
Feb 22 23:18:58 volumio-x86-784 ntfs-3g[1712]: Unmounting /dev/sdc1 ()
Feb 22 23:18:58 volumio-x86-784 systemd[1]: volumio.service: Failed with result 'exit-code'.
Feb 22 23:18:58 volumio-x86-784 systemd[1]: Started dynamicswap service.
Feb 22 23:18:58 volumio-x86-784 systemd[1]: dynamicswap.service: Succeeded.
Feb 22 23:18:59 volumio-x86-784 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Feb 22 23:18:59 volumio-x86-784 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1.
Feb 22 23:18:59 volumio-x86-784 systemd[1]: Stopped Volumio Backend Module.
Feb 22 23:18:59 volumio-x86-784 systemd[1]: Started Volumio Backend Module.
Feb 22 23:18:59 volumio-x86-784 systemd[1]: Started dynamicswap service.
Feb 22 23:18:59 volumio-x86-784 systemd[1]: dynamicswap.service: Succeeded.
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: -------------------------------------------
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: -----            Volumio3              ----
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: -------------------------------------------
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: -----          System startup          ----
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: -------------------------------------------
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: MYVOLUMIO Environment detected
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Plugin folders cleanup
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning into folder /volumio/app/plugins/
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category audio_interface
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category miscellanea
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category music_service
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category plugins.json
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category system_controller
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category user_interface
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning into folder /data/plugins/
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category music_service
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category system_controller
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category system_hardware
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Scanning category user_interface
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Plugin folders cleanup completed
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: -------------------------------------------
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: -----      Core plugins startup        ----
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: -------------------------------------------
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Loading plugins from folder /volumio/app/plugins/
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Adding plugin upnp to MyMusic Plugins
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Adding plugin airplay_emulation to MyMusic Plugins
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Adding plugin upnp_browser to MyMusic Plugins
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Loading plugins from folder /data/plugins/
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Loading plugin "system"...
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Loading plugin "appearance"...
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Loading plugin "network"...
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Refreshing Cached IP Addresses
Feb 22 23:18:59 volumio-x86-784 sudo[3281]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 22 23:18:59 volumio-x86-784 sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:18:59 volumio-x86-784 sudo[3281]: pam_unix(sudo:session): session closed for user root
Feb 22 23:18:59 volumio-x86-784 sudo[3283]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 22 23:18:59 volumio-x86-784 sudo[3283]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:18:59 volumio-x86-784 sudo[3283]: pam_unix(sudo:session): session closed for user root
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Loading plugin "services"...
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Loading plugin "alsa_controller"...
Feb 22 23:18:59 volumio-x86-784 sudo[3295]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Feb 22 23:18:59 volumio-x86-784 sudo[3295]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Loading plugin "wizard"...
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Loading plugin "networkfs"...
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Cannot mount NAS local at system boot, trial number 1 ,retrying in 5 seconds
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Starting Udev Watcher for removable devices
Feb 22 23:18:59 volumio-x86-784 volumio[3254]: info: Mounting Device 18687A45687A2224
Feb 22 23:18:59 volumio-x86-784 sudo[3312]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/18687A45687A2224 -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Feb 22 23:18:59 volumio-x86-784 sudo[3312]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:00 volumio-x86-784 ntfs-3g[3315]: Version 2017.3.23AR.3 integrated FUSE 28
Feb 22 23:19:00 volumio-x86-784 ntfs-3g[3315]: Mounted /dev/sdc1 (Read-Write, label "", NTFS 3.1)
Feb 22 23:19:00 volumio-x86-784 ntfs-3g[3315]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8
Feb 22 23:19:00 volumio-x86-784 ntfs-3g[3315]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdc1,blkdev,blksize=4096
Feb 22 23:19:00 volumio-x86-784 ntfs-3g[3315]: Global ownership and permissions enforced, configuration type 7
Feb 22 23:19:00 volumio-x86-784 sudo[3312]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:00 volumio-x86-784 volumio[3254]: error: Cannot associate FS Label, not mounting
Feb 22 23:19:00 volumio-x86-784 volumio[3254]: info: Mounting Device 1C400CC0400CA29E
Feb 22 23:19:00 volumio-x86-784 sudo[3320]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc5 /mnt/USB/1C400CC0400CA29E -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Feb 22 23:19:00 volumio-x86-784 sudo[3320]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3323]: Version 2017.3.23AR.3 integrated FUSE 28
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3323]: Mounted /dev/sdc5 (Read-Write, label "", NTFS 3.1)
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3323]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3323]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdc5,blkdev,blksize=4096
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3323]: Global ownership and permissions enforced, configuration type 7
Feb 22 23:19:01 volumio-x86-784 sudo[3320]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: boot
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: volumio
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: volumio_data
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: 52663293663277B5
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: error: Cannot associate FS Label, not mounting
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: 音乐相片
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: 电影
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: 程序备份
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: ESP_EFI
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: error: Cannot associate FS Label, not mounting
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: Win_10_Pro_x64
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Ignoring mount for partition: 软件
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Mounting Device 云净U盘
Feb 22 23:19:01 volumio-x86-784 sudo[3349]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/云净U盘 -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Feb 22 23:19:01 volumio-x86-784 sudo[3349]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3353]: Version 2017.3.23AR.3 integrated FUSE 28
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3353]: Mounted /dev/sda1 (Read-Write, label "云净U盘", NTFS 3.1)
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3353]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3353]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=512
Feb 22 23:19:01 volumio-x86-784 ntfs-3g[3353]: Global ownership and permissions enforced, configuration type 7
Feb 22 23:19:01 volumio-x86-784 sudo[3349]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Mounting Device _ƾ_EFI
Feb 22 23:19:01 volumio-x86-784 sudo[3358]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda4 /mnt/USB/_ƾ_EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Feb 22 23:19:01 volumio-x86-784 sudo[3358]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:01 volumio-x86-784 sudo[3358]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: mount: /media/_ƾ_EFI: /dev/sda4 already mounted on /media/_ƾ_EFI.
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: error: Failed to mount _ƾ_EFI: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda4" "/mnt/USB/_ƾ_EFI" -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: mount: /media/_ƾ_EFI: /dev/sda4 already mounted on /media/_ƾ_EFI.
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "volumio_command_line_client"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "upnp"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: [1771773541710] Starting Upmpd Daemon
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "my_music"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "mpd"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "upnp_browser"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "alarm-clock"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "airplay_emulation"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Starting Shairport Sync
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "last_100"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "webradio"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "i2s_dacs"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: I2S DAC not set, start Auto-detection
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "volumiodiscovery"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Feb 22 23:19:01 volumio-x86-784 node[3254]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Feb 22 23:19:01 volumio-x86-784 node[3254]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: *** WARNING *** For more information see 
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: *** WARNING *** For more information see 
Feb 22 23:19:01 volumio-x86-784 node[3254]: *** WARNING *** For more information see 
Feb 22 23:19:01 volumio-x86-784 node[3254]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Feb 22 23:19:01 volumio-x86-784 node[3254]: *** WARNING *** Please fix your application to use the native API of Avahi!
Feb 22 23:19:01 volumio-x86-784 node[3254]: *** WARNING *** For more information see 
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Applying required configuration parameters for plugin volumiodiscovery
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Discovery: Started advertising with name: Volumio-x86-784
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin jellyfin is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin youtube2 is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin ytcr is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "autostart"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Applying required configuration parameters for plugin autostart
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: AutoStart - onVolumioStart -  read config.json
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin now_playing is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "peppy_screensaver"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Applying required configuration parameters for plugin peppy_screensaver
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "outputs"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "albumart"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin example_plugin is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "inputs"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "updater_comm"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin mpdemulation is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "rest_api"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "websocket"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Starting Socket.io Server version 2.3.0
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin 80s80s is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin RoonBridge is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading plugin "minidlna"...
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: Forking 7 albumart workers
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Applying required configuration parameters for plugin minidlna
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin podcast is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin backup_restore is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin onkyo_control is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Plugin Systeminfo is not enabled
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Loading i18n strings for locale zh
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: Updating browse sources language
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::initPlayerControls
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: Express server listening on port 3000
Feb 22 23:19:01 volumio-x86-784 volumio[3254]:  [Metrics]  WebUI:  2s 671.13ms 
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: Setting Device type: x86
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreStateMachine::resetVolumioState
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreStateMachine::getcurrentVolume
Feb 22 23:19:01 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioRetrievevolume
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Starting albumart workers
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Starting albumart workers
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Completed loading Core Plugins
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Preparing to generate the ALSA configuration file
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Starting albumart workers
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Volumio Network Manager: Network status updated: 2
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Reading ALSA contributions from plugins.
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Front Left: 0 [17%] [-30.00dB] Capture [off]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Front Right: 0 [17%] [-30.00dB] Capture [off]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Starting albumart workers
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Starting albumart workers
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Starting albumart workers
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Scanning new location : "USB/1C400CC0400CA29E"
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Starting albumart workers
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Scanning new location : "USB/云净U盘"
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Reloading queue from file
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Front Left: 0 [17%] [-30.00dB] Capture [off]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Front Right: 0 [17%] [-30.00dB] Capture [off]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: VolumeController:: Volume=undefined Mute =false
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreStateMachine::updateTrackBlock
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrackBlock
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioRetrievevolume
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setRepeat null single undefined
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setRandom true
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Front Left: 0 [17%] [-30.00dB] Capture [off]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Front Right: 0 [17%] [-30.00dB] Capture [off]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Asound.conf file unchanged, so no further update is needed
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Output device has changed, restarting MPD
Feb 22 23:19:02 volumio-x86-784 sudo[3489]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 22 23:19:02 volumio-x86-784 sudo[3489]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Output device has changed, restarting Shairport Sync
Feb 22 23:19:02 volumio-x86-784 sudo[3489]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:02 volumio-x86-784 sudo[3492]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 22 23:19:02 volumio-x86-784 sudo[3492]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Stopping Music Player Daemon...
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: ___________ START PLUGINS ___________
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: ControllerMpd::onStart: Initializing MPD
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Creating MPD Configuration file
Feb 22 23:19:02 volumio-x86-784 sudo[3498]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 22 23:19:02 volumio-x86-784 sudo[3498]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 sudo[3498]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: [1771773542157] CoreMusicLibrary::Adding element 媒体服务器
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 22 23:19:02 volumio-x86-784 sudo[3501]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 22 23:19:02 volumio-x86-784 sudo[3501]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:02 volumio-x86-784 systemd[1]: mpd.service: Succeeded.
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Stopped Music Player Daemon.
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: [1771773542180] CoreMusicLibrary::Adding element Last_100
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: [1771773542181] CoreMusicLibrary::Adding element Webradio
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Initializing BBC Radios
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: AutoStart - onStart
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Loading i18n strings for locale zh
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Starting Music Player Daemon...
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: /tmp/myfifo created
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: /tmp/myfifosa created
Feb 22 23:19:02 volumio-x86-784 sudo[3525]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0
Feb 22 23:19:02 volumio-x86-784 sudo[3525]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 sudo[3525]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: snd-dummy loaded
Feb 22 23:19:02 volumio-x86-784 sudo[3506]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 22 23:19:02 volumio-x86-784 sudo[3506]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 sudo[3506]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Loading i18n strings for locale zh
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Volumio Calling Home
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: MPD Permissions set
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: MPD Permissions set
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Front Left: 0 [17%] [-30.00dB] Capture [off]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   Front Right: 0 [17%] [-30.00dB] Capture [off]
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: VolumeController:: Volume=undefined Mute =false
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: minidlna: Found miniDLNA version 1.2.1
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: minidlna: Starting minidlna.service
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 sudo[3540]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service
Feb 22 23:19:02 volumio-x86-784 sudo[3540]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 sudo[3540]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: minidlna: systemctl start minidlna.service succeeded.
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Starting Shairport Sync
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Starting Shairport Sync
Feb 22 23:19:02 volumio-x86-784 sudo[3555]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 22 23:19:02 volumio-x86-784 sudo[3555]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Starting Shairport Sync
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Feb 22 23:19:02 volumio-x86-784 sudo[3558]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 22 23:19:02 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded.
Feb 22 23:19:02 volumio-x86-784 sudo[3558]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Starting Shairport Sync
Feb 22 23:19:02 volumio-x86-784 sudo[3561]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 22 23:19:02 volumio-x86-784 sudo[3561]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 mpd[3528]: Feb 22 23:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 sudo[3565]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:02 volumio-x86-784 sudo[3565]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Started Music Player Daemon.
Feb 22 23:19:02 volumio-x86-784 sudo[3555]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 sudo[3501]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 sudo[3492]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Shairport-Sync Started
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: Error adding Membership: Error: addMembership EINVAL
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Feb 22 23:19:02 volumio-x86-784 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Feb 22 23:19:02 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded.
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:02 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:02 volumio-x86-784 sudo[3565]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 sudo[3561]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 sudo[3558]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Completed starting Core Plugins
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: -------------------------------------------
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: -----    MyVolumio plugins startup     ----
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: -------------------------------------------
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Fetching plans data....
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Shairport-Sync Started
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Shairport-Sync Started
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Shairport-Sync Started
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: error: MPD error: The expression evaluated to a falsy value:
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   assert.ok(self.idling)
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: error: The expression evaluated to a falsy value:
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:   assert.ok(self.idling)
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: MPD running with PID3528
Feb 22 23:19:02 volumio-x86-784 volumio[3254]:  ,establishing connection
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetVisibleSources
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Received Get System Info
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Discovery: Getting this device information
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: Listing playlists
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetQueue
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CoreStateMachine::getQueue
Feb 22 23:19:02 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getQueue
Feb 22 23:19:03 volumio-x86-784 volumio[3254]: info: VolumeController::SetAlsaVolume0
Feb 22 23:19:03 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:03 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:03 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 22 23:19:03 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:03 volumio-x86-784 volumio-remote-updater[1019]: [2026-02-22 23:19:03] [connect] Successful connection
Feb 22 23:19:03 volumio-x86-784 volumio-remote-updater[1019]: [2026-02-22 23:19:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1771773543 101
Feb 22 23:19:03 volumio-x86-784 volumio[3254]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5
Feb 22 23:19:04 volumio-x86-784 volumio[3254]: info: Cannot mount NAS local at system boot, trial number 2 ,retrying in 5 seconds
Feb 22 23:19:04 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:04 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:06 volumio-x86-784 sudo[3295]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:07 volumio-x86-784 volumio[3254]: info: Discovery: adding 0e59c70f-ec26-43f5-9bfb-5d29c01886b1
Feb 22 23:19:07 volumio-x86-784 volumio[3254]: info: Discovery: Found device Volumio-x86-784
Feb 22 23:19:07 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:07 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object]
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Preparing to save Alsa Options, stopping services first
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPause
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pause
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Saving Audio Output to: {"output_device":{"value":"7","label":"Dummy"}}
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Setting mixer Master for card Dummy
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Updating Volume Controller Parameters: Device: 7 Name: Dummy Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Disabling external Volume Control
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Preparing to generate the ALSA configuration file
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Cannot mount NAS local at system boot, trial number 3 ,retrying in 5 seconds
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Reading ALSA contributions from plugins.
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Front Left: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Front Right: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Front Left: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:09 volumio-x86-784 volumio[3254]:   Front Right: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: VolumeController:: Volume=undefined Mute =false
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Asound.conf file unchanged, so no further update is needed
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Output device has changed, restarting MPD
Feb 22 23:19:09 volumio-x86-784 sudo[3614]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 22 23:19:09 volumio-x86-784 sudo[3614]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Output device has changed, restarting Shairport Sync
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:09 volumio-x86-784 sudo[3614]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:09 volumio-x86-784 sudo[3616]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 22 23:19:09 volumio-x86-784 sudo[3616]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:09 volumio-x86-784 systemd[1]: Stopping Music Player Daemon...
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 22 23:19:09 volumio-x86-784 systemd[1]: mpd.service: Succeeded.
Feb 22 23:19:09 volumio-x86-784 systemd[1]: Stopped Music Player Daemon.
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: MPD Permissions set
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Starting Shairport Sync
Feb 22 23:19:09 volumio-x86-784 sudo[3630]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 22 23:19:09 volumio-x86-784 sudo[3630]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:09 volumio-x86-784 systemd[1]: Starting Music Player Daemon...
Feb 22 23:19:09 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Feb 22 23:19:09 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded.
Feb 22 23:19:09 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:09 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:09 volumio-x86-784 sudo[3630]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: info: Shairport-Sync Started
Feb 22 23:19:09 volumio-x86-784 sudo[3622]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 22 23:19:09 volumio-x86-784 sudo[3622]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:09 volumio-x86-784 sudo[3622]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:09 volumio-x86-784 mpd[3636]: Feb 22 23:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 22 23:19:09 volumio-x86-784 systemd[1]: Started Music Player Daemon.
Feb 22 23:19:09 volumio-x86-784 sudo[3616]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:09 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:10 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:10 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:11 volumio-x86-784 sudo[3640]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Feb 22 23:19:11 volumio-x86-784 sudo[3640]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:11 volumio-x86-784 sudo[3640]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:11 volumio-x86-784 sudo[3642]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Feb 22 23:19:11 volumio-x86-784 sudo[3642]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:11 volumio-x86-784 sudo[3642]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:11 volumio-x86-784 sudo[3646]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Feb 22 23:19:11 volumio-x86-784 sudo[3646]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:11 volumio-x86-784 sudo[3646]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:11 volumio-x86-784 volumio[3254]: info: Upmpdcli Daemon Started
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPlay
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index undefined
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreStateMachine::startPlaybackTimer
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::clearAddPlayTracks USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand stop
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: sendMpdCommand stop took 0 milliseconds
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand clear
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: sendMpdCommand clear took 0 milliseconds
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand add "USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3"
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: ------------------------------ 1ms
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreStateMachine::getcurrentVolume
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioRetrievevolume
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Front Left: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Front Right: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Front Left: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Front Right: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: VolumeController:: Volume=undefined Mute =false
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreStateMachine::updateTrackBlock
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrackBlock
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioRetrievevolume
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Front Left: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Front Right: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: Unable to parse:
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:  Simple mixer control 'Master',0
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Capabilities: volume cswitch
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Playback channels: Front Left - Front Right
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Capture channels: Front Left - Front Right
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Limits: -50 - 100
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Front Left: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:13 volumio-x86-784 volumio[3254]:   Front Right: -50 [0%] [-45.00dB] Capture [off]
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: VolumeController:: Volume=undefined Mute =false
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 22 23:19:13 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:14 volumio-x86-784 volumio[3254]: info: Cannot mount NAS local at system boot, trial number 4 ,retrying in 5 seconds
Feb 22 23:19:15 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:15 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Adding plugin bluetooth to MyMusic Plugins
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Adding plugin multiroom to MyMusic Plugins
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Adding plugin metavolumio to MyMusic Plugins
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Adding plugin cd_controller to MyMusic Plugins
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Adding plugin smart_inputs to MyMusic Plugins
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Adding plugin tidalconnect to MyMusic Plugins
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Starting MyVolumio Remote Streaming Endpoints
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: MyVolumio login type: Token
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Starting Streaming Service Transparent Proxy
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Streaming services startup
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: Starting Streaming Daemon
Feb 22 23:19:17 volumio-x86-784 sudo[3667]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Feb 22 23:19:17 volumio-x86-784 sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:17 volumio-x86-784 sudo[3667]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: error: Cannot start Volumio Streaming Daemon
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Feb 22 23:19:17 volumio-x86-784 volumio[3254]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Feb 22 23:19:18 volumio-x86-784 volumio[3254]: STREAMING PROXY: Starting server on port 3245
Feb 22 23:19:18 volumio-x86-784 volumio[3254]: Node JS runtime: 14
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object]
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Preparing to save Alsa Options, stopping services first
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPause
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pause
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Saving Audio Output to: {"output_device":{"value":"2,0","label":"Analog Out"}}
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Found match in Cards Database: setting mixer  for card Analog Out
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Setting mixer Master for card Analog Out
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: 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:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Disabling external Volume Control
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Preparing to generate the ALSA configuration file
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Reading ALSA contributions from plugins.
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: VolumeController:: Volume=41 Mute =false
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Asound.conf file written
Feb 22 23:19:19 volumio-x86-784 sudo[3691]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Feb 22 23:19:19 volumio-x86-784 sudo[3691]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:19 volumio-x86-784 sudo[3691]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Output device has changed, restarting MPD
Feb 22 23:19:19 volumio-x86-784 sudo[3696]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 22 23:19:19 volumio-x86-784 sudo[3696]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Output device has changed, restarting Shairport Sync
Feb 22 23:19:19 volumio-x86-784 sudo[3696]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:19 volumio-x86-784 sudo[3699]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 22 23:19:19 volumio-x86-784 sudo[3699]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Stopping Music Player Daemon...
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 22 23:19:19 volumio-x86-784 systemd[1]: mpd.service: Succeeded.
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Stopped Music Player Daemon.
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Output device has changed, restarting MPD
Feb 22 23:19:19 volumio-x86-784 sudo[3706]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Feb 22 23:19:19 volumio-x86-784 sudo[3706]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Output device has changed, restarting Shairport Sync
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: MPD Permissions set
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Starting Shairport Sync
Feb 22 23:19:19 volumio-x86-784 sudo[3706]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Starting Music Player Daemon...
Feb 22 23:19:19 volumio-x86-784 sudo[3709]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Feb 22 23:19:19 volumio-x86-784 sudo[3709]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Starting Shairport Sync
Feb 22 23:19:19 volumio-x86-784 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Feb 22 23:19:19 volumio-x86-784 systemd[1]: mpd.service: Succeeded.
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Stopped Music Player Daemon.
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Starting Music Player Daemon...
Feb 22 23:19:19 volumio-x86-784 sudo[3727]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 22 23:19:19 volumio-x86-784 sudo[3727]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Feb 22 23:19:19 volumio-x86-784 sudo[3731]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Feb 22 23:19:19 volumio-x86-784 sudo[3731]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:19 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded.
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:19 volumio-x86-784 sudo[3727]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Shairport-Sync Started
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Feb 22 23:19:19 volumio-x86-784 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Feb 22 23:19:19 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded.
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Feb 22 23:19:19 volumio-x86-784 sudo[3731]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Shairport-Sync Started
Feb 22 23:19:19 volumio-x86-784 sudo[3728]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Feb 22 23:19:19 volumio-x86-784 sudo[3728]: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb 22 23:19:19 volumio-x86-784 sudo[3728]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:19 volumio-x86-784 mpd[3738]: Feb 22 23:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 22 23:19:19 volumio-x86-784 systemd[1]: Started Music Player Daemon.
Feb 22 23:19:19 volumio-x86-784 sudo[3709]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:19 volumio-x86-784 sudo[3699]: pam_unix(sudo:session): session closed for user root
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: error: MPD error: The expression evaluated to a falsy value:
Feb 22 23:19:19 volumio-x86-784 volumio[3254]:   assert.ok(self.idling)
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: error: The expression evaluated to a falsy value:
Feb 22 23:19:19 volumio-x86-784 volumio[3254]:   assert.ok(self.idling)
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:19 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: AutoStart - Plugin is starting
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetQueue
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::getQueue
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getQueue
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: AutoStart - start playing
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: AutoStart - start playing with no specific position
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPlay
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index 0
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::stop
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index undefined
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::startPlaybackTimer
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::clearAddPlayTracks USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand stop
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: sendMpdCommand stop took 1 milliseconds
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand clear
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: sendMpdCommand clear took 0 milliseconds
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand add "USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3"
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: ------------------------------ 0ms
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: ------------------------------ 1ms
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPlay
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index undefined
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CoreStateMachine::startPlaybackTimer
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::clearAddPlayTracks USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand stop
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: sendMpdCommand stop took 0 milliseconds
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand clear
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: sendMpdCommand clear took 0 milliseconds
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand add "USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3"
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: ------------------------------ 0ms
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:22 volumio-x86-784 volumio[3254]: info: ------------------------------ 1ms
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreStateMachine::getcurrentVolume
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioRetrievevolume
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: VolumeController:: Volume=41 Mute =false
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreStateMachine::updateTrackBlock
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrackBlock
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioRetrievevolume
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: VolumeController:: Volume=41 Mute =false
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:23 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPlay
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index undefined
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: CoreStateMachine::startPlaybackTimer
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::clearAddPlayTracks USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand stop
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: sendMpdCommand stop took 0 milliseconds
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand clear
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: sendMpdCommand clear took 0 milliseconds
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand add "USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3"
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: ------------------------------ 1ms
Feb 22 23:19:24 volumio-x86-784 volumio[3254]: info: ------------------------------ 1ms
Feb 22 23:19:26 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:26 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:27 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:27 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPlay
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index undefined
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: CoreStateMachine::startPlaybackTimer
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::clearAddPlayTracks USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand stop
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: sendMpdCommand stop took 0 milliseconds
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand clear
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: sendMpdCommand clear took 1 milliseconds
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand add "USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3"
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: ------------------------------ 0ms
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:29 volumio-x86-784 volumio[3254]: info: ------------------------------ 0ms
Feb 22 23:19:32 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 22 23:19:32 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:32 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 22 23:19:32 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 22 23:19:32 volumio-x86-784 volumio-remote-updater[1019]: No test mode
Feb 22 23:19:32 volumio-x86-784 volumio-remote-updater[1019]: No alpha test mode
Feb 22 23:19:32 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Feb 22 23:19:32 volumio-x86-784 volumio[3254]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Feb 22 23:19:32 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:32 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: Completed starting MyVolumio Plugin
Feb 22 23:19:37 volumio-x86-784 volumio[3254]:  [Metrics]  CommandRouter:  38s 546.33ms 
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumiosetStartupVolume
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: VolumeController:: Setting startup Volume 10
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: VolumeController::SetAlsaVolume10
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::Close All Modals sent
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::Close All Modals sent
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreStateMachine::pushState
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 22 23:19:37 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPushState
Feb 22 23:19:38 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Feb 22 23:19:38 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Feb 22 23:19:38 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Feb 22 23:19:39 volumio-x86-784 volumio[3254]: info: Volumio called home
Feb 22 23:19:39 volumio-x86-784 volumio[3254]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES


Fix for lost MPD database after reboot
 
Fix for TIDAL authentication refresh
 
Fix TIDAL low quality (AAC) playback
 
Fix for DLNA Server discovery
Fix for Plugins installation


NEW ADDITIONS


Update Bluez to v5.83
 
Update TIDAL SDK
 
Add Bluetooth A2DP playback
 ","title":"Update v3.886","updateavailable":true}
Feb 22 23:19:39 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPlay
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index undefined
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: CoreStateMachine::startPlaybackTimer
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 0
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::clearAddPlayTracks USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand stop
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: sendMpdCommand stop took 0 milliseconds
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand clear
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: sendMpdCommand clear took 0 milliseconds
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand add "USB/云净U盘/酷我音乐3/Doar Cu Tine - Activ.mp3"
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: ------------------------------ 0ms
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: ------------------------------ 1ms
Feb 22 23:19:44 volumio-x86-784 volumio[3254]: info: BOOT COMPLETED
Feb 22 23:19:47 volumio-x86-784 volumio[3254]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/%E5%B1%B1%E6%AD%8C%E5%AF%A5%E5%93%89/6c147fe3-2729-432e-a82e-30d9aa3b8fa7.jpg'
Feb 22 23:19:47 volumio-x86-784 volumio[3254]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/%E6%8A%AB%E7%9D%80%E7%BE%8A%E7%9A%AE%E7%9A%84%E7%8B%BC/6272dabb-ee3c-49bf-b485-bce014efd4fb.jpg'
Feb 22 23:19:47 volumio-x86-784 volumio[3254]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%BA%84%E5%BF%83%E5%A6%8D/%E6%94%BE%E8%BF%87%E8%87%AA%E5%B7%B1/f789526d-b50a-4f6a-aec2-52526c0aa9c5.jpg'
Feb 22 23:19:47 volumio-x86-784 volumio[3254]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%A8%8B%E5%93%8D/%E6%97%B6%E5%85%89%E6%B4%AA%E6%B5%81/74cc6bfd-e2f7-4bfe-b0ed-ea6d134075f3.jpg'
Feb 22 23:19:47 volumio-x86-784 volumio[3254]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E5%85%8B%E5%8B%A4/%E6%9D%8E%E5%85%8B%E5%8B%A4%E6%9E%81%E5%93%81%E4%B9%8B%E9%80%89/585b3b65-c0c8-4bac-bb66-0550abac836a.jpg'
Feb 22 23:19:47 volumio-x86-784 volumio[3254]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%90%95%E6%96%B9/%E7%88%B1%E4%B8%80%E5%9B%9E%E4%BC%A4%E4%B8%80%E5%9B%9E/ec17bdd3-47c5-4e35-82f4-24d28e39ab19.jpg'
Feb 22 23:19:47 volumio-x86-784 volumio[3254]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
Feb 22 23:19:47 volumio-x86-784 volumio[3254]: info: Retrying Login Due to Network Error
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPlay
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index 7
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CoreStateMachine::stop
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index undefined
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 7
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CoreStateMachine::startPlaybackTimer
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 7
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::clearAddPlayTracks USB/云净U盘/酷我音乐3/一定会-林俊杰.320.mp3
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand stop
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: sendMpdCommand stop took 0 milliseconds
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand clear
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: sendMpdCommand clear took 0 milliseconds
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand add "USB/云净U盘/酷我音乐3/一定会-林俊杰.320.mp3"
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: ------------------------------ 1ms
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: ------------------------------ 2ms
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioGetState
Feb 22 23:19:48 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 7
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CoreCommandRouter::volumioPlay
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index 7
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CoreStateMachine::stop
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CoreStateMachine::play index undefined
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 7
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CoreStateMachine::startPlaybackTimer
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: CorePlayQueue::getTrack 7
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::clearAddPlayTracks USB/云净U盘/酷我音乐3/一定会-林俊杰.320.mp3
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand stop
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: sendMpdCommand stop took 0 milliseconds
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand clear
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: sendMpdCommand clear took 0 milliseconds
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: verbose: ControllerMpd::sendMpdCommand add "USB/云净U盘/酷我音乐3/一定会-林俊杰.320.mp3"
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: ------------------------------ 1ms
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info:
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: ---------------------------- MPD announces system playlist update
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: Ignoring MPD Status Update
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: error: updateQueue error: null
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: info: ------------------------------ 0ms
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: Error: connect ECONNREFUSED 23.101.24.70:443
Feb 22 23:19:49 volumio-x86-784 volumio[3254]:     at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
Feb 22 23:19:49 volumio-x86-784 volumio[3254]:   errno: -111,
Feb 22 23:19:49 volumio-x86-784 volumio[3254]:   code: 'ECONNREFUSED',
Feb 22 23:19:49 volumio-x86-784 volumio[3254]:   syscall: 'connect',
Feb 22 23:19:49 volumio-x86-784 volumio[3254]:   address: '23.101.24.70',
Feb 22 23:19:49 volumio-x86-784 volumio[3254]:   port: 443
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: }
Feb 22 23:19:49 volumio-x86-784 volumio[3254]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 22 23:19:49 volumio-x86-784 sudo[3835]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-22 23:18
Feb 22 23:19:49 volumio-x86-784 sudo[3835]: 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"