-- Logs begin at Sat 2024-11-02 17:07:15 CET, end at Sat 2024-11-02 17:31:07 CET. -- Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Nov 02 17:30:19 rpi5-volumio sudo[2526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 17:30:19 rpi5-volumio sudo[2526]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:19 rpi5-volumio sudo[2526]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:19 rpi5-volumio sudo[2529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 17:30:19 rpi5-volumio sudo[2529]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:19 rpi5-volumio volumio[1203]: info: MPD Permissions set Nov 02 17:30:19 rpi5-volumio systemd[1]: Stopping Music Player Daemon... Nov 02 17:30:19 rpi5-volumio systemd[1]: mpd.service: Succeeded. Nov 02 17:30:19 rpi5-volumio systemd[1]: Stopped Music Player Daemon. Nov 02 17:30:19 rpi5-volumio systemd[1]: Starting Music Player Daemon... Nov 02 17:30:19 rpi5-volumio sudo[2535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 17:30:19 rpi5-volumio sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:19 rpi5-volumio sudo[2535]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:19 rpi5-volumio mpd[2539]: Nov 02 17:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 17:30:19 rpi5-volumio systemd[1]: Started Music Player Daemon. Nov 02 17:30:19 rpi5-volumio sudo[2529]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:19 rpi5-volumio volumio[1203]: error: updateQueue error: null Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Restoring Previous Volume level: 100 false true Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: VolumeController::SetAlsaVolume100 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Enable softmixer device for audio device number 0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioStop Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreStateMachine::stop Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Enable softmixer device for audio device undefined Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Disabling external Volume Control Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Output device has changed, restarting MPD Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Output device has changed, restarting Shairport Sync Nov 02 17:30:26 rpi5-volumio sudo[2545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 17:30:26 rpi5-volumio sudo[2545]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:26 rpi5-volumio sudo[2545]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:26 rpi5-volumio sudo[2547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 17:30:26 rpi5-volumio sudo[2547]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio systemd[1]: Stopping Music Player Daemon... Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Volume configurations have been set Nov 02 17:30:26 rpi5-volumio systemd[1]: mpd.service: Succeeded. Nov 02 17:30:26 rpi5-volumio systemd[1]: Stopped Music Player Daemon. Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Disabling external Volume Control Nov 02 17:30:26 rpi5-volumio systemd[1]: Starting Music Player Daemon... Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: MPD Permissions set Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Software Volume ALSA configuration written Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Preparing to generate the ALSA configuration file Nov 02 17:30:26 rpi5-volumio sudo[2553]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio sudo[2553]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio sudo[2553]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Reading ALSA contributions from plugins. Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Starting Shairport Sync Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Asound.conf file written Nov 02 17:30:26 rpi5-volumio sudo[2574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 17:30:26 rpi5-volumio sudo[2574]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 17:30:26 rpi5-volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 17:30:26 rpi5-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 17:30:26 rpi5-volumio sudo[2577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 02 17:30:26 rpi5-volumio sudo[2577]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio sudo[2577]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Output device has changed, restarting MPD Nov 02 17:30:26 rpi5-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 17:30:26 rpi5-volumio sudo[2574]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Output device has changed, restarting Shairport Sync Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:26 rpi5-volumio sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 17:30:26 rpi5-volumio sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio sudo[2588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 17:30:26 rpi5-volumio sudo[2588]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio sudo[2586]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:26 rpi5-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 02 17:30:26 rpi5-volumio systemd[1]: mpd.service: Succeeded. Nov 02 17:30:26 rpi5-volumio systemd[1]: Stopped Music Player Daemon. Nov 02 17:30:26 rpi5-volumio systemd[1]: Starting Music Player Daemon... Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: aplay: main:828: audio open error: Unknown error 524 Nov 02 17:30:26 rpi5-volumio sudo[2595]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 17:30:26 rpi5-volumio sudo[2595]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio sudo[2595]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Output device has changed, restarting MPD Nov 02 17:30:26 rpi5-volumio sudo[2603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 17:30:26 rpi5-volumio sudo[2603]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Output device has changed, restarting Shairport Sync Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:26 rpi5-volumio sudo[2605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 17:30:26 rpi5-volumio sudo[2605]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio sudo[2603]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:26 rpi5-volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 02 17:30:26 rpi5-volumio systemd[1]: mpd.service: Succeeded. Nov 02 17:30:26 rpi5-volumio systemd[1]: Stopped Music Player Daemon. Nov 02 17:30:26 rpi5-volumio systemd[1]: Starting Music Player Daemon... Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Updating Volume Controller Parameters: Device: 0 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 02 17:30:26 rpi5-volumio sudo[2611]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 17:30:26 rpi5-volumio sudo[2611]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio sudo[2611]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Disabling external Volume Control Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: MPD Permissions set Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: MPD Permissions set Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Shairport-Sync Started Nov 02 17:30:26 rpi5-volumio volumio[1203]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Starting Shairport Sync Nov 02 17:30:26 rpi5-volumio volumio[1203]: info: Starting Shairport Sync Nov 02 17:30:26 rpi5-volumio sudo[2633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 17:30:26 rpi5-volumio sudo[2633]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio sudo[2635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 17:30:26 rpi5-volumio sudo[2635]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:26 rpi5-volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 17:30:26 rpi5-volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 17:30:26 rpi5-volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 17:30:26 rpi5-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 17:30:26 rpi5-volumio sudo[2635]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:27 rpi5-volumio sudo[2633]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:27 rpi5-volumio volumio[1203]: info: Shairport-Sync Started Nov 02 17:30:27 rpi5-volumio volumio[1203]: info: Shairport-Sync Started Nov 02 17:30:27 rpi5-volumio mpd[2617]: Nov 02 17:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 17:30:27 rpi5-volumio systemd[1]: Started Music Player Daemon. Nov 02 17:30:27 rpi5-volumio sudo[2605]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:27 rpi5-volumio sudo[2547]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:27 rpi5-volumio sudo[2588]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:27 rpi5-volumio volumio[1203]: error: MPD error: The expression evaluated to a falsy value: Nov 02 17:30:27 rpi5-volumio volumio[1203]: assert.ok(self.idling) Nov 02 17:30:27 rpi5-volumio volumio[1203]: error: The expression evaluated to a falsy value: Nov 02 17:30:27 rpi5-volumio volumio[1203]: assert.ok(self.idling) Nov 02 17:30:27 rpi5-volumio volumio[1203]: error: MPD error: The expression evaluated to a falsy value: Nov 02 17:30:27 rpi5-volumio volumio[1203]: assert.ok(self.idling) Nov 02 17:30:27 rpi5-volumio volumio[1203]: error: The expression evaluated to a falsy value: Nov 02 17:30:27 rpi5-volumio volumio[1203]: assert.ok(self.idling) Nov 02 17:30:27 rpi5-volumio volumio[1203]: error: updateQueue error: null Nov 02 17:30:28 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 02 17:30:28 rpi5-volumio sudo[2645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 17:30:28 rpi5-volumio sudo[2645]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:28 rpi5-volumio systemd[1]: Stopping Music Player Daemon... Nov 02 17:30:28 rpi5-volumio systemd[1]: mpd.service: Succeeded. Nov 02 17:30:28 rpi5-volumio systemd[1]: Stopped Music Player Daemon. Nov 02 17:30:28 rpi5-volumio systemd[1]: Starting Music Player Daemon... Nov 02 17:30:28 rpi5-volumio sudo[2647]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 17:30:28 rpi5-volumio sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:28 rpi5-volumio sudo[2647]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:28 rpi5-volumio mpd[2652]: Nov 02 17:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 17:30:28 rpi5-volumio systemd[1]: Started Music Player Daemon. Nov 02 17:30:28 rpi5-volumio sudo[2645]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:28 rpi5-volumio volumio[1203]: error: updateQueue error: null Nov 02 17:30:31 rpi5-volumio volumio[1203]: info: VolumeController::SetAlsaVolume100 Nov 02 17:30:31 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:31 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:31 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 17:30:31 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:31 rpi5-volumio volumio[1203]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Nov 02 17:30:31 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: Restoring Previous Volume level: 100 false true Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: VolumeController::SetAlsaVolume100 Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: Volume configurations have been set Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: Updating Volume Controller Parameters: Device: 0 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: Disabling external Volume Control Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:33 rpi5-volumio volumio[1203]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Nov 02 17:30:33 rpi5-volumio volumio[1203]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 17:30:33 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:38 rpi5-volumio volumio[1203]: info: VolumeController::SetAlsaVolume100 Nov 02 17:30:38 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:38 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:38 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 17:30:38 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:38 rpi5-volumio volumio[1203]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Nov 02 17:30:49 rpi5-volumio volumio[1203]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Nov 02 17:30:49 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Nov 02 17:30:49 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Nov 02 17:30:49 rpi5-volumio sudo[2673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 17:30:49 rpi5-volumio sudo[2673]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:49 rpi5-volumio sudo[2673]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:49 rpi5-volumio sudo[2675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 17:30:49 rpi5-volumio sudo[2675]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:49 rpi5-volumio volumio[1203]: info: MPD Permissions set Nov 02 17:30:49 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:49 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:49 rpi5-volumio systemd[1]: Stopping Music Player Daemon... Nov 02 17:30:49 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 17:30:49 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 17:30:49 rpi5-volumio systemd[1]: mpd.service: Succeeded. Nov 02 17:30:49 rpi5-volumio systemd[1]: Stopped Music Player Daemon. Nov 02 17:30:49 rpi5-volumio systemd[1]: Starting Music Player Daemon... Nov 02 17:30:49 rpi5-volumio sudo[2682]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 17:30:49 rpi5-volumio sudo[2682]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 17:30:49 rpi5-volumio sudo[2682]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:50 rpi5-volumio mpd[2684]: Nov 02 17:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 17:30:50 rpi5-volumio systemd[1]: Started Music Player Daemon. Nov 02 17:30:50 rpi5-volumio sudo[2675]: pam_unix(sudo:session): session closed for user root Nov 02 17:30:50 rpi5-volumio volumio[1203]: error: updateQueue error: null Nov 02 17:30:51 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPlay Nov 02 17:30:51 rpi5-volumio volumio[1203]: info: CoreStateMachine::play index undefined Nov 02 17:30:51 rpi5-volumio volumio[1203]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 17:30:51 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: VolumeController::SetAlsaVolume10 Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:53 rpi5-volumio volumio[1203]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: VolumeController::SetAlsaVolume50 Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 17:30:53 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:53 rpi5-volumio volumio[1203]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Nov 02 17:30:54 rpi5-volumio volumio[1203]: info: VolumeController::SetAlsaVolume55 Nov 02 17:30:54 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:54 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:54 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 17:30:54 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:54 rpi5-volumio volumio[1203]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Nov 02 17:30:55 rpi5-volumio volumio[1203]: info: VolumeController::SetAlsaVolume60 Nov 02 17:30:55 rpi5-volumio volumio[1203]: info: CoreStateMachine::pushState Nov 02 17:30:55 rpi5-volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Nov 02 17:30:55 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 17:30:55 rpi5-volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Nov 02 17:30:55 rpi5-volumio volumio[1203]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Nov 02 17:30:58 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 02 17:30:58 rpi5-volumio volumio[1203]: info: CURURI: music-library Nov 02 17:30:58 rpi5-volumio volumio[1203]: error: Failed LSINFO: null Nov 02 17:30:58 rpi5-volumio volumio[1203]: info: Preload queue cleared Nov 02 17:31:00 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 02 17:31:00 rpi5-volumio volumio[1203]: info: Preload queue cleared Nov 02 17:31:03 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 02 17:31:03 rpi5-volumio volumio[1203]: info: Preload queue cleared Nov 02 17:31:04 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 02 17:31:04 rpi5-volumio volumio[1203]: info: Preload queue cleared Nov 02 17:31:06 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 02 17:31:06 rpi5-volumio volumio[1203]: info: Preload queue cleared Nov 02 17:31:07 rpi5-volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 02 17:31:07 rpi5-volumio volumio[1203]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 17:31:07 rpi5-volumio volumio[1203]: TypeError: Cannot read property 'length' of undefined Nov 02 17:31:07 rpi5-volumio volumio[1203]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 02 17:31:07 rpi5-volumio volumio[1203]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 02 17:31:07 rpi5-volumio volumio[1203]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at Parser.emit (events.js:315:20) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at IncomingMessage.emit (events.js:327:22) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 02 17:31:07 rpi5-volumio volumio[1203]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 02 17:31:07 rpi5-volumio volumio[1203]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 17:31:07 rpi5-volumio sudo[2700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 17:30 Nov 02 17:31:07 rpi5-volumio sudo[2700]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"