-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Tue 2025-04-22 11:38:17 CST. -- Apr 22 11:37:17 volumio volumio[1124]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::volumioGetState Apr 22 11:37:17 volumio volumio[1124]: info: Restoring Previous Volume level: 35 false true Apr 22 11:37:17 volumio volumio[1124]: info: VolumeController::SetAlsaVolume100 Apr 22 11:37:17 volumio volumio[1124]: info: Enable softmixer device for audio device number 5 Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::volumioStop Apr 22 11:37:17 volumio volumio[1124]: info: CoreStateMachine::stop Apr 22 11:37:17 volumio volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 11:37:17 volumio volumio[1124]: info: CoreStateMachine::stPlaybackTimer Apr 22 11:37:17 volumio volumio[1124]: info: CoreStateMachine::updateTrackBlock Apr 22 11:37:17 volumio volumio[1124]: info: CorePlayQueue::getTrackBlock Apr 22 11:37:17 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:17 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:17 volumio volumio[1124]: info: CoreStateMachine::serviceStop Apr 22 11:37:17 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::serviceStop Apr 22 11:37:17 volumio volumio[1124]: info: [1745293037263] ControllerWebradio::stop Apr 22 11:37:17 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 11:37:17 volumio volumio[1124]: info: Enable softmixer device for audio device undefined Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 22 11:37:17 volumio volumio[1124]: info: Updating Volume Controller Parameters: Device: 5 Name: LE AUDIO Transmitter Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 22 11:37:17 volumio volumio[1124]: info: Disabling external Volume Control Apr 22 11:37:17 volumio volumio[1124]: info: Output device has changed, restarting MPD Apr 22 11:37:17 volumio sudo[2386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 11:37:17 volumio volumio[1124]: info: Output device has changed, restarting Shairport Sync Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio sudo[2386]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:37:17 volumio sudo[2386]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:17 volumio sudo[2388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 11:37:17 volumio sudo[2388]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:17 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 11:37:17 volumio volumio[1124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 11:37:17 volumio volumio[1124]: info: Volume configurations have been set Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 22 11:37:17 volumio volumio[1124]: info: Updating Volume Controller Parameters: Device: 5 Name: LE AUDIO Transmitter Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 22 11:37:17 volumio volumio[1124]: info: Disabling external Volume Control Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 11:37:17 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:17 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:17 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:17 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:17 volumio volumio[1124]: info: MPD Permissions set Apr 22 11:37:17 volumio volumio[1124]: error: MPD error: Error: read ECONNRESET Apr 22 11:37:17 volumio volumio[1124]: info: Software Volume ALSA configuration written Apr 22 11:37:17 volumio volumio[1124]: info: Preparing to generate the ALSA configuration file Apr 22 11:37:17 volumio systemd[1]: mpd.service: Succeeded. Apr 22 11:37:17 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 11:37:17 volumio volumio[1124]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 22 11:37:17 volumio volumio[1124]: info: Reading ALSA contributions from plugins. Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio systemd[1]: Starting Music Player Daemon... Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 22 11:37:17 volumio sudo[2404]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 22 11:37:17 volumio sudo[2404]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:17 volumio sudo[2404]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:17 volumio volumio[1124]: info: VolumeController:: Volume=100 Mute =false Apr 22 11:37:17 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:17 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:17 volumio volumio[1124]: info: Starting Shairport Sync Apr 22 11:37:17 volumio volumio[1124]: info: Asound.conf file written Apr 22 11:37:17 volumio sudo[2417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 11:37:17 volumio sudo[2417]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 11:37:17 volumio sudo[2420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 22 11:37:17 volumio sudo[2420]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:17 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 11:37:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 11:37:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 11:37:17 volumio sudo[2417]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:17 volumio sudo[2420]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:17 volumio volumio[1124]: info: Output device has changed, restarting MPD Apr 22 11:37:17 volumio sudo[2428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 11:37:17 volumio sudo[2428]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:17 volumio volumio[1124]: info: Output device has changed, restarting Shairport Sync Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:17 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:37:17 volumio sudo[2428]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:17 volumio sudo[2431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 11:37:17 volumio sudo[2431]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:17 volumio volumio[1124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 11:37:17 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 22 11:37:17 volumio systemd[1]: mpd.service: Succeeded. Apr 22 11:37:17 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 11:37:17 volumio systemd[1]: Starting Music Player Daemon... Apr 22 11:37:17 volumio sudo[2437]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 11:37:17 volumio sudo[2437]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:17 volumio sudo[2437]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:17 volumio mpd[2441]: Apr 22 11:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 11:37:17 volumio systemd[1]: Started Music Player Daemon. Apr 22 11:37:17 volumio sudo[2431]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:17 volumio sudo[2388]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:18 volumio volumio[1124]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 22 11:37:18 volumio volumio[1124]: Found hardware: "USB-Audio" "USB Mixer" "USB0a12:4007" "" "" Apr 22 11:37:18 volumio volumio[1124]: Hardware is initialized using a generic method Apr 22 11:37:18 volumio volumio[1124]: info: Output device has changed, restarting MPD Apr 22 11:37:18 volumio sudo[2448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 11:37:18 volumio volumio[1124]: info: Output device has changed, restarting Shairport Sync Apr 22 11:37:18 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:18 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:37:18 volumio sudo[2448]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:18 volumio sudo[2448]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:18 volumio sudo[2450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 11:37:18 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:18 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 11:37:18 volumio volumio[1124]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 11:37:18 volumio systemd[1]: mpd.service: Succeeded. Apr 22 11:37:18 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 11:37:18 volumio volumio[1124]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 22 11:37:18 volumio volumio[1124]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 22 11:37:18 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 22 11:37:18 volumio volumio[1124]: info: Disabling external Volume Control Apr 22 11:37:18 volumio volumio[1124]: info: MPD Permissions set Apr 22 11:37:18 volumio systemd[1]: Starting Music Player Daemon... Apr 22 11:37:18 volumio volumio[1124]: info: MPD Permissions set Apr 22 11:37:18 volumio volumio[1124]: info: Shairport-Sync Started Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 11:37:19 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:19 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:19 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:19 volumio sudo[2457]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 11:37:19 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:19 volumio volumio[1124]: info: Starting Shairport Sync Apr 22 11:37:19 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:19 volumio volumio[1124]: info: Starting Shairport Sync Apr 22 11:37:19 volumio sudo[2478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 11:37:19 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:19 volumio sudo[2481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 11:37:19 volumio sudo[2481]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 11:37:19 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 11:37:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 11:37:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 11:37:19 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:19 volumio sudo[2481]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:19 volumio volumio[1124]: info: Shairport-Sync Started Apr 22 11:37:19 volumio mpd[2476]: Apr 22 11:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 11:37:19 volumio systemd[1]: Started Music Player Daemon. Apr 22 11:37:19 volumio volumio[1124]: error: MPD error: The expression evaluated to a falsy value: Apr 22 11:37:19 volumio volumio[1124]: assert.ok(self.idling) Apr 22 11:37:19 volumio volumio[1124]: error: The expression evaluated to a falsy value: Apr 22 11:37:19 volumio volumio[1124]: assert.ok(self.idling) Apr 22 11:37:19 volumio volumio[1124]: error: updateQueue error: null Apr 22 11:37:19 volumio sudo[2450]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:19 volumio volumio[1124]: error: updateQueue error: null Apr 22 11:37:20 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 22 11:37:20 volumio sudo[2504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 11:37:20 volumio sudo[2504]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:20 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 11:37:20 volumio systemd[1]: mpd.service: Succeeded. Apr 22 11:37:20 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 11:37:20 volumio systemd[1]: Starting Music Player Daemon... Apr 22 11:37:20 volumio sudo[2506]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 11:37:20 volumio sudo[2506]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 11:37:20 volumio sudo[2506]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:20 volumio mpd[2508]: Apr 22 11:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 11:37:20 volumio systemd[1]: Started Music Player Daemon. Apr 22 11:37:20 volumio sudo[2504]: pam_unix(sudo:session): session closed for user root Apr 22 11:37:20 volumio volumio[1124]: error: updateQueue error: null Apr 22 11:37:21 volumio volumio[1124]: info: VolumeController::SetAlsaVolume35 Apr 22 11:37:21 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:21 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:21 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:21 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:22 volumio volumio[1124]: error: updateQueue error: null Apr 22 11:37:27 volumio volumio[1124]: info: CoreCommandRouter::volumioPlay Apr 22 11:37:27 volumio volumio[1124]: info: CoreStateMachine::play index undefined Apr 22 11:37:27 volumio volumio[1124]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 11:37:27 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:27 volumio volumio[1124]: info: CoreStateMachine::startPlaybackTimer Apr 22 11:37:27 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:27 volumio volumio[1124]: info: [1745293047053] ControllerWebradio::clearAddPlayTrack Apr 22 11:37:27 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 11:37:27 volumio volumio[1124]: info: sendMpdCommand stop took 0 milliseconds Apr 22 11:37:27 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand clear Apr 22 11:37:27 volumio volumio[1124]: info: Apr 22 11:37:27 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Apr 22 11:37:27 volumio volumio[1124]: info: Ignoring MPD Status Update Apr 22 11:37:27 volumio volumio[1124]: info: sendMpdCommand clear took 1 milliseconds Apr 22 11:37:27 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576270" Apr 22 11:37:27 volumio volumio[1124]: info: Apr 22 11:37:27 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Apr 22 11:37:27 volumio volumio[1124]: info: Ignoring MPD Status Update Apr 22 11:37:27 volumio volumio[1124]: error: updateQueue error: null Apr 22 11:37:27 volumio volumio[1124]: info: ------------------------------ 1ms Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99576270" took 1304 milliseconds Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand play Apr 22 11:37:28 volumio volumio[1124]: info: Apr 22 11:37:28 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Apr 22 11:37:28 volumio volumio[1124]: info: Ignoring MPD Status Update Apr 22 11:37:28 volumio volumio[1124]: info: ------------------------------ 1305ms Apr 22 11:37:28 volumio volumio[1124]: info: Apr 22 11:37:28 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Apr 22 11:37:28 volumio volumio[1124]: info: Ignoring MPD Status Update Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand play took 1 milliseconds Apr 22 11:37:28 volumio volumio[1124]: info: ------------------------------ 1ms Apr 22 11:37:28 volumio volumio[1124]: info: ------------------------------ 1ms Apr 22 11:37:28 volumio volumio[1124]: info: Apr 22 11:37:28 volumio volumio[1124]: ---------------------------- MPD announces state update: player Apr 22 11:37:28 volumio volumio[1124]: info: ControllerMpd::getState Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Apr 22 11:37:28 volumio volumio[1124]: info: Apr 22 11:37:28 volumio volumio[1124]: ---------------------------- MPD announces state update: player Apr 22 11:37:28 volumio volumio[1124]: info: ControllerMpd::getState Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Apr 22 11:37:28 volumio volumio[1124]: info: Apr 22 11:37:28 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Apr 22 11:37:28 volumio volumio[1124]: info: Ignoring MPD Status Update Apr 22 11:37:28 volumio volumio[1124]: info: Apr 22 11:37:28 volumio volumio[1124]: ---------------------------- MPD announces state update: player Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand status took 132 milliseconds Apr 22 11:37:28 volumio volumio[1124]: info: ControllerMpd::getState Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand status took 132 milliseconds Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::parseState Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::parseState Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 11:37:28 volumio volumio[1124]: info: Apr 22 11:37:28 volumio volumio[1124]: ---------------------------- MPD announces system playlist update Apr 22 11:37:28 volumio volumio[1124]: info: Ignoring MPD Status Update Apr 22 11:37:28 volumio volumio[1124]: info: Apr 22 11:37:28 volumio volumio[1124]: ---------------------------- MPD announces state update: player Apr 22 11:37:28 volumio volumio[1124]: info: ControllerMpd::getState Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand status Apr 22 11:37:28 volumio volumio[1124]: info: ------------------------------ 3ms Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand status took 2 milliseconds Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::parseState Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Apr 22 11:37:28 volumio volumio[1124]: info: ControllerMpd::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Apr 22 11:37:28 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:28 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"livech1.mp3","artist":"Top Radio FM 93.5","album":null,"uri":"http://202.129.206.187:8000/livech1.mp3","trackType":"mp3"} Apr 22 11:37:28 volumio volumio[1124]: verbose: CURRENT POSITION 0 Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus stop Apr 22 11:37:28 volumio volumio[1124]: info: ControllerMpd::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Apr 22 11:37:28 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:28 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":11,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"livech1.mp3","artist":"Top Radio FM 93.5","album":null,"uri":"http://202.129.206.187:8000/livech1.mp3","trackType":"mp3"} Apr 22 11:37:28 volumio volumio[1124]: verbose: CURRENT POSITION 0 Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Apr 22 11:37:28 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:28 volumio volumio[1124]: info: ------------------------------ 139ms Apr 22 11:37:28 volumio volumio[1124]: info: ------------------------------ 141ms Apr 22 11:37:28 volumio volumio[1124]: info: ------------------------------ 9ms Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand status took 9 milliseconds Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::parseState Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Apr 22 11:37:28 volumio volumio[1124]: info: ControllerMpd::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Apr 22 11:37:28 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:28 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"livech1.mp3","artist":"Top Radio FM 93.5","album":null,"uri":"http://202.129.206.187:8000/livech1.mp3","trackType":"mp3"} Apr 22 11:37:28 volumio volumio[1124]: verbose: CURRENT POSITION 0 Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Apr 22 11:37:28 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:28 volumio volumio[1124]: info: ------------------------------ 16ms Apr 22 11:37:28 volumio volumio[1124]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 22 11:37:28 volumio volumio[1124]: verbose: ControllerMpd::parseTrackInfo Apr 22 11:37:28 volumio volumio[1124]: info: ControllerMpd::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::servicePushState Apr 22 11:37:28 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:28 volumio volumio[1124]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"livech1.mp3","artist":"Top Radio FM 93.5","album":null,"uri":"http://202.129.206.187:8000/livech1.mp3","trackType":"mp3"} Apr 22 11:37:28 volumio volumio[1124]: verbose: CURRENT POSITION 0 Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::syncState stateService play Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::syncState currentStatus play Apr 22 11:37:28 volumio volumio[1124]: info: Received an update from plugin. extracting info from payload Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CorePlayQueue::getTrack 0 Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:28 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:28 volumio volumio[1124]: info: ------------------------------ 22ms Apr 22 11:37:39 volumio volumio[1124]: info: CoreCommandRouter::volumioGetState Apr 22 11:37:52 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:52 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:52 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:52 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:52 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:52 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:52 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:52 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:53 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:53 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:53 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:53 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:53 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:53 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:53 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:53 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:53 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:53 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:53 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:53 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:54 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:54 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:54 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:54 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:54 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:54 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:54 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:54 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:54 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:54 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:54 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:54 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:54 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:54 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:54 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:54 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:55 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:55 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:55 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:55 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:55 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:55 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:55 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:55 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:55 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:55 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:55 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:55 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:55 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:55 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:55 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:55 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:56 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:56 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:56 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:56 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:58 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:58 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:58 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:58 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:37:58 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:37:58 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:37:58 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:37:58 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:00 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:00 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:00 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:00 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:00 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:00 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:00 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:00 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:00 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:00 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:00 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:00 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:01 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:01 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:01 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:01 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:01 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:01 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:01 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:01 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:01 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:01 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:01 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:01 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:01 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:01 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:01 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:01 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:02 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:02 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:02 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:02 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:02 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:02 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:02 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:02 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:02 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:02 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:02 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:03 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:03 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:03 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:03 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:03 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:03 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:03 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:03 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:03 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:03 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:03 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:04 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:04 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:04 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:04 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:04 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:04 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:04 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:04 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:05 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:05 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:05 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:05 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:05 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:05 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:05 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:05 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:05 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:05 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:05 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:05 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:05 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:05 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:05 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:05 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:06 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:06 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:06 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:06 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:06 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:06 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:06 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:06 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:06 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:06 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:06 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:07 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:07 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:07 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:07 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:07 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:07 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:07 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:07 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:07 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:07 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:07 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:07 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:07 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:08 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:08 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:08 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:08 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:08 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:08 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:08 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:08 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:08 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:08 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:08 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:09 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:09 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:09 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:09 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:09 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:09 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:09 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:09 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:09 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:09 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:09 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:09 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:09 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:10 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:10 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:10 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:10 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:10 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:10 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:10 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:10 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:10 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:10 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:10 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:10 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:10 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:10 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:10 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:10 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:11 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:11 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:11 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:11 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:12 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:12 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:12 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:12 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:12 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:12 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:12 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:12 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:12 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:12 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:12 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:12 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:12 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:12 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:12 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:12 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:13 volumio volumio[1124]: info: VolumeController::SetAlsaVolume+ Apr 22 11:38:13 volumio volumio[1124]: info: CoreStateMachine::pushState Apr 22 11:38:13 volumio volumio[1124]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 11:38:13 volumio volumio[1124]: info: CoreCommandRouter::volumioPushState Apr 22 11:38:16 volumio volumio[1124]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 11:38:16 volumio volumio[1124]: Error: connect ETIMEDOUT 199.59.149.244:80 Apr 22 11:38:16 volumio volumio[1124]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 22 11:38:16 volumio volumio[1124]: errno: -110, Apr 22 11:38:16 volumio volumio[1124]: code: 'ETIMEDOUT', Apr 22 11:38:16 volumio volumio[1124]: syscall: 'connect', Apr 22 11:38:16 volumio volumio[1124]: address: '199.59.149.244', Apr 22 11:38:16 volumio volumio[1124]: port: 80 Apr 22 11:38:16 volumio volumio[1124]: } Apr 22 11:38:16 volumio volumio[1124]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 11:38:17 volumio sudo[2711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 11:37 Apr 22 11:38:17 volumio sudo[2711]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"