-- Logs begin at Sat 2024-07-13 22:47:15 CST, end at Sat 2024-07-13 23:00:51 CST. -- Jul 13 22:59:15 volumio volumio[859]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 13 22:59:15 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 13 22:59:15 volumio volumio[859]: info: CoreCommandRouter::volumioGetState Jul 13 22:59:15 volumio volumio[859]: info: Restoring Previous Volume level: 0 false true Jul 13 22:59:15 volumio volumio[859]: info: VolumeController::SetAlsaVolume100 Jul 13 22:59:15 volumio volumio[859]: info: Volume configurations have been set Jul 13 22:59:15 volumio volumio[859]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 13 22:59:15 volumio volumio[859]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 13 22:59:15 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 13 22:59:15 volumio volumio[859]: info: Disabling external Volume Control Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 22:59:16 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 22:59:16 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 13 22:59:16 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 13 22:59:20 volumio volumio[859]: info: VolumeController::SetAlsaVolume0 Jul 13 22:59:20 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 22:59:20 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 22:59:20 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 22:59:20 volumio volumio[859]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Jul 13 22:59:50 volumio volumio[859]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::volumioGetState Jul 13 22:59:50 volumio volumio[859]: info: Disable softmixer device for audio device Jul 13 22:59:50 volumio volumio[859]: info: Output device has changed, restarting MPD Jul 13 22:59:50 volumio sudo[1872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 22:59:50 volumio sudo[1872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 22:59:50 volumio volumio[859]: info: Output device has changed, restarting Shairport Sync Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 22:59:50 volumio sudo[1872]: pam_unix(sudo:session): session closed for user root Jul 13 22:59:50 volumio sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 22:59:50 volumio sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 22:59:50 volumio systemd[1]: Stopping Music Player Daemon... Jul 13 22:59:50 volumio volumio[859]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 22:59:50 volumio volumio[859]: info: Restoring Previous Volume level: 0 false false Jul 13 22:59:50 volumio volumio[859]: info: Volume configurations have been set Jul 13 22:59:50 volumio systemd[1]: mpd.service: Succeeded. Jul 13 22:59:50 volumio systemd[1]: Stopped Music Player Daemon. Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 13 22:59:50 volumio volumio[859]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: xCORE USB Audio 2.0 Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 13 22:59:50 volumio volumio[859]: info: Disabling external Volume Control Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 22:59:50 volumio volumio[859]: info: Soft Volume ALSA configuration file deleted Jul 13 22:59:50 volumio volumio[859]: info: Preparing to generate the ALSA configuration file Jul 13 22:59:50 volumio volumio[859]: info: MPD Permissions set Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio systemd[1]: Starting Music Player Daemon... Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 13 22:59:50 volumio sudo[1882]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 22:59:50 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 13 22:59:50 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 13 22:59:50 volumio volumio[859]: info: VolumeController:: Volume=100 Mute =false Jul 13 22:59:50 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 22:59:50 volumio volumio[859]: info: Starting Shairport Sync Jul 13 22:59:50 volumio sudo[1896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 13 22:59:50 volumio sudo[1896]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 22:59:50 volumio volumio[859]: info: Asound.conf file written Jul 13 22:59:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 13 22:59:50 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 13 22:59:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 13 22:59:50 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 13 22:59:50 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 22:59:50 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root Jul 13 22:59:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 13 22:59:50 volumio volumio[859]: No state is present for card x20 Jul 13 22:59:50 volumio volumio[859]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:000a" "" "" Jul 13 22:59:50 volumio volumio[859]: Hardware is initialized using a generic method Jul 13 22:59:50 volumio volumio[859]: No state is present for card x20 Jul 13 22:59:50 volumio volumio[859]: info: Output device has changed, restarting MPD Jul 13 22:59:50 volumio sudo[1896]: pam_unix(sudo:session): session closed for user root Jul 13 22:59:50 volumio sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 22:59:50 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 22:59:50 volumio volumio[859]: info: Output device has changed, restarting Shairport Sync Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 22:59:50 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root Jul 13 22:59:50 volumio sudo[1911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 22:59:50 volumio sudo[1911]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 22:59:50 volumio volumio[859]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 22:59:50 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 13 22:59:50 volumio systemd[1]: mpd.service: Succeeded. Jul 13 22:59:50 volumio systemd[1]: Stopped Music Player Daemon. Jul 13 22:59:50 volumio volumio[859]: info: MPD Permissions set Jul 13 22:59:50 volumio volumio[859]: info: Shairport-Sync Started Jul 13 22:59:50 volumio systemd[1]: Starting Music Player Daemon... Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 22:59:50 volumio volumio[859]: info: Starting Shairport Sync Jul 13 22:59:50 volumio sudo[1922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 13 22:59:50 volumio sudo[1922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 22:59:50 volumio sudo[1916]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 13 22:59:50 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 22:59:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 13 22:59:50 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Jul 13 22:59:50 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 13 22:59:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 13 22:59:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 13 22:59:50 volumio sudo[1922]: pam_unix(sudo:session): session closed for user root Jul 13 22:59:50 volumio volumio[859]: info: Shairport-Sync Started Jul 13 22:59:51 volumio mpd[1925]: Jul 13 22:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 13 22:59:51 volumio systemd[1]: Started Music Player Daemon. Jul 13 22:59:51 volumio sudo[1875]: pam_unix(sudo:session): session closed for user root Jul 13 22:59:51 volumio sudo[1911]: pam_unix(sudo:session): session closed for user root Jul 13 22:59:51 volumio volumio[859]: error: MPD error: The expression evaluated to a falsy value: Jul 13 22:59:51 volumio volumio[859]: assert.ok(self.idling) Jul 13 22:59:51 volumio volumio[859]: error: The expression evaluated to a falsy value: Jul 13 22:59:51 volumio volumio[859]: assert.ok(self.idling) Jul 13 22:59:51 volumio volumio[859]: error: updateQueue error: null Jul 13 22:59:54 volumio volumio[859]: info: VolumeController::SetAlsaVolume0 Jul 13 22:59:54 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 22:59:54 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 22:59:54 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 22:59:59 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 22:59:59 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 22:59:59 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 22:59:59 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 22:59:59 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 22:59:59 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 22:59:59 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 22:59:59 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:00 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:00 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:00 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:00 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:00 volumio volumio[859]: info: VolumeController::SetAlsaVolume10 Jul 13 23:00:00 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:00 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:00 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:01 volumio volumio[859]: info: VolumeController::SetAlsaVolume35 Jul 13 23:00:01 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:01 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:01 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:01 volumio volumio[859]: info: VolumeController::SetAlsaVolume50 Jul 13 23:00:01 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:01 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:01 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:03 volumio volumio[859]: info: VolumeController::SetAlsaVolume55 Jul 13 23:00:03 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:03 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:03 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:03 volumio volumio[859]: info: VolumeController::SetAlsaVolume75 Jul 13 23:00:03 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:03 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:03 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:04 volumio volumio[859]: info: VolumeController::SetAlsaVolume85 Jul 13 23:00:04 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:04 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:04 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:04 volumio volumio[859]: info: VolumeController::SetAlsaVolume80 Jul 13 23:00:04 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:04 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:04 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:04 volumio volumio[859]: info: VolumeController::SetAlsaVolume65 Jul 13 23:00:04 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:04 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:04 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:04 volumio volumio[859]: info: VolumeController::SetAlsaVolume50 Jul 13 23:00:04 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:04 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:04 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:20 volumio volumio[859]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 13 23:00:20 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 13 23:00:20 volumio volumio[859]: info: CoreCommandRouter::volumioGetState Jul 13 23:00:20 volumio volumio[859]: info: Restoring Previous Volume level: 50 false true Jul 13 23:00:20 volumio volumio[859]: info: VolumeController::SetAlsaVolume100 Jul 13 23:00:20 volumio volumio[859]: info: Enable softmixer device for audio device number 5 Jul 13 23:00:20 volumio volumio[859]: info: CoreCommandRouter::volumioStop Jul 13 23:00:20 volumio volumio[859]: info: CoreStateMachine::stop Jul 13 23:00:20 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:00:20 volumio volumio[859]: info: CoreStateMachine::stPlaybackTimer Jul 13 23:00:20 volumio volumio[859]: info: CoreStateMachine::updateTrackBlock Jul 13 23:00:20 volumio volumio[859]: info: CorePlayQueue::getTrackBlock Jul 13 23:00:20 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:20 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:20 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:20 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:20 volumio volumio[859]: info: CoreStateMachine::serviceStop Jul 13 23:00:20 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:20 volumio volumio[859]: info: CoreCommandRouter::serviceStop Jul 13 23:00:20 volumio volumio[859]: info: [1720882820960] ControllerWebradio::stop Jul 13 23:00:20 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 23:00:20 volumio volumio[859]: info: Enable softmixer device for audio device undefined Jul 13 23:00:20 volumio volumio[859]: info: Output device has changed, restarting MPD Jul 13 23:00:20 volumio sudo[1986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 23:00:20 volumio sudo[1986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:20 volumio volumio[859]: info: Output device has changed, restarting Shairport Sync Jul 13 23:00:20 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:20 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 23:00:20 volumio sudo[1986]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:21 volumio sudo[1989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 23:00:21 volumio sudo[1989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:21 volumio systemd[1]: Stopping Music Player Daemon... Jul 13 23:00:21 volumio volumio[859]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 23:00:21 volumio volumio[859]: info: Volume configurations have been set Jul 13 23:00:21 volumio systemd[1]: mpd.service: Succeeded. Jul 13 23:00:21 volumio systemd[1]: Stopped Music Player Daemon. Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 13 23:00:21 volumio volumio[859]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: xCORE USB Audio 2.0 Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 13 23:00:21 volumio volumio[859]: info: Disabling external Volume Control Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 13 23:00:21 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:21 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:21 volumio systemd[1]: Starting Music Player Daemon... Jul 13 23:00:21 volumio volumio[859]: error: MPD error: Error: write EPIPE Jul 13 23:00:21 volumio volumio[859]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 13 23:00:21 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:21 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:21 volumio volumio[859]: info: MPD Permissions set Jul 13 23:00:21 volumio volumio[859]: info: Software Volume ALSA configuration written Jul 13 23:00:21 volumio volumio[859]: info: Preparing to generate the ALSA configuration file Jul 13 23:00:21 volumio volumio[859]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 13 23:00:21 volumio volumio[859]: info: Reading ALSA contributions from plugins. Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio sudo[1995]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 13 23:00:21 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:21 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 13 23:00:21 volumio volumio[859]: info: Starting Shairport Sync Jul 13 23:00:21 volumio sudo[2016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 13 23:00:21 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:21 volumio volumio[859]: info: Asound.conf file written Jul 13 23:00:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 13 23:00:21 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 13 23:00:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 13 23:00:21 volumio sudo[2019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 13 23:00:21 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:21 volumio volumio[859]: No state is present for card x20 Jul 13 23:00:21 volumio volumio[859]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:000a" "" "" Jul 13 23:00:21 volumio volumio[859]: Hardware is initialized using a generic method Jul 13 23:00:21 volumio volumio[859]: No state is present for card x20 Jul 13 23:00:21 volumio volumio[859]: info: Output device has changed, restarting MPD Jul 13 23:00:21 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 13 23:00:21 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:21 volumio volumio[859]: info: Output device has changed, restarting Shairport Sync Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:21 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 23:00:21 volumio sudo[2027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 23:00:21 volumio sudo[2027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:21 volumio sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 23:00:21 volumio sudo[2027]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:21 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:21 volumio volumio[859]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 23:00:21 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 13 23:00:21 volumio systemd[1]: mpd.service: Succeeded. Jul 13 23:00:21 volumio systemd[1]: Stopped Music Player Daemon. Jul 13 23:00:21 volumio systemd[1]: Starting Music Player Daemon... Jul 13 23:00:21 volumio sudo[2036]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 13 23:00:21 volumio sudo[2036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:21 volumio sudo[2036]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:22 volumio mpd[2040]: Jul 13 23:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 13 23:00:22 volumio systemd[1]: Started Music Player Daemon. Jul 13 23:00:22 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:22 volumio sudo[1989]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:22 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Jul 13 23:00:22 volumio kernel: xhci_hcd 0000:01:00.0: @000000042a1191d0 00000000 00000000 0e000000 02028000 Jul 13 23:00:22 volumio volumio[859]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 13 23:00:22 volumio volumio[859]: No state is present for card x20 Jul 13 23:00:22 volumio volumio[859]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:000a" "" "" Jul 13 23:00:22 volumio volumio[859]: Hardware is initialized using a generic method Jul 13 23:00:22 volumio volumio[859]: No state is present for card x20 Jul 13 23:00:22 volumio volumio[859]: info: Output device has changed, restarting MPD Jul 13 23:00:22 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 13 23:00:22 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:22 volumio volumio[859]: info: Output device has changed, restarting Shairport Sync Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 23:00:22 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:22 volumio sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 23:00:22 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:22 volumio systemd[1]: Stopping Music Player Daemon... Jul 13 23:00:22 volumio volumio[859]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 13 23:00:22 volumio systemd[1]: mpd.service: Succeeded. Jul 13 23:00:22 volumio systemd[1]: Stopped Music Player Daemon. Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 13 23:00:22 volumio volumio[859]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 13 23:00:22 volumio volumio[859]: info: Disabling external Volume Control Jul 13 23:00:22 volumio volumio[859]: info: MPD Permissions set Jul 13 23:00:22 volumio volumio[859]: info: MPD Permissions set Jul 13 23:00:22 volumio volumio[859]: info: Shairport-Sync Started Jul 13 23:00:22 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:22 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:22 volumio systemd[1]: Starting Music Player Daemon... Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:22 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 13 23:00:23 volumio volumio[859]: info: Starting Shairport Sync Jul 13 23:00:23 volumio sudo[2055]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 13 23:00:23 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:23 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:23 volumio volumio[859]: info: Starting Shairport Sync Jul 13 23:00:23 volumio sudo[2076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 13 23:00:23 volumio sudo[2076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 13 23:00:23 volumio sudo[2079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 13 23:00:23 volumio sudo[2079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:23 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 13 23:00:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 13 23:00:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 13 23:00:23 volumio sudo[2076]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:23 volumio volumio[859]: info: Shairport-Sync Started Jul 13 23:00:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 13 23:00:23 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 13 23:00:23 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 13 23:00:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 13 23:00:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 13 23:00:23 volumio sudo[2079]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:23 volumio volumio[859]: info: Shairport-Sync Started Jul 13 23:00:23 volumio mpd[2077]: Jul 13 23:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 13 23:00:23 volumio systemd[1]: Started Music Player Daemon. Jul 13 23:00:23 volumio volumio[859]: error: MPD error: The expression evaluated to a falsy value: Jul 13 23:00:23 volumio volumio[859]: assert.ok(self.idling) Jul 13 23:00:23 volumio volumio[859]: error: The expression evaluated to a falsy value: Jul 13 23:00:23 volumio volumio[859]: assert.ok(self.idling) Jul 13 23:00:23 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:23 volumio volumio[859]: error: updateQueue error: null Jul 13 23:00:23 volumio volumio[859]: error: updateQueue error: null Jul 13 23:00:24 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 13 23:00:24 volumio sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 13 23:00:24 volumio sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:24 volumio systemd[1]: Stopping Music Player Daemon... Jul 13 23:00:24 volumio systemd[1]: mpd.service: Succeeded. Jul 13 23:00:24 volumio systemd[1]: Stopped Music Player Daemon. Jul 13 23:00:24 volumio systemd[1]: Starting Music Player Daemon... Jul 13 23:00:24 volumio sudo[2092]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 13 23:00:24 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 13 23:00:24 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:24 volumio volumio[859]: info: CoreCommandRouter::volumioPlay Jul 13 23:00:24 volumio volumio[859]: info: CoreStateMachine::play index undefined Jul 13 23:00:24 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:00:24 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:24 volumio volumio[859]: info: CoreStateMachine::startPlaybackTimer Jul 13 23:00:24 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:24 volumio volumio[859]: info: [1720882824732] ControllerWebradio::clearAddPlayTrack Jul 13 23:00:24 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 23:00:24 volumio volumio[859]: error: MPD error: Error: This socket has been ended by the other party Jul 13 23:00:24 volumio volumio[859]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 13 23:00:24 volumio volumio[859]: error: MPD error: Error: This socket has been ended by the other party Jul 13 23:00:24 volumio volumio[859]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 13 23:00:24 volumio volumio[859]: error: MPD error: Error: This socket has been ended by the other party Jul 13 23:00:24 volumio volumio[859]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 13 23:00:25 volumio mpd[2095]: Jul 13 23:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 13 23:00:25 volumio systemd[1]: Started Music Player Daemon. Jul 13 23:00:25 volumio sudo[2090]: pam_unix(sudo:session): session closed for user root Jul 13 23:00:25 volumio volumio[859]: error: updateQueue error: null Jul 13 23:00:25 volumio volumio[859]: info: VolumeController::SetAlsaVolume50 Jul 13 23:00:25 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:25 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:25 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:25 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:29 volumio volumio[859]: info: CoreCommandRouter::volumioPlay Jul 13 23:00:29 volumio volumio[859]: info: CoreStateMachine::play index undefined Jul 13 23:00:29 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:00:29 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:29 volumio volumio[859]: info: CoreStateMachine::startPlaybackTimer Jul 13 23:00:29 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:29 volumio volumio[859]: info: [1720882829196] ControllerWebradio::clearAddPlayTrack Jul 13 23:00:29 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 23:00:29 volumio volumio[859]: info: sendMpdCommand stop took 0 milliseconds Jul 13 23:00:29 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 23:00:29 volumio volumio[859]: info: Jul 13 23:00:29 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:29 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:29 volumio volumio[859]: info: sendMpdCommand clear took 1 milliseconds Jul 13 23:00:29 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Jul 13 23:00:29 volumio volumio[859]: error: updateQueue error: null Jul 13 23:00:29 volumio volumio[859]: info: ------------------------------ 2ms Jul 13 23:00:30 volumio volumio[859]: info: CoreCommandRouter::volumioPlay Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::play index undefined Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:00:30 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::startPlaybackTimer Jul 13 23:00:30 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:30 volumio volumio[859]: info: [1720882830021] ControllerWebradio::clearAddPlayTrack Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 23:00:30 volumio volumio[859]: info: CoreCommandRouter::volumioPlay Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::play index undefined Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:00:30 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::startPlaybackTimer Jul 13 23:00:30 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:30 volumio volumio[859]: info: [1720882830648] ControllerWebradio::clearAddPlayTrack Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 23:00:30 volumio volumio[859]: info: CoreCommandRouter::volumioPlay Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::play index undefined Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:00:30 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::startPlaybackTimer Jul 13 23:00:30 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:30 volumio volumio[859]: info: [1720882830813] ControllerWebradio::clearAddPlayTrack Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 1782 milliseconds Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand play Jul 13 23:00:30 volumio volumio[859]: info: Jul 13 23:00:30 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:30 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand stop took 961 milliseconds Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand stop took 334 milliseconds Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand stop took 170 milliseconds Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand play took 3 milliseconds Jul 13 23:00:30 volumio volumio[859]: info: ------------------------------ 3ms Jul 13 23:00:30 volumio volumio[859]: info: CoreCommandRouter::volumioPlay Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::play index undefined Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:00:30 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:30 volumio volumio[859]: info: CoreStateMachine::startPlaybackTimer Jul 13 23:00:30 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:30 volumio volumio[859]: info: [1720882830986] ControllerWebradio::clearAddPlayTrack Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 23:00:30 volumio volumio[859]: info: Jul 13 23:00:30 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:30 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:30 volumio volumio[859]: info: Jul 13 23:00:30 volumio volumio[859]: ---------------------------- MPD announces state update: player Jul 13 23:00:30 volumio volumio[859]: info: Jul 13 23:00:30 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:30 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand clear took 7 milliseconds Jul 13 23:00:30 volumio volumio[859]: info: ControllerMpd::getState Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand status Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand clear took 8 milliseconds Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand clear took 8 milliseconds Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand stop took 4 milliseconds Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 23:00:30 volumio volumio[859]: error: updateQueue error: null Jul 13 23:00:30 volumio volumio[859]: info: ------------------------------ 7ms Jul 13 23:00:30 volumio volumio[859]: info: sendMpdCommand status took 8 milliseconds Jul 13 23:00:30 volumio volumio[859]: verbose: ControllerMpd::parseState Jul 13 23:00:30 volumio volumio[859]: info: ControllerMpd::pushState Jul 13 23:00:31 volumio volumio[859]: info: CoreCommandRouter::servicePushState Jul 13 23:00:31 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:31 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:31 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:31 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:31 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:31 volumio volumio[859]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 13 23:00:31 volumio volumio[859]: info: ------------------------------ 21ms Jul 13 23:00:31 volumio volumio[859]: info: CoreCommandRouter::volumioPlay Jul 13 23:00:31 volumio volumio[859]: info: CoreStateMachine::play index undefined Jul 13 23:00:31 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:00:31 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:31 volumio volumio[859]: info: CoreStateMachine::startPlaybackTimer Jul 13 23:00:31 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:31 volumio volumio[859]: info: [1720882831146] ControllerWebradio::clearAddPlayTrack Jul 13 23:00:31 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 23:00:33 volumio volumio[859]: info: CoreCommandRouter::volumioPlay Jul 13 23:00:33 volumio volumio[859]: info: CoreStateMachine::play index undefined Jul 13 23:00:33 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 13 23:00:33 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:33 volumio volumio[859]: info: CoreStateMachine::startPlaybackTimer Jul 13 23:00:33 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:33 volumio volumio[859]: info: [1720882833160] ControllerWebradio::clearAddPlayTrack Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jul 13 23:00:33 volumio volumio[859]: error: updateQueue error: null Jul 13 23:00:33 volumio volumio[859]: info: Jul 13 23:00:33 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:33 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:33 volumio volumio[859]: info: ------------------------------ 2376ms Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 2372 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 2372 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 2372 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand clear took 2373 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand play Jul 13 23:00:33 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand play Jul 13 23:00:33 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand play Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Jul 13 23:00:33 volumio volumio[859]: error: updateQueue error: null Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand stop took 2220 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand stop took 207 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: ------------------------------ 3ms Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand play took 2 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand play took 2 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand play took 3 milliseconds Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand clear Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 284 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand play Jul 13 23:00:33 volumio volumio[859]: info: Jul 13 23:00:33 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:33 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:33 volumio volumio[859]: info: Jul 13 23:00:33 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:33 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand clear took 284 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand clear took 284 milliseconds Jul 13 23:00:33 volumio volumio[859]: info: sendMpdCommand play took 2 milliseconds Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Jul 13 23:00:33 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Jul 13 23:00:33 volumio volumio[859]: error: updateQueue error: null Jul 13 23:00:33 volumio volumio[859]: error: updateQueue error: null Jul 13 23:00:33 volumio volumio[859]: info: ------------------------------ 2ms Jul 13 23:00:33 volumio volumio[859]: info: ------------------------------ 3ms Jul 13 23:00:34 volumio volumio[859]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 923 milliseconds Jul 13 23:00:34 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 23:00:34 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand play Jul 13 23:00:34 volumio volumio[859]: info: Jul 13 23:00:34 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:34 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:34 volumio volumio[859]: info: Jul 13 23:00:34 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:34 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:34 volumio volumio[859]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 1205 milliseconds Jul 13 23:00:34 volumio volumio[859]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 13 23:00:34 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand play Jul 13 23:00:34 volumio volumio[859]: info: sendMpdCommand play took 283 milliseconds Jul 13 23:00:34 volumio volumio[859]: info: ------------------------------ 3ms Jul 13 23:00:34 volumio volumio[859]: info: ------------------------------ 2ms Jul 13 23:00:34 volumio volumio[859]: info: sendMpdCommand play took 1 milliseconds Jul 13 23:00:37 volumio volumio[859]: info: Jul 13 23:00:37 volumio volumio[859]: ---------------------------- MPD announces state update: player Jul 13 23:00:37 volumio volumio[859]: info: ControllerMpd::getState Jul 13 23:00:37 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand status Jul 13 23:00:37 volumio volumio[859]: info: Jul 13 23:00:37 volumio volumio[859]: ---------------------------- MPD announces state update: player Jul 13 23:00:37 volumio volumio[859]: info: sendMpdCommand status took 16 milliseconds Jul 13 23:00:37 volumio volumio[859]: info: ControllerMpd::getState Jul 13 23:00:37 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand status Jul 13 23:00:37 volumio volumio[859]: verbose: ControllerMpd::parseState Jul 13 23:00:37 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 23:00:37 volumio volumio[859]: info: sendMpdCommand status took 1 milliseconds Jul 13 23:00:37 volumio volumio[859]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 13 23:00:37 volumio volumio[859]: verbose: ControllerMpd::parseState Jul 13 23:00:37 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 23:00:37 volumio volumio[859]: verbose: ControllerMpd::parseTrackInfo Jul 13 23:00:37 volumio volumio[859]: info: ControllerMpd::pushState Jul 13 23:00:37 volumio volumio[859]: info: CoreCommandRouter::servicePushState Jul 13 23:00:37 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:37 volumio volumio[859]: 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":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 3392/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 13 23:00:37 volumio volumio[859]: verbose: CURRENT POSITION 0 Jul 13 23:00:37 volumio volumio[859]: info: CoreStateMachine::syncState stateService play Jul 13 23:00:37 volumio volumio[859]: info: CoreStateMachine::syncState currentStatus stop Jul 13 23:00:37 volumio volumio[859]: info: ------------------------------ 20ms Jul 13 23:00:37 volumio volumio[859]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 23:00:37 volumio volumio[859]: verbose: ControllerMpd::parseTrackInfo Jul 13 23:00:37 volumio volumio[859]: info: ControllerMpd::pushState Jul 13 23:00:37 volumio volumio[859]: info: CoreCommandRouter::servicePushState Jul 13 23:00:37 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:37 volumio volumio[859]: 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":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 3392/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 13 23:00:37 volumio volumio[859]: verbose: CURRENT POSITION 0 Jul 13 23:00:37 volumio volumio[859]: info: CoreStateMachine::syncState stateService play Jul 13 23:00:37 volumio volumio[859]: info: CoreStateMachine::syncState currentStatus play Jul 13 23:00:37 volumio volumio[859]: info: Received an update from plugin. extracting info from payload Jul 13 23:00:37 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 13 23:00:37 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:37 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:37 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:37 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:37 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:37 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:37 volumio volumio[859]: info: ------------------------------ 21ms Jul 13 23:00:38 volumio volumio[859]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%203392%2F500000)%20ROCK%20ANTENNE/c78f3285-9627-462d-8382-e8f1ec42b132.jpg' Jul 13 23:00:38 volumio volumio[859]: info: Jul 13 23:00:38 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:38 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:38 volumio volumio[859]: info: Jul 13 23:00:38 volumio volumio[859]: ---------------------------- MPD announces state update: player Jul 13 23:00:38 volumio volumio[859]: info: ControllerMpd::getState Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand status Jul 13 23:00:38 volumio volumio[859]: info: Jul 13 23:00:38 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:38 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:38 volumio volumio[859]: info: Jul 13 23:00:38 volumio volumio[859]: ---------------------------- MPD announces state update: player Jul 13 23:00:38 volumio volumio[859]: info: ControllerMpd::getState Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand status Jul 13 23:00:38 volumio volumio[859]: info: ------------------------------ 6ms Jul 13 23:00:38 volumio volumio[859]: info: sendMpdCommand status took 5 milliseconds Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::parseState Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 23:00:38 volumio volumio[859]: info: Jul 13 23:00:38 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:38 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:38 volumio volumio[859]: info: Jul 13 23:00:38 volumio volumio[859]: ---------------------------- MPD announces state update: player Jul 13 23:00:38 volumio volumio[859]: info: ControllerMpd::getState Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand status Jul 13 23:00:38 volumio volumio[859]: info: ------------------------------ 5ms Jul 13 23:00:38 volumio volumio[859]: info: sendMpdCommand status took 4 milliseconds Jul 13 23:00:38 volumio volumio[859]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::parseState Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::parseTrackInfo Jul 13 23:00:38 volumio volumio[859]: info: ControllerMpd::pushState Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::servicePushState Jul 13 23:00:38 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:38 volumio volumio[859]: 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":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 13 23:00:38 volumio volumio[859]: verbose: CURRENT POSITION 0 Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::syncState stateService play Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::syncState currentStatus play Jul 13 23:00:38 volumio volumio[859]: info: Received an update from plugin. extracting info from payload Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:38 volumio volumio[859]: info: ------------------------------ 44ms Jul 13 23:00:38 volumio volumio[859]: info: ------------------------------ 38ms Jul 13 23:00:38 volumio volumio[859]: info: sendMpdCommand status took 37 milliseconds Jul 13 23:00:38 volumio volumio[859]: info: sendMpdCommand playlistinfo took 32 milliseconds Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::parseState Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::parseTrackInfo Jul 13 23:00:38 volumio volumio[859]: info: ControllerMpd::pushState Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::servicePushState Jul 13 23:00:38 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:38 volumio volumio[859]: 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":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 13 23:00:38 volumio volumio[859]: verbose: CURRENT POSITION 0 Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::syncState stateService play Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::syncState currentStatus play Jul 13 23:00:38 volumio volumio[859]: info: Received an update from plugin. extracting info from payload Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:38 volumio volumio[859]: info: ------------------------------ 64ms Jul 13 23:00:38 volumio volumio[859]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 13 23:00:38 volumio volumio[859]: verbose: ControllerMpd::parseTrackInfo Jul 13 23:00:38 volumio volumio[859]: info: ControllerMpd::pushState Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::servicePushState Jul 13 23:00:38 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:38 volumio volumio[859]: 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":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 13 23:00:38 volumio volumio[859]: verbose: CURRENT POSITION 0 Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::syncState stateService play Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::syncState currentStatus play Jul 13 23:00:38 volumio volumio[859]: info: Received an update from plugin. extracting info from payload Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:38 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:38 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:38 volumio volumio[859]: info: ------------------------------ 89ms Jul 13 23:00:38 volumio volumio[859]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ROCK%20ANTENNE/8ec957c4-360c-4b68-ac20-0d46428d0d64.jpg' Jul 13 23:00:39 volumio volumio[859]: info: Jul 13 23:00:39 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:39 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:39 volumio volumio[859]: info: Jul 13 23:00:39 volumio volumio[859]: ---------------------------- MPD announces state update: player Jul 13 23:00:39 volumio volumio[859]: info: ControllerMpd::getState Jul 13 23:00:39 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand status Jul 13 23:00:39 volumio volumio[859]: info: ------------------------------ 2ms Jul 13 23:00:39 volumio volumio[859]: info: sendMpdCommand status took 1 milliseconds Jul 13 23:00:39 volumio volumio[859]: verbose: ControllerMpd::parseState Jul 13 23:00:39 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 23:00:39 volumio volumio[859]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 13 23:00:39 volumio volumio[859]: verbose: ControllerMpd::parseTrackInfo Jul 13 23:00:39 volumio volumio[859]: info: ControllerMpd::pushState Jul 13 23:00:39 volumio volumio[859]: info: CoreCommandRouter::servicePushState Jul 13 23:00:39 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:39 volumio volumio[859]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1857,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 13 23:00:39 volumio volumio[859]: verbose: CURRENT POSITION 0 Jul 13 23:00:39 volumio volumio[859]: info: CoreStateMachine::syncState stateService play Jul 13 23:00:39 volumio volumio[859]: info: CoreStateMachine::syncState currentStatus play Jul 13 23:00:39 volumio volumio[859]: info: Received an update from plugin. extracting info from payload Jul 13 23:00:39 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 13 23:00:39 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:39 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:39 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:39 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:39 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:39 volumio volumio[859]: info: ------------------------------ 26ms Jul 13 23:00:40 volumio volumio[859]: info: Jul 13 23:00:40 volumio volumio[859]: ---------------------------- MPD announces system playlist update Jul 13 23:00:40 volumio volumio[859]: info: Ignoring MPD Status Update Jul 13 23:00:40 volumio volumio[859]: info: Jul 13 23:00:40 volumio volumio[859]: ---------------------------- MPD announces state update: player Jul 13 23:00:40 volumio volumio[859]: info: ControllerMpd::getState Jul 13 23:00:40 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand status Jul 13 23:00:40 volumio volumio[859]: info: ------------------------------ 2ms Jul 13 23:00:40 volumio volumio[859]: info: sendMpdCommand status took 1 milliseconds Jul 13 23:00:40 volumio volumio[859]: verbose: ControllerMpd::parseState Jul 13 23:00:40 volumio volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 13 23:00:40 volumio volumio[859]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 13 23:00:40 volumio volumio[859]: verbose: ControllerMpd::parseTrackInfo Jul 13 23:00:40 volumio volumio[859]: info: ControllerMpd::pushState Jul 13 23:00:40 volumio volumio[859]: info: CoreCommandRouter::servicePushState Jul 13 23:00:40 volumio volumio[859]: info: CorePlayQueue::getTrack 0 Jul 13 23:00:40 volumio volumio[859]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2734,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} Jul 13 23:00:40 volumio volumio[859]: verbose: CURRENT POSITION 0 Jul 13 23:00:40 volumio volumio[859]: info: CoreStateMachine::syncState stateService play Jul 13 23:00:40 volumio volumio[859]: info: CoreStateMachine::syncState currentStatus play Jul 13 23:00:40 volumio volumio[859]: info: Received an update from plugin. extracting info from payload Jul 13 23:00:40 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 13 23:00:40 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:40 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:40 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:40 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:40 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:40 volumio volumio[859]: info: ------------------------------ 19ms Jul 13 23:00:41 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:41 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:41 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:41 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:42 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:42 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:42 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:42 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:42 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:42 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:42 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:42 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:42 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:42 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:42 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:43 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:43 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:43 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:43 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:43 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:43 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:43 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:43 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:43 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:43 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:43 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:43 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:44 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:44 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:44 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:44 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:44 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:44 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:44 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:44 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:44 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:44 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:45 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:45 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:45 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:45 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:45 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:45 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:45 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:45 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:45 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:45 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:45 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:46 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:46 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:46 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:46 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:46 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:46 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:46 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:46 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:46 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:46 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:46 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:46 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:46 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:47 volumio volumio[859]: info: VolumeController::SetAlsaVolume+ Jul 13 23:00:47 volumio volumio[859]: info: CoreStateMachine::pushState Jul 13 23:00:47 volumio volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 13 23:00:47 volumio volumio[859]: info: CoreCommandRouter::volumioPushState Jul 13 23:00:51 volumio volumio[859]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 23:00:51 volumio volumio[859]: Error: connect ETIMEDOUT 202.160.130.66:80 Jul 13 23:00:51 volumio volumio[859]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 13 23:00:51 volumio volumio[859]: errno: -110, Jul 13 23:00:51 volumio volumio[859]: code: 'ETIMEDOUT', Jul 13 23:00:51 volumio volumio[859]: syscall: 'connect', Jul 13 23:00:51 volumio volumio[859]: address: '202.160.130.66', Jul 13 23:00:51 volumio volumio[859]: port: 80 Jul 13 23:00:51 volumio volumio[859]: } Jul 13 23:00:51 volumio volumio[859]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 13 23:00:51 volumio sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-13 22:59 Jul 13 23:00:51 volumio sudo[2145]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"