-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Wed 2025-12-10 11:44:24 CST. -- Dec 10 11:43:00 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPlay Dec 10 11:43:00 volumio-x86-784 volumio[990]: info: CoreStateMachine::play index undefined Dec 10 11:43:00 volumio-x86-784 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:43:00 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:00 volumio-x86-784 volumio[990]: info: CoreStateMachine::startPlaybackTimer Dec 10 11:43:00 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:00 volumio-x86-784 volumio[990]: info: ControllerMpd::resume Dec 10 11:43:00 volumio-x86-784 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Dec 10 11:43:00 volumio-x86-784 volumio[990]: info: sendMpdCommand play took 1 milliseconds Dec 10 11:43:01 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPlay Dec 10 11:43:01 volumio-x86-784 volumio[990]: info: CoreStateMachine::play index undefined Dec 10 11:43:01 volumio-x86-784 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:43:01 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:01 volumio-x86-784 volumio[990]: info: CoreStateMachine::startPlaybackTimer Dec 10 11:43:01 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:01 volumio-x86-784 volumio[990]: info: ControllerMpd::resume Dec 10 11:43:01 volumio-x86-784 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Dec 10 11:43:01 volumio-x86-784 volumio[990]: info: sendMpdCommand play took 1 milliseconds Dec 10 11:43:02 volumio-x86-784 volumio[990]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 10 11:43:02 volumio-x86-784 volumio[990]: info: Retrying Login Due to Network Error Dec 10 11:43:05 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:05 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:12 volumio-x86-784 volumio[990]: info: MyVolumio login type: Token Dec 10 11:43:14 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:14 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:16 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:16 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 11:43:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 11:43:27 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:27 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:29 volumio-x86-784 volumio[990]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 10 11:43:29 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 10 11:43:29 volumio-x86-784 volumio[990]: info: Preparing to save Alsa Options, stopping services first Dec 10 11:43:29 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:29 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:29 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPause Dec 10 11:43:29 volumio-x86-784 volumio[990]: info: CoreStateMachine::pause Dec 10 11:43:29 volumio-x86-784 volumio[990]: info: Saving Audio Output to: {"output_device":{"value":"7","label":"Dummy"}} Dec 10 11:43:29 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: Setting mixer Master for card Dummy Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: Updating Volume Controller Parameters: Device: 7 Name: Dummy Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: Disabling external Volume Control Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: Preparing to generate the ALSA configuration file Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: Reading ALSA contributions from plugins. Dec 10 11:43:30 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:30 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:30 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:30 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:30 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:30 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:30 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:30 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:30 volumio-x86-784 volumio[990]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 11:43:30 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:30 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:30 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:30 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:30 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:30 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:30 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:30 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=undefined Mute =false Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:43:30 volumio-x86-784 volumio[990]: info: Asound.conf file written Dec 10 11:43:30 volumio-x86-784 sudo[3231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 11:43:30 volumio-x86-784 sudo[3231]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:31 volumio-x86-784 sudo[3231]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: Output device has changed, restarting MPD Dec 10 11:43:31 volumio-x86-784 sudo[3239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 11:43:31 volumio-x86-784 sudo[3239]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:31 volumio-x86-784 sudo[3239]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: Output device has changed, restarting Shairport Sync Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:31 volumio-x86-784 sudo[3242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 11:43:31 volumio-x86-784 sudo[3242]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:31 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Dec 10 11:43:31 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Dec 10 11:43:31 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: Output device has changed, restarting MPD Dec 10 11:43:31 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Dec 10 11:43:31 volumio-x86-784 sudo[3249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 11:43:31 volumio-x86-784 sudo[3249]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:31 volumio-x86-784 sudo[3249]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: Output device has changed, restarting Shairport Sync Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:31 volumio-x86-784 sudo[3252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 11:43:31 volumio-x86-784 sudo[3252]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:31 volumio-x86-784 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 10 11:43:31 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Dec 10 11:43:31 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Dec 10 11:43:31 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Dec 10 11:43:31 volumio-x86-784 volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 11:43:31 volumio-x86-784 sudo[3257]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 11:43:31 volumio-x86-784 sudo[3257]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:31 volumio-x86-784 sudo[3257]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:32 volumio-x86-784 mpd[3259]: Dec 10 11:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 11:43:32 volumio-x86-784 systemd[1]: Started Music Player Daemon. Dec 10 11:43:32 volumio-x86-784 sudo[3242]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:32 volumio-x86-784 sudo[3252]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Preparing to save Alsa Options, stopping services first Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPause Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreStateMachine::pause Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Saving Audio Output to: {"output_device":{"value":"7","label":"Dummy"}} Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Setting mixer Master for card Dummy Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Updating Volume Controller Parameters: Device: 7 Name: Dummy Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Disabling external Volume Control Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Preparing to generate the ALSA configuration file Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: MPD Permissions set Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: MPD Permissions set Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Reading ALSA contributions from plugins. Dec 10 11:43:32 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:32 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:32 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:32 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:32 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:32 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:32 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:32 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:32 volumio-x86-784 volumio[990]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 10 11:43:32 volumio-x86-784 volumio[990]: error: MPD error: The expression evaluated to a falsy value: Dec 10 11:43:32 volumio-x86-784 volumio[990]: assert.ok(self.idling) Dec 10 11:43:32 volumio-x86-784 volumio[990]: error: The expression evaluated to a falsy value: Dec 10 11:43:32 volumio-x86-784 volumio[990]: assert.ok(self.idling) Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 11:43:32 volumio-x86-784 volumio[990]: error: updateQueue error: null Dec 10 11:43:32 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:32 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:32 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:32 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:32 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:32 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:32 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:32 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=undefined Mute =false Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Starting Shairport Sync Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Starting Shairport Sync Dec 10 11:43:32 volumio-x86-784 sudo[3289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 11:43:32 volumio-x86-784 sudo[3289]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Asound.conf file unchanged, so no further update is needed Dec 10 11:43:32 volumio-x86-784 volumio[990]: info: Output device has changed, restarting MPD Dec 10 11:43:32 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 10 11:43:32 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Dec 10 11:43:32 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 10 11:43:32 volumio-x86-784 sudo[3292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 11:43:32 volumio-x86-784 sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:32 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 10 11:43:33 volumio-x86-784 sudo[3296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 11:43:33 volumio-x86-784 sudo[3296]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:33 volumio-x86-784 sudo[3289]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: Output device has changed, restarting Shairport Sync Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 sudo[3296]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:33 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 10 11:43:33 volumio-x86-784 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 10 11:43:33 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Dec 10 11:43:33 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 10 11:43:33 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 10 11:43:33 volumio-x86-784 sudo[3299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 11:43:33 volumio-x86-784 sudo[3292]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:33 volumio-x86-784 sudo[3299]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:33 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Dec 10 11:43:33 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Dec 10 11:43:33 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: MPD Permissions set Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: Shairport-Sync Started Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: Shairport-Sync Started Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: Starting Shairport Sync Dec 10 11:43:33 volumio-x86-784 sudo[3306]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 11:43:33 volumio-x86-784 sudo[3306]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:33 volumio-x86-784 sudo[3306]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:33 volumio-x86-784 sudo[3315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 11:43:33 volumio-x86-784 sudo[3315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:43:33 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 10 11:43:33 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Dec 10 11:43:33 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 10 11:43:33 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 10 11:43:33 volumio-x86-784 sudo[3315]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:33 volumio-x86-784 volumio[990]: info: Shairport-Sync Started Dec 10 11:43:34 volumio-x86-784 mpd[3317]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 11:43:34 volumio-x86-784 mpd[3317]: output: No 'audio_output' defined in config file Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreStateMachine::getcurrentVolume Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 11:43:34 volumio-x86-784 mpd[3317]: output: Successfully detected a sndio audio device Dec 10 11:43:34 volumio-x86-784 mpd[3317]: zeroconf: No global port, disabling zeroconf Dec 10 11:43:34 volumio-x86-784 systemd[1]: Started Music Player Daemon. Dec 10 11:43:34 volumio-x86-784 sudo[3299]: pam_unix(sudo:session): session closed for user root Dec 10 11:43:34 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:34 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:34 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:34 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:34 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:34 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:34 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:34 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:34 volumio-x86-784 volumio[990]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 10 11:43:34 volumio-x86-784 volumio[990]: error: updateQueue error: null Dec 10 11:43:34 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:34 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:34 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:34 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:34 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:34 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:34 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:34 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=undefined Mute =false Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreStateMachine::updateTrackBlock Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrackBlock Dec 10 11:43:34 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 11:43:35 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:35 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:35 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:35 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:35 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:35 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:35 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:35 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:35 volumio-x86-784 volumio[990]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 10 11:43:35 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:35 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:35 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:35 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:35 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:35 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:35 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:35 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:35 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=undefined Mute =false Dec 10 11:43:35 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:35 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:35 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:43:35 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:35 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreStateMachine::getcurrentVolume Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 11:43:36 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:36 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:36 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:36 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:36 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:36 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:36 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:36 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:36 volumio-x86-784 volumio[990]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 10 11:43:36 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:36 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:36 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:36 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:36 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:36 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:36 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:36 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=undefined Mute =false Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreStateMachine::updateTrackBlock Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrackBlock Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 11:43:36 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:36 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:36 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:36 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:36 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:36 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:36 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:36 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:36 volumio-x86-784 volumio[990]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 10 11:43:36 volumio-x86-784 volumio[990]: Unable to parse: Dec 10 11:43:36 volumio-x86-784 volumio[990]: Simple mixer control 'Master',0 Dec 10 11:43:36 volumio-x86-784 volumio[990]: Capabilities: volume cswitch Dec 10 11:43:36 volumio-x86-784 volumio[990]: Playback channels: Front Left - Front Right Dec 10 11:43:36 volumio-x86-784 volumio[990]: Capture channels: Front Left - Front Right Dec 10 11:43:36 volumio-x86-784 volumio[990]: Limits: -50 - 100 Dec 10 11:43:36 volumio-x86-784 volumio[990]: Front Left: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:36 volumio-x86-784 volumio[990]: Front Right: 0 [17%] [-30.00dB] Capture [off] Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=undefined Mute =false Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:36 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:43:38 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:38 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:42 volumio-x86-784 volumio[990]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 10 11:43:42 volumio-x86-784 volumio[990]: info: Retrying Login Due to Network Error Dec 10 11:43:43 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:43 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:45 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPlay Dec 10 11:43:45 volumio-x86-784 volumio[990]: info: CoreStateMachine::play index undefined Dec 10 11:43:45 volumio-x86-784 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:43:45 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:45 volumio-x86-784 volumio[990]: info: CoreStateMachine::startPlaybackTimer Dec 10 11:43:45 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:45 volumio-x86-784 volumio[990]: info: ControllerMpd::resume Dec 10 11:43:45 volumio-x86-784 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Dec 10 11:43:45 volumio-x86-784 volumio[990]: info: sendMpdCommand play took 1 milliseconds Dec 10 11:43:49 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:43:49 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:52 volumio-x86-784 volumio[990]: info: MyVolumio login type: Token Dec 10 11:43:55 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 0 Dec 10 11:43:55 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:43:55 volumio-x86-784 volumio[990]: info: Prefetching next song Dec 10 11:43:55 volumio-x86-784 volumio[990]: info: DOING PREFETCH IN MPD Dec 10 11:43:55 volumio-x86-784 volumio[990]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/20-BEYOND专辑 42首/019.谁伴我闯荡-BEYOND.mp3" Dec 10 11:43:55 volumio-x86-784 mpd[3317]: exception: No database Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 11:43:58 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 11:44:00 volumio-x86-784 volumio[990]: info: CoreStateMachine::startPlaybackTimer Dec 10 11:44:00 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:00 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:44:00 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:00 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:44:00 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:44:00 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:00 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:10 volumio-x86-784 volumio[990]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 10 11:44:10 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 10 11:44:10 volumio-x86-784 volumio[990]: info: Preparing to save Alsa Options, stopping services first Dec 10 11:44:10 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:10 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:10 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPause Dec 10 11:44:10 volumio-x86-784 volumio[990]: info: CoreStateMachine::pause Dec 10 11:44:10 volumio-x86-784 volumio[990]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Dec 10 11:44:10 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Found match in Cards Database: setting mixer for card Analog Out Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Setting mixer Master for card Analog Out Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Disabling external Volume Control Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Preparing to generate the ALSA configuration file Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Reading ALSA contributions from plugins. Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=41 Mute =false Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Asound.conf file written Dec 10 11:44:11 volumio-x86-784 sudo[3488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 10 11:44:11 volumio-x86-784 sudo[3488]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:11 volumio-x86-784 sudo[3488]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Output device has changed, restarting MPD Dec 10 11:44:11 volumio-x86-784 sudo[3493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 11:44:11 volumio-x86-784 sudo[3493]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:11 volumio-x86-784 sudo[3493]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Output device has changed, restarting Shairport Sync Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:44:11 volumio-x86-784 sudo[3496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 11:44:11 volumio-x86-784 sudo[3496]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:11 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Dec 10 11:44:11 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Dec 10 11:44:11 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 11:44:11 volumio-x86-784 volumio[990]: info: Output device has changed, restarting MPD Dec 10 11:44:12 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Dec 10 11:44:12 volumio-x86-784 sudo[3503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 11:44:12 volumio-x86-784 sudo[3503]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:12 volumio-x86-784 sudo[3503]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:12 volumio-x86-784 volumio[990]: info: Output device has changed, restarting Shairport Sync Dec 10 11:44:12 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:12 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:44:12 volumio-x86-784 sudo[3510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 11:44:12 volumio-x86-784 sudo[3510]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:12 volumio-x86-784 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 10 11:44:12 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Dec 10 11:44:12 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Dec 10 11:44:12 volumio-x86-784 volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 11:44:12 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Dec 10 11:44:12 volumio-x86-784 sudo[3515]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 11:44:12 volumio-x86-784 sudo[3515]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:12 volumio-x86-784 sudo[3515]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:12 volumio-x86-784 mpd[3517]: Dec 10 11:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 11:44:12 volumio-x86-784 systemd[1]: Started Music Player Daemon. Dec 10 11:44:12 volumio-x86-784 sudo[3496]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:12 volumio-x86-784 sudo[3510]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Preparing to save Alsa Options, stopping services first Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPause Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreStateMachine::pause Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Found match in Cards Database: setting mixer for card Analog Out Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Setting mixer Master for card Analog Out Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Disabling external Volume Control Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Preparing to generate the ALSA configuration file Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: MPD Permissions set Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: MPD Permissions set Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Reading ALSA contributions from plugins. Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:13 volumio-x86-784 volumio[990]: error: MPD error: The expression evaluated to a falsy value: Dec 10 11:44:13 volumio-x86-784 volumio[990]: assert.ok(self.idling) Dec 10 11:44:13 volumio-x86-784 volumio[990]: error: The expression evaluated to a falsy value: Dec 10 11:44:13 volumio-x86-784 volumio[990]: assert.ok(self.idling) Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: error: updateQueue error: null Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=41 Mute =false Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Starting Shairport Sync Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Starting Shairport Sync Dec 10 11:44:13 volumio-x86-784 sudo[3550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 11:44:13 volumio-x86-784 sudo[3550]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Asound.conf file unchanged, so no further update is needed Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Output device has changed, restarting MPD Dec 10 11:44:13 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 10 11:44:13 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Dec 10 11:44:13 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 10 11:44:13 volumio-x86-784 sudo[3553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 11:44:13 volumio-x86-784 sudo[3553]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:13 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 10 11:44:13 volumio-x86-784 sudo[3550]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:13 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 10 11:44:13 volumio-x86-784 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 10 11:44:13 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Dec 10 11:44:13 volumio-x86-784 sudo[3557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 11:44:13 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 10 11:44:13 volumio-x86-784 sudo[3557]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:13 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 10 11:44:13 volumio-x86-784 sudo[3557]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:13 volumio-x86-784 sudo[3553]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Output device has changed, restarting Shairport Sync Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:44:13 volumio-x86-784 sudo[3561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 11:44:13 volumio-x86-784 sudo[3561]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:13 volumio-x86-784 systemd[1]: Stopping Music Player Daemon... Dec 10 11:44:13 volumio-x86-784 systemd[1]: mpd.service: Succeeded. Dec 10 11:44:13 volumio-x86-784 systemd[1]: Stopped Music Player Daemon. Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: MPD Permissions set Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Shairport-Sync Started Dec 10 11:44:13 volumio-x86-784 volumio[990]: (node:990) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 11:44:13 volumio-x86-784 volumio[990]: (node:990) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 11:44:13 volumio-x86-784 volumio[990]: (node:990) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 11:44:13 volumio-x86-784 volumio[990]: (node:990) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 11:44:13 volumio-x86-784 volumio[990]: (node:990) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Shairport-Sync Started Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 systemd[1]: Starting Music Player Daemon... Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Starting Shairport Sync Dec 10 11:44:13 volumio-x86-784 sudo[3568]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 11:44:13 volumio-x86-784 sudo[3568]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:13 volumio-x86-784 sudo[3568]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:13 volumio-x86-784 sudo[3577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 11:44:13 volumio-x86-784 sudo[3577]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:44:13 volumio-x86-784 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 10 11:44:13 volumio-x86-784 systemd[1]: shairport-sync.service: Succeeded. Dec 10 11:44:13 volumio-x86-784 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 10 11:44:13 volumio-x86-784 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 10 11:44:13 volumio-x86-784 sudo[3577]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:13 volumio-x86-784 volumio[990]: info: Shairport-Sync Started Dec 10 11:44:14 volumio-x86-784 mpd[3578]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 11:44:14 volumio-x86-784 mpd[3578]: output: No 'audio_output' defined in config file Dec 10 11:44:14 volumio-x86-784 mpd[3578]: output: Successfully detected a sndio audio device Dec 10 11:44:14 volumio-x86-784 mpd[3578]: zeroconf: No global port, disabling zeroconf Dec 10 11:44:14 volumio-x86-784 systemd[1]: Started Music Player Daemon. Dec 10 11:44:14 volumio-x86-784 sudo[3561]: pam_unix(sudo:session): session closed for user root Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreStateMachine::getcurrentVolume Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 11:44:15 volumio-x86-784 volumio[990]: error: updateQueue error: null Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=41 Mute =false Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreStateMachine::updateTrackBlock Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrackBlock Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=41 Mute =false Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:15 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreStateMachine::getcurrentVolume Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=41 Mute =false Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreStateMachine::updateTrackBlock Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrackBlock Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: VolumeController:: Volume=41 Mute =false Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreStateMachine::pushState Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:17 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPushState Dec 10 11:44:19 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPlay Dec 10 11:44:19 volumio-x86-784 volumio[990]: info: CoreStateMachine::play index undefined Dec 10 11:44:19 volumio-x86-784 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:44:19 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:19 volumio-x86-784 volumio[990]: info: CoreStateMachine::startPlaybackTimer Dec 10 11:44:19 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:19 volumio-x86-784 volumio[990]: info: ControllerMpd::resume Dec 10 11:44:19 volumio-x86-784 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Dec 10 11:44:19 volumio-x86-784 volumio[990]: info: sendMpdCommand play took 1 milliseconds Dec 10 11:44:20 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPlay Dec 10 11:44:20 volumio-x86-784 volumio[990]: info: CoreStateMachine::play index undefined Dec 10 11:44:20 volumio-x86-784 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:44:20 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:20 volumio-x86-784 volumio[990]: info: CoreStateMachine::startPlaybackTimer Dec 10 11:44:20 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:20 volumio-x86-784 volumio[990]: info: ControllerMpd::resume Dec 10 11:44:20 volumio-x86-784 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Dec 10 11:44:20 volumio-x86-784 volumio[990]: info: sendMpdCommand play took 0 milliseconds Dec 10 11:44:21 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioPlay Dec 10 11:44:21 volumio-x86-784 volumio[990]: info: CoreStateMachine::play index undefined Dec 10 11:44:21 volumio-x86-784 volumio[990]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:44:21 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:21 volumio-x86-784 volumio[990]: info: CoreStateMachine::startPlaybackTimer Dec 10 11:44:21 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:21 volumio-x86-784 volumio[990]: info: ControllerMpd::resume Dec 10 11:44:21 volumio-x86-784 volumio[990]: verbose: ControllerMpd::sendMpdCommand play Dec 10 11:44:21 volumio-x86-784 volumio[990]: info: sendMpdCommand play took 1 milliseconds Dec 10 11:44:22 volumio-x86-784 volumio[990]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 10 11:44:22 volumio-x86-784 volumio[990]: info: Retrying Login Due to Network Error Dec 10 11:44:24 volumio-x86-784 volumio[990]: info: CoreCommandRouter::volumioGetState Dec 10 11:44:24 volumio-x86-784 volumio[990]: info: CorePlayQueue::getTrack 8 Dec 10 11:44:24 volumio-x86-784 volumio[990]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 11:44:24 volumio-x86-784 volumio[990]: Error: connect ETIMEDOUT 199.96.58.177:443 Dec 10 11:44:24 volumio-x86-784 volumio[990]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 10 11:44:24 volumio-x86-784 volumio[990]: errno: -110, Dec 10 11:44:24 volumio-x86-784 volumio[990]: code: 'ETIMEDOUT', Dec 10 11:44:24 volumio-x86-784 volumio[990]: syscall: 'connect', Dec 10 11:44:24 volumio-x86-784 volumio[990]: address: '199.96.58.177', Dec 10 11:44:24 volumio-x86-784 volumio[990]: port: 443 Dec 10 11:44:24 volumio-x86-784 volumio[990]: } Dec 10 11:44:24 volumio-x86-784 volumio[990]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 11:44:24 volumio-x86-784 sudo[3624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-10 11:43 Dec 10 11:44:24 volumio-x86-784 sudo[3624]: 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"