-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sun 2025-09-14 08:10:50 CST. -- Sep 14 08:09:00 volumio volumio[8101]: info: CoreStateMachine::pushState Sep 14 08:09:00 volumio volumio[8101]: info: CorePlayQueue::getTrack 3 Sep 14 08:09:00 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:09:00 volumio volumio[8101]: info: CoreCommandRouter::volumioPushState Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 14 08:09:13 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 08:09:18 volumio volumio[8101]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 14 08:09:18 volumio volumio[8101]: info: Retrying Login Due to Network Error Sep 14 08:09:28 volumio volumio[8101]: info: MyVolumio login type: Token Sep 14 08:09:41 volumio volumio[8101]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::volumioGetState Sep 14 08:09:41 volumio volumio[8101]: info: CorePlayQueue::getTrack 3 Sep 14 08:09:41 volumio volumio[8101]: info: Restoring Previous Volume level: 58 false true Sep 14 08:09:41 volumio volumio[8101]: info: VolumeController::SetAlsaVolume100 Sep 14 08:09:41 volumio volumio[8101]: info: Enable softmixer device for audio device number 5 Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::volumioStop Sep 14 08:09:41 volumio volumio[8101]: info: CoreStateMachine::stop Sep 14 08:09:41 volumio volumio[8101]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 08:09:41 volumio volumio[8101]: info: CoreStateMachine::stPlaybackTimer Sep 14 08:09:41 volumio volumio[8101]: info: CoreStateMachine::updateTrackBlock Sep 14 08:09:41 volumio volumio[8101]: info: CorePlayQueue::getTrackBlock Sep 14 08:09:41 volumio volumio[8101]: info: CoreStateMachine::pushState Sep 14 08:09:41 volumio volumio[8101]: info: CorePlayQueue::getTrack 3 Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::volumioPushState Sep 14 08:09:41 volumio volumio[8101]: info: CoreStateMachine::serviceStop Sep 14 08:09:41 volumio volumio[8101]: info: CorePlayQueue::getTrack 3 Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::serviceStop Sep 14 08:09:41 volumio volumio[8101]: info: ControllerMpd::stop Sep 14 08:09:41 volumio volumio[8101]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 08:09:41 volumio volumio[8101]: info: Enable softmixer device for audio device undefined Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 14 08:09:41 volumio volumio[8101]: info: Updating Volume Controller Parameters: Device: 5 Name: LE AUDIO Transmitter Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 14 08:09:41 volumio volumio[8101]: info: Disabling external Volume Control Sep 14 08:09:41 volumio volumio[8101]: info: Output device has changed, restarting MPD Sep 14 08:09:41 volumio sudo[8571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:09:41 volumio sudo[8571]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:41 volumio sudo[8571]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:41 volumio volumio[8101]: info: Output device has changed, restarting Shairport Sync Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:41 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:09:41 volumio sudo[8574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:09:41 volumio sudo[8574]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:41 volumio systemd[1]: Stopping Music Player Daemon... Sep 14 08:09:41 volumio volumio[8101]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:09:41 volumio volumio[8101]: info: Volume configurations have been set Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 14 08:09:42 volumio volumio[8101]: info: Updating Volume Controller Parameters: Device: 5 Name: LE AUDIO Transmitter Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 14 08:09:42 volumio volumio[8101]: info: Disabling external Volume Control Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 08:09:42 volumio volumio[8101]: info: CoreStateMachine::pushState Sep 14 08:09:42 volumio volumio[8101]: info: CorePlayQueue::getTrack 3 Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::volumioPushState Sep 14 08:09:42 volumio volumio[8101]: info: CoreStateMachine::pushState Sep 14 08:09:42 volumio volumio[8101]: info: CorePlayQueue::getTrack 3 Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::volumioPushState Sep 14 08:09:42 volumio volumio[8101]: info: MPD Permissions set Sep 14 08:09:42 volumio volumio[8101]: info: Software Volume ALSA configuration written Sep 14 08:09:42 volumio volumio[8101]: info: Preparing to generate the ALSA configuration file Sep 14 08:09:42 volumio volumio[8101]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 14 08:09:42 volumio volumio[8101]: info: Reading ALSA contributions from plugins. Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio systemd[1]: mpd.service: Succeeded. Sep 14 08:09:42 volumio systemd[1]: Stopped Music Player Daemon. Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 08:09:42 volumio volumio[8101]: error: MPD error: Error: read ECONNRESET Sep 14 08:09:42 volumio volumio[8101]: info: VolumeController:: Volume=100 Mute =false Sep 14 08:09:42 volumio volumio[8101]: info: CoreStateMachine::pushState Sep 14 08:09:42 volumio volumio[8101]: info: CorePlayQueue::getTrack 3 Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::volumioPushState Sep 14 08:09:42 volumio systemd[1]: Starting Music Player Daemon... Sep 14 08:09:42 volumio volumio[8101]: info: Starting Shairport Sync Sep 14 08:09:42 volumio volumio[8101]: info: Asound.conf file written Sep 14 08:09:42 volumio sudo[8599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:09:42 volumio sudo[8599]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:42 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:09:42 volumio sudo[8602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 14 08:09:42 volumio sudo[8602]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:42 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:09:42 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:09:42 volumio sudo[8595]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:09:42 volumio sudo[8595]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:42 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:09:42 volumio sudo[8602]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:42 volumio sudo[8599]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:42 volumio sudo[8595]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:42 volumio volumio[8101]: info: Output device has changed, restarting MPD Sep 14 08:09:42 volumio sudo[8612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:09:42 volumio sudo[8612]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:42 volumio volumio[8101]: info: Output device has changed, restarting Shairport Sync Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:42 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:09:42 volumio sudo[8612]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:42 volumio sudo[8615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:09:42 volumio sudo[8615]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:42 volumio volumio[8101]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:09:42 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 14 08:09:42 volumio systemd[1]: mpd.service: Succeeded. Sep 14 08:09:42 volumio systemd[1]: Stopped Music Player Daemon. Sep 14 08:09:42 volumio systemd[1]: Starting Music Player Daemon... Sep 14 08:09:42 volumio sudo[8620]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:09:42 volumio sudo[8620]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:42 volumio sudo[8620]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:42 volumio mpd[8624]: Sep 14 08:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:09:42 volumio systemd[1]: Started Music Player Daemon. Sep 14 08:09:42 volumio sudo[8574]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:42 volumio sudo[8615]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:43 volumio volumio[8101]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Sep 14 08:09:43 volumio volumio[8101]: Found hardware: "USB-Audio" "USB Mixer" "USB0a12:4007" "" "" Sep 14 08:09:43 volumio volumio[8101]: Hardware is initialized using a generic method Sep 14 08:09:43 volumio volumio[8101]: info: Output device has changed, restarting MPD Sep 14 08:09:43 volumio sudo[8630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:09:43 volumio sudo[8630]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:43 volumio volumio[8101]: info: Output device has changed, restarting Shairport Sync Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:09:43 volumio sudo[8630]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:43 volumio sudo[8633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:09:43 volumio sudo[8633]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:43 volumio systemd[1]: Stopping Music Player Daemon... Sep 14 08:09:43 volumio volumio[8101]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 14 08:09:43 volumio systemd[1]: mpd.service: Succeeded. Sep 14 08:09:43 volumio systemd[1]: Stopped Music Player Daemon. Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 14 08:09:43 volumio volumio[8101]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 14 08:09:43 volumio systemd[1]: Starting Music Player Daemon... Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 14 08:09:43 volumio volumio[8101]: info: Disabling external Volume Control Sep 14 08:09:43 volumio volumio[8101]: info: MPD Permissions set Sep 14 08:09:43 volumio volumio[8101]: info: MPD Permissions set Sep 14 08:09:43 volumio volumio[8101]: info: Shairport-Sync Started Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio sudo[8639]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio sudo[8639]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:43 volumio sudo[8639]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:09:43 volumio volumio[8101]: info: CoreStateMachine::pushState Sep 14 08:09:43 volumio volumio[8101]: info: CorePlayQueue::getTrack 3 Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:09:43 volumio volumio[8101]: info: CoreCommandRouter::volumioPushState Sep 14 08:09:43 volumio volumio[8101]: info: Starting Shairport Sync Sep 14 08:09:43 volumio volumio[8101]: info: Starting Shairport Sync Sep 14 08:09:43 volumio sudo[8659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:09:43 volumio sudo[8659]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:43 volumio sudo[8662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 14 08:09:43 volumio sudo[8662]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:09:43 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:09:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:09:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:09:43 volumio sudo[8659]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 14 08:09:43 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 14 08:09:43 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 14 08:09:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 14 08:09:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 14 08:09:43 volumio sudo[8662]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:43 volumio volumio[8101]: info: Shairport-Sync Started Sep 14 08:09:43 volumio volumio[8101]: info: Shairport-Sync Started Sep 14 08:09:43 volumio mpd[8654]: Sep 14 08:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:09:43 volumio systemd[1]: Started Music Player Daemon. Sep 14 08:09:43 volumio volumio[8101]: error: MPD error: The expression evaluated to a falsy value: Sep 14 08:09:43 volumio volumio[8101]: assert.ok(self.idling) Sep 14 08:09:43 volumio volumio[8101]: error: The expression evaluated to a falsy value: Sep 14 08:09:43 volumio volumio[8101]: assert.ok(self.idling) Sep 14 08:09:43 volumio sudo[8633]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:43 volumio volumio[8101]: error: updateQueue error: null Sep 14 08:09:43 volumio volumio[8101]: error: updateQueue error: null Sep 14 08:09:45 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Sep 14 08:09:45 volumio sudo[8671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:09:45 volumio sudo[8671]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:45 volumio systemd[1]: Stopping Music Player Daemon... Sep 14 08:09:45 volumio systemd[1]: mpd.service: Succeeded. Sep 14 08:09:45 volumio systemd[1]: Stopped Music Player Daemon. Sep 14 08:09:45 volumio systemd[1]: Starting Music Player Daemon... Sep 14 08:09:45 volumio sudo[8673]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:09:45 volumio sudo[8673]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:09:45 volumio sudo[8673]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:45 volumio mpd[8675]: Sep 14 08:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:09:45 volumio systemd[1]: Started Music Player Daemon. Sep 14 08:09:45 volumio sudo[8671]: pam_unix(sudo:session): session closed for user root Sep 14 08:09:45 volumio volumio[8101]: error: updateQueue error: null Sep 14 08:09:46 volumio volumio[8101]: info: VolumeController::SetAlsaVolume58 Sep 14 08:09:46 volumio volumio[8101]: info: CoreStateMachine::pushState Sep 14 08:09:46 volumio volumio[8101]: info: CorePlayQueue::getTrack 3 Sep 14 08:09:46 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:09:46 volumio volumio[8101]: info: CoreCommandRouter::volumioPushState Sep 14 08:09:47 volumio volumio[8101]: error: updateQueue error: null Sep 14 08:09:58 volumio volumio[8101]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 14 08:09:58 volumio volumio[8101]: info: Retrying Login Due to Network Error Sep 14 08:10:01 volumio volumio[8101]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Sep 14 08:10:01 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Sep 14 08:10:01 volumio sudo[8713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:10:01 volumio sudo[8713]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:10:01 volumio sudo[8713]: pam_unix(sudo:session): session closed for user root Sep 14 08:10:01 volumio volumio[8101]: info: MPD Permissions set Sep 14 08:10:01 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:01 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:01 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:01 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:01 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:01 volumio sudo[8716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:10:01 volumio sudo[8716]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:10:01 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:10:01 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:01 volumio systemd[1]: Stopping Music Player Daemon... Sep 14 08:10:01 volumio systemd[1]: mpd.service: Succeeded. Sep 14 08:10:01 volumio systemd[1]: Stopped Music Player Daemon. Sep 14 08:10:01 volumio systemd[1]: Starting Music Player Daemon... Sep 14 08:10:02 volumio sudo[8722]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:10:02 volumio sudo[8722]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:10:02 volumio sudo[8722]: pam_unix(sudo:session): session closed for user root Sep 14 08:10:02 volumio mpd[8724]: Sep 14 08:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:10:02 volumio systemd[1]: Started Music Player Daemon. Sep 14 08:10:02 volumio sudo[8716]: pam_unix(sudo:session): session closed for user root Sep 14 08:10:02 volumio volumio[8101]: error: updateQueue error: null Sep 14 08:10:08 volumio volumio[8101]: info: MyVolumio login type: Token Sep 14 08:10:14 volumio volumio[8101]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Sep 14 08:10:14 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Sep 14 08:10:14 volumio sudo[8746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 08:10:14 volumio sudo[8746]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:10:14 volumio sudo[8746]: pam_unix(sudo:session): session closed for user root Sep 14 08:10:14 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:14 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:14 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:14 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:14 volumio sudo[8749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 08:10:14 volumio sudo[8749]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:10:14 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 08:10:14 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 08:10:14 volumio volumio[8101]: info: MPD Permissions set Sep 14 08:10:14 volumio systemd[1]: Stopping Music Player Daemon... Sep 14 08:10:15 volumio systemd[1]: mpd.service: Succeeded. Sep 14 08:10:15 volumio systemd[1]: Stopped Music Player Daemon. Sep 14 08:10:15 volumio systemd[1]: Starting Music Player Daemon... Sep 14 08:10:15 volumio sudo[8755]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 14 08:10:15 volumio sudo[8755]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 08:10:15 volumio sudo[8755]: pam_unix(sudo:session): session closed for user root Sep 14 08:10:15 volumio mpd[8758]: Sep 14 08:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 14 08:10:15 volumio systemd[1]: Started Music Player Daemon. Sep 14 08:10:15 volumio sudo[8749]: pam_unix(sudo:session): session closed for user root Sep 14 08:10:15 volumio volumio[8101]: error: updateQueue error: null Sep 14 08:10:38 volumio volumio[8101]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 14 08:10:38 volumio volumio[8101]: info: Retrying Login Due to Network Error Sep 14 08:10:48 volumio volumio[8101]: info: CoreCommandRouter::volumioPlay Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::play index 4 Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::stop Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::play index undefined Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 08:10:48 volumio volumio[8101]: info: CorePlayQueue::getTrack 4 Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::startPlaybackTimer Sep 14 08:10:48 volumio volumio[8101]: info: CorePlayQueue::getTrack 4 Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::clearAddPlayTracks USB/607C-06C7/2660192718某9038.wav Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 08:10:48 volumio volumio[8101]: info: sendMpdCommand stop took 0 milliseconds Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 08:10:48 volumio volumio[8101]: info: Sep 14 08:10:48 volumio volumio[8101]: ---------------------------- MPD announces system playlist update Sep 14 08:10:48 volumio volumio[8101]: info: Ignoring MPD Status Update Sep 14 08:10:48 volumio volumio[8101]: info: sendMpdCommand clear took 1 milliseconds Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::sendMpdCommand add "USB/607C-06C7/2660192718某9038.wav" Sep 14 08:10:48 volumio volumio[8101]: error: updateQueue error: null Sep 14 08:10:48 volumio volumio[8101]: info: Sep 14 08:10:48 volumio volumio[8101]: ---------------------------- MPD announces system playlist update Sep 14 08:10:48 volumio volumio[8101]: info: Ignoring MPD Status Update Sep 14 08:10:48 volumio volumio[8101]: info: ------------------------------ 1ms Sep 14 08:10:48 volumio volumio[8101]: info: sendMpdCommand add "USB/607C-06C7/2660192718某9038.wav" took 1 milliseconds Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::sendMpdCommand play Sep 14 08:10:48 volumio volumio[8101]: info: ------------------------------ 1ms Sep 14 08:10:48 volumio volumio[8101]: info: sendMpdCommand play took 1 milliseconds Sep 14 08:10:48 volumio volumio[8101]: info: Sep 14 08:10:48 volumio volumio[8101]: ---------------------------- MPD announces state update: player Sep 14 08:10:48 volumio volumio[8101]: info: ControllerMpd::getState Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::sendMpdCommand status Sep 14 08:10:48 volumio volumio[8101]: info: Sep 14 08:10:48 volumio volumio[8101]: ---------------------------- MPD announces state update: player Sep 14 08:10:48 volumio volumio[8101]: info: sendMpdCommand status took 123 milliseconds Sep 14 08:10:48 volumio volumio[8101]: info: ControllerMpd::getState Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::sendMpdCommand status Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::parseState Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 08:10:48 volumio volumio[8101]: info: sendMpdCommand status took 1 milliseconds Sep 14 08:10:48 volumio volumio[8101]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::parseState Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::parseTrackInfo Sep 14 08:10:48 volumio volumio[8101]: info: ControllerMpd::pushState Sep 14 08:10:48 volumio volumio[8101]: info: CoreCommandRouter::servicePushState Sep 14 08:10:48 volumio volumio[8101]: info: CorePlayQueue::getTrack 4 Sep 14 08:10:48 volumio volumio[8101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2660192718某9038.wav","artist":null,"album":null,"uri":"USB/607C-06C7/2660192718某9038.wav","trackType":"wav"} Sep 14 08:10:48 volumio volumio[8101]: verbose: CURRENT POSITION 4 Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::syncState stateService play Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::syncState currentStatus stop Sep 14 08:10:48 volumio volumio[8101]: info: ------------------------------ 126ms Sep 14 08:10:48 volumio volumio[8101]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 08:10:48 volumio volumio[8101]: verbose: ControllerMpd::parseTrackInfo Sep 14 08:10:48 volumio volumio[8101]: info: ControllerMpd::pushState Sep 14 08:10:48 volumio volumio[8101]: info: CoreCommandRouter::servicePushState Sep 14 08:10:48 volumio volumio[8101]: info: CorePlayQueue::getTrack 4 Sep 14 08:10:48 volumio volumio[8101]: verbose: STATE SERVICE {"status":"play","position":0,"seek":682,"duration":223,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2660192718某9038.wav","artist":null,"album":null,"uri":"USB/607C-06C7/2660192718某9038.wav","trackType":"wav"} Sep 14 08:10:48 volumio volumio[8101]: verbose: CURRENT POSITION 4 Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::syncState stateService play Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::syncState currentStatus play Sep 14 08:10:48 volumio volumio[8101]: info: Received an update from plugin. extracting info from payload Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::pushState Sep 14 08:10:48 volumio volumio[8101]: info: CorePlayQueue::getTrack 4 Sep 14 08:10:48 volumio volumio[8101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 08:10:48 volumio volumio[8101]: info: CoreCommandRouter::volumioPushState Sep 14 08:10:48 volumio volumio[8101]: info: CoreStateMachine::pushState Sep 14 08:10:48 volumio volumio[8101]: info: CorePlayQueue::getTrack 4 Sep 14 08:10:48 volumio volumio[8101]: info: CoreCommandRouter::volumioPushState Sep 14 08:10:48 volumio volumio[8101]: info: ------------------------------ 11ms Sep 14 08:10:48 volumio volumio[8101]: info: MyVolumio login type: Token Sep 14 08:10:49 volumio volumio[8101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:10:49 volumio volumio[8101]: Error: connect ETIMEDOUT 104.244.43.182:443 Sep 14 08:10:49 volumio volumio[8101]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 14 08:10:49 volumio volumio[8101]: errno: -110, Sep 14 08:10:49 volumio volumio[8101]: code: 'ETIMEDOUT', Sep 14 08:10:49 volumio volumio[8101]: syscall: 'connect', Sep 14 08:10:49 volumio volumio[8101]: address: '104.244.43.182', Sep 14 08:10:49 volumio volumio[8101]: port: 443 Sep 14 08:10:49 volumio volumio[8101]: } Sep 14 08:10:49 volumio volumio[8101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 08:10:50 volumio sudo[8875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 08:09 Sep 14 08:10:50 volumio sudo[8875]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"