-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-07-17 02:30:46 CEST. -- Jul 17 02:29:08 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:98:pixelvalve-2] flip_done timed out Jul 17 02:29:16 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 02:29:18 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Jul 17 02:29:18 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:98:pixelvalve-2] commit wait timed out Jul 17 02:29:18 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 17 02:29:18 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 17 02:29:21 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 17 02:29:21 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:29:23 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 02:29:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 17 02:29:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 17 02:29:23 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 17 02:29:24 volumio volumio[975]: info: Received Get System Version Jul 17 02:29:24 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 17 02:29:28 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Jul 17 02:29:28 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CONNECTOR:32:HDMI-A-1] commit wait timed out Jul 17 02:29:30 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 17 02:29:30 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 17 02:29:35 volumio volumio[975]: info: Getting Alsa Cards List without I2S DAC Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 17 02:29:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 17 02:29:38 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Jul 17 02:29:38 volumio kernel: vc4-drm gpu: [drm] *ERROR* [PLANE:88:plane-3] commit wait timed out Jul 17 02:29:49 volumio volumio[975]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 17 02:29:49 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:29:49 volumio volumio[975]: info: Restoring Previous Volume level: 86 false true Jul 17 02:29:49 volumio volumio[975]: info: VolumeController::SetAlsaVolume100 Jul 17 02:29:49 volumio volumio[975]: info: Enable softmixer device for audio device number 2 Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::volumioStop Jul 17 02:29:49 volumio volumio[975]: info: CoreStateMachine::stop Jul 17 02:29:49 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 17 02:29:49 volumio volumio[975]: info: Enable softmixer device for audio device undefined Jul 17 02:29:49 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Jul 17 02:29:49 volumio kernel: vc4-drm gpu: [drm] *ERROR* Timed out waiting for commit Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 17 02:29:49 volumio volumio[975]: info: Updating Volume Controller Parameters: Device: 2 Name: IQaudIO DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 17 02:29:49 volumio volumio[975]: info: Disabling external Volume Control Jul 17 02:29:49 volumio volumio[975]: info: Output device has changed, restarting MPD Jul 17 02:29:49 volumio sudo[6589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 02:29:49 volumio sudo[6589]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:49 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio sudo[6589]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:49 volumio sudo[6592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 02:29:49 volumio sudo[6592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:49 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 02:29:49 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 02:29:49 volumio volumio[975]: info: Volume configurations have been set Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 17 02:29:49 volumio volumio[975]: info: Updating Volume Controller Parameters: Device: 2 Name: IQaudIO DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 17 02:29:49 volumio volumio[975]: info: Disabling external Volume Control Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 02:29:49 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:29:49 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:29:49 volumio volumio[975]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Jul 17 02:29:49 volumio volumio[975]: info: MPD Permissions set Jul 17 02:29:49 volumio volumio[975]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Jul 17 02:29:49 volumio volumio[975]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Jul 17 02:29:49 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:29:49 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:29:49 volumio volumio[975]: info: Software Volume ALSA configuration written Jul 17 02:29:49 volumio volumio[975]: info: Preparing to generate the ALSA configuration file Jul 17 02:29:49 volumio volumio[975]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 17 02:29:49 volumio volumio[975]: info: Reading ALSA contributions from plugins. Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 17 02:29:49 volumio volumio[975]: info: Getting Alsa Cards List without I2S DAC Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: VolumeController:: Volume=undefined Mute =false Jul 17 02:29:49 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:29:49 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:29:49 volumio volumio[975]: info: Starting Shairport Sync Jul 17 02:29:49 volumio volumio[975]: info: VolumeController::SetAlsaVolume0 Jul 17 02:29:49 volumio sudo[6622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 02:29:49 volumio sudo[6622]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:49 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:29:49 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:29:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 02:29:49 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 02:29:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 02:29:49 volumio volumio[975]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Jul 17 02:29:49 volumio volumio[975]: info: Asound.conf file written Jul 17 02:29:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 02:29:49 volumio systemd[1]: mpd.service: Succeeded. Jul 17 02:29:49 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 02:29:49 volumio sudo[6627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 17 02:29:49 volumio sudo[6627]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:49 volumio sudo[6627]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:49 volumio sudo[6622]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:49 volumio systemd[1]: Starting Music Player Daemon... Jul 17 02:29:49 volumio volumio[975]: No state is present for card b1 Jul 17 02:29:49 volumio volumio[975]: /usr/share/alsa/init/bcm2835:14: control element not found Jul 17 02:29:49 volumio volumio[975]: No state is present for card b1 Jul 17 02:29:49 volumio volumio[975]: No state is present for card Headphones Jul 17 02:29:49 volumio volumio[975]: /usr/share/alsa/init/bcm2835:20: control element not found Jul 17 02:29:49 volumio volumio[975]: No state is present for card Headphones Jul 17 02:29:49 volumio volumio[975]: No state is present for card vc4hdmi0 Jul 17 02:29:49 volumio volumio[975]: Found hardware: "vc4-hdmi" "" "" "" "" Jul 17 02:29:49 volumio volumio[975]: Hardware is initialized using a generic method Jul 17 02:29:49 volumio volumio[975]: No state is present for card vc4hdmi0 Jul 17 02:29:49 volumio volumio[975]: No state is present for card vc4hdmi1 Jul 17 02:29:49 volumio volumio[975]: Found hardware: "vc4-hdmi" "" "" "" "" Jul 17 02:29:49 volumio volumio[975]: Hardware is initialized using a generic method Jul 17 02:29:49 volumio volumio[975]: No state is present for card vc4hdmi1 Jul 17 02:29:49 volumio volumio[975]: No state is present for card IQaudIODAC Jul 17 02:29:49 volumio volumio[975]: Found hardware: "IQaudIODAC" "" "" "" "" Jul 17 02:29:49 volumio volumio[975]: Hardware is initialized using a generic method Jul 17 02:29:49 volumio volumio[975]: No state is present for card IQaudIODAC Jul 17 02:29:49 volumio volumio[975]: info: Output device has changed, restarting MPD Jul 17 02:29:49 volumio sudo[6630]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 17 02:29:49 volumio sudo[6630]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:49 volumio sudo[6630]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:49 volumio sudo[6637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 02:29:49 volumio sudo[6637]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:49 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:49 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:49 volumio sudo[6637]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:49 volumio sudo[6641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 02:29:49 volumio sudo[6641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:49 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 17 02:29:49 volumio systemd[1]: mpd.service: Succeeded. Jul 17 02:29:49 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 02:29:49 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 02:29:50 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 17 02:29:50 volumio systemd[1]: Starting Music Player Daemon... Jul 17 02:29:50 volumio sudo[6646]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 17 02:29:50 volumio sudo[6646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:50 volumio sudo[6646]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:51 volumio mpd[6652]: Jul 17 02:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 02:29:51 volumio systemd[1]: Started Music Player Daemon. Jul 17 02:29:51 volumio sudo[6592]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:51 volumio sudo[6641]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:51 volumio volumio[975]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 17 02:29:51 volumio volumio[975]: No state is present for card b1 Jul 17 02:29:51 volumio volumio[975]: /usr/share/alsa/init/bcm2835:14: control element not found Jul 17 02:29:51 volumio volumio[975]: No state is present for card b1 Jul 17 02:29:51 volumio volumio[975]: No state is present for card Headphones Jul 17 02:29:51 volumio volumio[975]: /usr/share/alsa/init/bcm2835:20: control element not found Jul 17 02:29:51 volumio volumio[975]: No state is present for card Headphones Jul 17 02:29:51 volumio volumio[975]: No state is present for card vc4hdmi0 Jul 17 02:29:51 volumio volumio[975]: Found hardware: "vc4-hdmi" "" "" "" "" Jul 17 02:29:51 volumio volumio[975]: Hardware is initialized using a generic method Jul 17 02:29:51 volumio volumio[975]: No state is present for card vc4hdmi0 Jul 17 02:29:51 volumio volumio[975]: No state is present for card vc4hdmi1 Jul 17 02:29:51 volumio volumio[975]: Found hardware: "vc4-hdmi" "" "" "" "" Jul 17 02:29:51 volumio volumio[975]: Hardware is initialized using a generic method Jul 17 02:29:51 volumio volumio[975]: No state is present for card vc4hdmi1 Jul 17 02:29:51 volumio volumio[975]: No state is present for card IQaudIODAC Jul 17 02:29:51 volumio volumio[975]: Found hardware: "IQaudIODAC" "" "" "" "" Jul 17 02:29:51 volumio volumio[975]: Hardware is initialized using a generic method Jul 17 02:29:51 volumio volumio[975]: No state is present for card IQaudIODAC Jul 17 02:29:51 volumio volumio[975]: info: Output device has changed, restarting MPD Jul 17 02:29:51 volumio sudo[6685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 02:29:51 volumio sudo[6685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:51 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:51 volumio sudo[6685]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:51 volumio sudo[6693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 02:29:51 volumio sudo[6693]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:51 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 02:29:51 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 02:29:51 volumio systemd[1]: mpd.service: Succeeded. Jul 17 02:29:51 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 17 02:29:51 volumio volumio[975]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 17 02:29:51 volumio volumio[975]: info: Disabling external Volume Control Jul 17 02:29:51 volumio systemd[1]: Starting Music Player Daemon... Jul 17 02:29:51 volumio volumio[975]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Digital',0 Jul 17 02:29:51 volumio volumio[975]: info: MPD Permissions set Jul 17 02:29:51 volumio volumio[975]: info: MPD Permissions set Jul 17 02:29:51 volumio volumio[975]: info: Shairport-Sync Started Jul 17 02:29:51 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:29:51 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:51 volumio sudo[6715]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio sudo[6715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio sudo[6715]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:29:51 volumio volumio[975]: info: Starting Shairport Sync Jul 17 02:29:51 volumio volumio[975]: info: Starting Shairport Sync Jul 17 02:29:51 volumio sudo[6746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 02:29:51 volumio sudo[6746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:51 volumio sudo[6749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 02:29:51 volumio sudo[6749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 02:29:51 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 02:29:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 02:29:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 02:29:51 volumio sudo[6746]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 02:29:51 volumio volumio[975]: info: Shairport-Sync Started Jul 17 02:29:51 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 17 02:29:51 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 02:29:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 02:29:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 02:29:51 volumio sudo[6749]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:51 volumio volumio[975]: info: Shairport-Sync Started Jul 17 02:29:52 volumio mpd[6741]: Jul 17 02:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 02:29:52 volumio systemd[1]: Started Music Player Daemon. Jul 17 02:29:52 volumio sudo[6693]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:52 volumio volumio[975]: error: MPD error: The expression evaluated to a falsy value: Jul 17 02:29:52 volumio volumio[975]: assert.ok(self.idling) Jul 17 02:29:52 volumio volumio[975]: error: The expression evaluated to a falsy value: Jul 17 02:29:52 volumio volumio[975]: assert.ok(self.idling) Jul 17 02:29:52 volumio volumio[975]: error: updateQueue error: null Jul 17 02:29:52 volumio volumio[975]: error: updateQueue error: null Jul 17 02:29:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 17 02:29:53 volumio sudo[6758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 02:29:53 volumio sudo[6758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:53 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 02:29:53 volumio systemd[1]: mpd.service: Succeeded. Jul 17 02:29:53 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 02:29:53 volumio systemd[1]: Starting Music Player Daemon... Jul 17 02:29:53 volumio sudo[6760]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 17 02:29:53 volumio sudo[6760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:29:53 volumio sudo[6760]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:53 volumio volumio[975]: info: VolumeController::SetAlsaVolume86 Jul 17 02:29:53 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:29:53 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:29:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:29:53 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:29:53 volumio mpd[6763]: Jul 17 02:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 02:29:53 volumio systemd[1]: Started Music Player Daemon. Jul 17 02:29:53 volumio sudo[6758]: pam_unix(sudo:session): session closed for user root Jul 17 02:29:53 volumio volumio[975]: error: updateQueue error: null Jul 17 02:29:59 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:98:pixelvalve-2] flip_done timed out Jul 17 02:30:04 volumio volumio[975]: info: VolumeController::SetAlsaVolume84 Jul 17 02:30:04 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:30:04 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:30:04 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:30:04 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:30:05 volumio volumio[975]: info: VolumeController::SetAlsaVolume70 Jul 17 02:30:05 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:30:05 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:30:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:30:05 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:30:09 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Jul 17 02:30:09 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:98:pixelvalve-2] commit wait timed out Jul 17 02:30:14 volumio volumio[975]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 17 02:30:14 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jul 17 02:30:14 volumio sudo[6770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 02:30:14 volumio sudo[6770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:30:14 volumio volumio[975]: info: Restoring Previous Volume level: 70 false true Jul 17 02:30:14 volumio sudo[6770]: pam_unix(sudo:session): session closed for user root Jul 17 02:30:14 volumio volumio[975]: info: VolumeController::SetAlsaVolume100 Jul 17 02:30:14 volumio sudo[6773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 02:30:14 volumio sudo[6773]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:30:14 volumio volumio[975]: info: Volume configurations have been set Jul 17 02:30:14 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 02:30:14 volumio systemd[1]: mpd.service: Succeeded. Jul 17 02:30:14 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 17 02:30:14 volumio volumio[975]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 17 02:30:14 volumio volumio[975]: info: Disabling external Volume Control Jul 17 02:30:14 volumio systemd[1]: Starting Music Player Daemon... Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 02:30:14 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:30:14 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:30:14 volumio volumio[975]: info: MPD Permissions set Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 02:30:14 volumio sudo[6778]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 17 02:30:14 volumio sudo[6778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:30:14 volumio sudo[6778]: pam_unix(sudo:session): session closed for user root Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 17 02:30:14 volumio volumio[975]: info: Getting Alsa Cards List without I2S DAC Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 17 02:30:14 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:30:14 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:30:14 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:30:15 volumio mpd[6795]: Jul 17 02:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 02:30:15 volumio systemd[1]: Started Music Player Daemon. Jul 17 02:30:15 volumio sudo[6773]: pam_unix(sudo:session): session closed for user root Jul 17 02:30:15 volumio volumio[975]: error: updateQueue error: null Jul 17 02:30:18 volumio volumio[975]: info: VolumeController::SetAlsaVolume70 Jul 17 02:30:18 volumio volumio[975]: info: CoreStateMachine::pushState Jul 17 02:30:18 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:30:18 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 02:30:18 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 17 02:30:19 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Jul 17 02:30:19 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CONNECTOR:32:HDMI-A-1] commit wait timed out Jul 17 02:30:30 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Jul 17 02:30:30 volumio kernel: vc4-drm gpu: [drm] *ERROR* [PLANE:88:plane-3] commit wait timed out Jul 17 02:30:35 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 17 02:30:35 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Jul 17 02:30:40 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Jul 17 02:30:40 volumio kernel: vc4-drm gpu: [drm] *ERROR* Timed out waiting for commit Jul 17 02:30:40 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 17 02:30:40 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 17 02:30:42 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 02:30:46 volumio volumio[975]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Jul 17 02:30:46 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Jul 17 02:30:46 volumio sudo[6809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jul 17 02:30:46 volumio sudo[6809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 02:30:46 volumio sudo[6809]: pam_unix(sudo:session): session closed for user root Jul 17 02:30:46 volumio volumio[975]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jul 17 02:30:46 volumio volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 02:30:46 volumio volumio[975]: TypeError: Cannot read property 'split' of undefined Jul 17 02:30:46 volumio volumio[975]: at /data/plugins/user_interface/touch_display/index.js:1193:37 Jul 17 02:30:46 volumio volumio[975]: at ChildProcess.exithandler (child_process.js:299:7) Jul 17 02:30:46 volumio volumio[975]: at ChildProcess.emit (events.js:315:20) Jul 17 02:30:46 volumio volumio[975]: at maybeClose (internal/child_process.js:1048:16) Jul 17 02:30:46 volumio volumio[975]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) Jul 17 02:30:46 volumio volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 02:30:46 volumio sudo[6820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-17 02:29 Jul 17 02:30:46 volumio sudo[6820]: 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"