-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2024-08-24 17:06:27 CEST. -- Aug 24 17:05:04 volumio volumio[1089]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 24 17:05:04 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 24 17:05:04 volumio volumio[1089]: info: Restoring Previous Volume level: false true Aug 24 17:05:04 volumio volumio[1089]: info: VolumeController::SetAlsaVolume100 Aug 24 17:05:04 volumio volumio[1089]: info: Enable softmixer device for audio device number 2 Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::volumioStop Aug 24 17:05:04 volumio volumio[1089]: info: CoreStateMachine::stop Aug 24 17:05:04 volumio volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 17:05:04 volumio volumio[1089]: info: Enable softmixer device for audio device undefined Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 24 17:05:04 volumio volumio[1089]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus DSP Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 24 17:05:04 volumio volumio[1089]: info: Disabling external Volume Control Aug 24 17:05:04 volumio volumio[1089]: info: Output device has changed, restarting MPD Aug 24 17:05:04 volumio volumio[1089]: info: Output device has changed, restarting Shairport Sync Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio sudo[2263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:05:04 volumio sudo[2263]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio sudo[2263]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 24 17:05:04 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio systemd[1]: Stopping Music Player Daemon... Aug 24 17:05:04 volumio volumio[1089]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 24 17:05:04 volumio volumio[1089]: info: Volume configurations have been set Aug 24 17:05:04 volumio systemd[1]: mpd.service: Succeeded. Aug 24 17:05:04 volumio systemd[1]: Stopped Music Player Daemon. Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 24 17:05:04 volumio volumio[1089]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus DSP Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 24 17:05:04 volumio systemd[1]: Starting Music Player Daemon... Aug 24 17:05:04 volumio volumio[1089]: info: Disabling external Volume Control Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 24 17:05:04 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 24 17:05:04 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 24 17:05:04 volumio volumio[1089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 24 17:05:04 volumio volumio[1089]: info: MPD Permissions set Aug 24 17:05:04 volumio volumio[1089]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 24 17:05:04 volumio volumio[1089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 24 17:05:04 volumio volumio[1089]: info: VolumeController:: Volume=undefined Mute =false Aug 24 17:05:04 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 24 17:05:04 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 24 17:05:04 volumio sudo[2271]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 24 17:05:04 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 24 17:05:04 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 24 17:05:04 volumio volumio[1089]: info: Software Volume ALSA configuration written Aug 24 17:05:04 volumio volumio[1089]: info: Preparing to generate the ALSA configuration file Aug 24 17:05:04 volumio volumio[1089]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 24 17:05:04 volumio volumio[1089]: info: Reading ALSA contributions from plugins. Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 24 17:05:04 volumio volumio[1089]: info: Getting Alsa Cards List without I2S DAC Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: Invalid card number. Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 24 17:05:04 volumio volumio[1089]: info: Starting Shairport Sync Aug 24 17:05:04 volumio volumio[1089]: info: Asound.conf file written Aug 24 17:05:04 volumio sudo[2298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 24 17:05:04 volumio sudo[2298]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio sudo[2301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 24 17:05:04 volumio sudo[2301]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 24 17:05:04 volumio sudo[2301]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 24 17:05:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 24 17:05:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 24 17:05:04 volumio sudo[2298]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio volumio[1089]: No state is present for card vc4hdmi0 Aug 24 17:05:04 volumio volumio[1089]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 24 17:05:04 volumio volumio[1089]: Hardware is initialized using a generic method Aug 24 17:05:04 volumio volumio[1089]: No state is present for card vc4hdmi0 Aug 24 17:05:04 volumio volumio[1089]: No state is present for card vc4hdmi1 Aug 24 17:05:04 volumio volumio[1089]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 24 17:05:04 volumio volumio[1089]: Hardware is initialized using a generic method Aug 24 17:05:04 volumio volumio[1089]: No state is present for card vc4hdmi1 Aug 24 17:05:04 volumio volumio[1089]: info: Output device has changed, restarting MPD Aug 24 17:05:04 volumio sudo[2309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 24 17:05:04 volumio sudo[2309]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio volumio[1089]: info: Output device has changed, restarting Shairport Sync Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:05:04 volumio sudo[2309]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio sudo[2311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 24 17:05:04 volumio sudo[2311]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 24 17:05:04 volumio systemd[1]: mpd.service: Succeeded. Aug 24 17:05:04 volumio systemd[1]: Stopped Music Player Daemon. Aug 24 17:05:04 volumio systemd[1]: Starting Music Player Daemon... Aug 24 17:05:04 volumio volumio[1089]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 24 17:05:04 volumio volumio[1089]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Aug 24 17:05:04 volumio volumio[1089]: aplay: main:828: audio open error: No such device Aug 24 17:05:04 volumio sudo[2317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 24 17:05:04 volumio sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio sudo[2317]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio volumio[1089]: No state is present for card vc4hdmi0 Aug 24 17:05:04 volumio volumio[1089]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 24 17:05:04 volumio volumio[1089]: Hardware is initialized using a generic method Aug 24 17:05:04 volumio volumio[1089]: No state is present for card vc4hdmi0 Aug 24 17:05:04 volumio volumio[1089]: No state is present for card vc4hdmi1 Aug 24 17:05:04 volumio volumio[1089]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 24 17:05:04 volumio volumio[1089]: Hardware is initialized using a generic method Aug 24 17:05:04 volumio volumio[1089]: No state is present for card vc4hdmi1 Aug 24 17:05:04 volumio volumio[1089]: info: Output device has changed, restarting MPD Aug 24 17:05:04 volumio sudo[2325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 24 17:05:04 volumio sudo[2325]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio volumio[1089]: info: Output device has changed, restarting Shairport Sync Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:05:04 volumio sudo[2325]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio sudo[2327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 24 17:05:04 volumio sudo[2327]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 24 17:05:04 volumio systemd[1]: mpd.service: Succeeded. Aug 24 17:05:04 volumio systemd[1]: Stopped Music Player Daemon. Aug 24 17:05:04 volumio volumio[1089]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 24 17:05:04 volumio systemd[1]: Starting Music Player Daemon... Aug 24 17:05:04 volumio volumio[1089]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 24 17:05:04 volumio volumio[1089]: info: Disabling external Volume Control Aug 24 17:05:04 volumio volumio[1089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 24 17:05:04 volumio volumio[1089]: info: MPD Permissions set Aug 24 17:05:04 volumio volumio[1089]: info: MPD Permissions set Aug 24 17:05:04 volumio volumio[1089]: info: Shairport-Sync Started Aug 24 17:05:04 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 24 17:05:04 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio sudo[2333]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 24 17:05:04 volumio sudo[2333]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio sudo[2333]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:04 volumio volumio[1089]: info: Starting Shairport Sync Aug 24 17:05:04 volumio volumio[1089]: info: Starting Shairport Sync Aug 24 17:05:04 volumio sudo[2354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 24 17:05:04 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 24 17:05:04 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 24 17:05:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 24 17:05:04 volumio sudo[2356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 24 17:05:04 volumio sudo[2356]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 24 17:05:04 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 24 17:05:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 24 17:05:04 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 24 17:05:04 volumio volumio[1089]: info: Shairport-Sync Started Aug 24 17:05:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 24 17:05:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 24 17:05:04 volumio sudo[2356]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio volumio[1089]: info: Shairport-Sync Started Aug 24 17:05:04 volumio mpd[2348]: Aug 24 17:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 24 17:05:04 volumio systemd[1]: Started Music Player Daemon. Aug 24 17:05:04 volumio sudo[2327]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio sudo[2311]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:04 volumio volumio[1089]: error: MPD error: The expression evaluated to a falsy value: Aug 24 17:05:04 volumio volumio[1089]: assert.ok(self.idling) Aug 24 17:05:04 volumio volumio[1089]: error: The expression evaluated to a falsy value: Aug 24 17:05:04 volumio volumio[1089]: assert.ok(self.idling) Aug 24 17:05:04 volumio volumio[1089]: error: MPD error: The expression evaluated to a falsy value: Aug 24 17:05:04 volumio volumio[1089]: assert.ok(self.idling) Aug 24 17:05:04 volumio volumio[1089]: error: The expression evaluated to a falsy value: Aug 24 17:05:04 volumio volumio[1089]: assert.ok(self.idling) Aug 24 17:05:04 volumio volumio[1089]: error: updateQueue error: null Aug 24 17:05:06 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 24 17:05:06 volumio sudo[2367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 24 17:05:06 volumio sudo[2367]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:06 volumio systemd[1]: Stopping Music Player Daemon... Aug 24 17:05:06 volumio systemd[1]: mpd.service: Succeeded. Aug 24 17:05:06 volumio systemd[1]: Stopped Music Player Daemon. Aug 24 17:05:06 volumio systemd[1]: Starting Music Player Daemon... Aug 24 17:05:06 volumio sudo[2369]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 24 17:05:06 volumio sudo[2369]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:06 volumio sudo[2369]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:06 volumio mpd[2372]: Aug 24 17:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 24 17:05:06 volumio systemd[1]: Started Music Player Daemon. Aug 24 17:05:06 volumio sudo[2367]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:06 volumio volumio[1089]: error: updateQueue error: null Aug 24 17:05:08 volumio volumio[1089]: info: VolumeController::SetAlsaVolumeNaN Aug 24 17:05:08 volumio volumio[1089]: info: CoreStateMachine::pushState Aug 24 17:05:08 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 24 17:05:08 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 17:05:08 volumio volumio[1089]: info: CoreCommandRouter::volumioPushState Aug 24 17:05:08 volumio volumio[1089]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Aug 24 17:05:48 volumio volumio[1089]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Aug 24 17:05:48 volumio sudo[2379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 24 17:05:48 volumio sudo[2379]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:48 volumio sudo[2379]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:48 volumio sudo[2381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 24 17:05:48 volumio sudo[2381]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 17:05:48 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 17:05:48 volumio volumio[1089]: info: MPD Permissions set Aug 24 17:05:48 volumio systemd[1]: Stopping Music Player Daemon... Aug 24 17:05:48 volumio systemd[1]: mpd.service: Succeeded. Aug 24 17:05:48 volumio systemd[1]: Stopped Music Player Daemon. Aug 24 17:05:48 volumio systemd[1]: Starting Music Player Daemon... Aug 24 17:05:48 volumio sudo[2388]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 24 17:05:48 volumio sudo[2388]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:48 volumio sudo[2388]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:48 volumio mpd[2391]: Aug 24 17:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 24 17:05:48 volumio systemd[1]: Started Music Player Daemon. Aug 24 17:05:48 volumio sudo[2381]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:48 volumio volumio[1089]: error: updateQueue error: null Aug 24 17:05:54 volumio ntpd[992]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 24 17:05:55 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 24 17:05:55 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 24 17:05:59 volumio volumio[1089]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 24 17:05:59 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 24 17:05:59 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 24 17:05:59 volumio sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 24 17:05:59 volumio sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:59 volumio sudo[2443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 24 17:05:59 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:59 volumio sudo[2438]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:59 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:59 volumio sudo[2451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 24 17:05:59 volumio sudo[2451]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:59 volumio sudo[2451]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:59 volumio sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 24 17:05:59 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:59 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:59 volumio sudo[2463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 24 17:05:59 volumio sudo[2463]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:59 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 24 17:05:59 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 24 17:05:59 volumio sudo[2463]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:59 volumio sudo[2467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 24 17:05:59 volumio sudo[2467]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:05:59 volumio sudo[2467]: pam_unix(sudo:session): session closed for user root Aug 24 17:05:59 volumio sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 24 17:05:59 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:06:03 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Aug 24 17:06:21 volumio volumio[1089]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Aug 24 17:06:21 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Aug 24 17:06:21 volumio sudo[2474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Aug 24 17:06:21 volumio sudo[2474]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:06:21 volumio sudo[2474]: pam_unix(sudo:session): session closed for user root Aug 24 17:06:21 volumio sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Aug 24 17:06:21 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:06:21 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Aug 24 17:06:21 volumio volumio[1089]: info: Permissions for /etc/network/interfaces set Aug 24 17:06:21 volumio volumio[1089]: info: Restarting Wireless Network Aug 24 17:06:21 volumio volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Aug 24 17:06:21 volumio volumio[1089]: info: Discovery: Restarting Advertising due to device name change Aug 24 17:06:21 volumio sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Aug 24 17:06:21 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:06:21 volumio systemd[1]: Starting Wireless Services... Aug 24 17:06:21 volumio wireless.js[2481]: Cleaning previous... Aug 24 17:06:21 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Aug 24 17:06:22 volumio systemd[1]: hostapd.service: Succeeded. Aug 24 17:06:22 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Aug 24 17:06:22 volumio sudo[2496]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 24 17:06:22 volumio sudo[2496]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:06:22 volumio avahi-daemon[850]: Withdrawing address record for 192.168.211.1 on wlan0. Aug 24 17:06:22 volumio avahi-daemon[850]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Aug 24 17:06:22 volumio avahi-daemon[850]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 24 17:06:22 volumio sudo[2496]: pam_unix(sudo:session): session closed for user root Aug 24 17:06:22 volumio volumio[1089]: info: Discovery: A device disappeared from network Aug 24 17:06:22 volumio volumio[1089]: info: Discovery: Device volumio disappeared from network Aug 24 17:06:22 volumio sudo[2498]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 24 17:06:22 volumio sudo[2498]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 17:06:22 volumio volumio[1089]: info: Discovery: A device disappeared from network Aug 24 17:06:22 volumio sudo[2498]: pam_unix(sudo:session): session closed for user root Aug 24 17:06:22 volumio wireless.js[2481]: Stopped aP Aug 24 17:06:22 volumio wireless.js[2481]: Wireless Networking DISABLED, not starting wireless flow Aug 24 17:06:22 volumio systemd[1]: wireless.service: Succeeded. Aug 24 17:06:22 volumio systemd[1]: Started Wireless Services. Aug 24 17:06:22 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Aug 24 17:06:24 volumio ntpd[992]: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=431 secs Aug 24 17:06:26 volumio volumio[1089]: info: Discovery: Started advertising with name: Volumio Aug 24 17:06:27 volumio volumio[1089]: info: Discovery: adding c8bc050f-367a-49dd-8520-45aa94b9656c Aug 24 17:06:27 volumio volumio[1089]: info: Discovery: Found device Volumio Aug 24 17:06:27 volumio volumio[1089]: info: CoreCommandRouter::volumioGetState Aug 24 17:06:27 volumio volumio[1089]: info: CorePlayQueue::getTrack 0 Aug 24 17:06:27 volumio volumio[1089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 17:06:27 volumio volumio[1089]: Error: The value NaN is not a number Aug 24 17:06:27 volumio volumio[1089]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Aug 24 17:06:27 volumio volumio[1089]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Aug 24 17:06:27 volumio volumio[1089]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Aug 24 17:06:27 volumio volumio[1089]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Aug 24 17:06:27 volumio volumio[1089]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Aug 24 17:06:27 volumio volumio[1089]: at Browser.emit (events.js:315:20) Aug 24 17:06:27 volumio volumio[1089]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Aug 24 17:06:27 volumio volumio[1089]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Aug 24 17:06:27 volumio volumio[1089]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Aug 24 17:06:27 volumio volumio[1089]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Aug 24 17:06:27 volumio volumio[1089]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Aug 24 17:06:27 volumio volumio[1089]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Aug 24 17:06:27 volumio volumio[1089]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Aug 24 17:06:27 volumio volumio[1089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 17:06:27 volumio sudo[2517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 17:05 Aug 24 17:06:27 volumio sudo[2517]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"