-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-04-14 16:28:28 CEST. -- Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 16:27:01 volumio-3 volumio[962]: info: Getting Alsa Cards List without I2S DAC Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:01 volumio-3 volumio[962]: Invalid card number. Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 14 16:27:01 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 16:27:02 volumio-3 volumiologrotate[597]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 14 16:27:02 volumio-3 volumiologrotate[597]: ls: cannot access '3': No such file or directory Apr 14 16:27:03 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 16:27:04 volumio-3 volumio[962]: info: BOOT COMPLETED Apr 14 16:27:11 volumio-3 ntpd[769]: Soliciting pool server 178.239.19.61 Apr 14 16:27:11 volumio-3 ntpd[769]: Soliciting pool server 194.50.19.204 Apr 14 16:27:11 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 16:27:11 volumio-3 volumio[962]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 16:27:11 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 16:27:11 volumio-3 volumio[962]: info: Received Get System Version Apr 14 16:27:11 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 16:27:11 volumio-3 volumio[962]: info: Received Get System Info Apr 14 16:27:11 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:27:11 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:27:11 volumio-3 volumio[962]: info: Discovery: Getting this device information Apr 14 16:27:11 volumio-3 volumio[962]: info: CoreCommandRouter::volumioGetState Apr 14 16:27:11 volumio-3 volumio[962]: info: CorePlayQueue::getTrack 0 Apr 14 16:27:11 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:27:12 volumio-3 ntpd[769]: Soliciting pool server 185.224.145.68 Apr 14 16:27:12 volumio-3 ntpd[769]: Soliciting pool server 213.239.234.28 Apr 14 16:27:12 volumio-3 ntpd[769]: Soliciting pool server 162.159.200.1 Apr 14 16:27:12 volumio-3 ntpd[769]: Soliciting pool server 194.50.19.117 Apr 14 16:27:13 volumio-3 ntpd[769]: Soliciting pool server 178.215.228.24 Apr 14 16:27:13 volumio-3 ntpd[769]: Soliciting pool server 80.60.67.39 Apr 14 16:27:13 volumio-3 ntpd[769]: Soliciting pool server 159.69.64.189 Apr 14 16:27:13 volumio-3 ntpd[769]: Soliciting pool server 79.133.44.138 Apr 14 16:27:14 volumio-3 ntpd[769]: Soliciting pool server 5.255.99.180 Apr 14 16:27:14 volumio-3 ntpd[769]: Soliciting pool server 83.98.201.134 Apr 14 16:27:14 volumio-3 ntpd[769]: Soliciting pool server 128.140.109.119 Apr 14 16:27:14 volumio-3 ntpd[769]: Soliciting pool server 90.187.112.137 Apr 14 16:27:15 volumio-3 ntpd[769]: Soliciting pool server 45.83.205.227 Apr 14 16:27:15 volumio-3 ntpd[769]: Soliciting pool server 2a02:e00:fff0:5db::1 Apr 14 16:27:15 volumio-3 ntpd[769]: Soliciting pool server 94.16.122.152 Apr 14 16:27:15 volumio-3 ntpd[769]: Soliciting pool server 185.232.69.65 Apr 14 16:27:15 volumio-3 volumio[962]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::volumioGetState Apr 14 16:27:15 volumio-3 volumio[962]: info: CorePlayQueue::getTrack 0 Apr 14 16:27:15 volumio-3 volumio[962]: info: Restoring Previous Volume level: false true Apr 14 16:27:15 volumio-3 volumio[962]: info: VolumeController::SetAlsaVolume100 Apr 14 16:27:15 volumio-3 volumio[962]: info: Enable softmixer device for audio device number 2 Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::volumioStop Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreStateMachine::stop Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 16:27:15 volumio-3 volumio[962]: info: Enable softmixer device for audio device undefined Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 14 16:27:15 volumio-3 volumio[962]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC2 Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 14 16:27:15 volumio-3 volumio[962]: info: Disabling external Volume Control Apr 14 16:27:15 volumio-3 volumio[962]: info: Output device has changed, restarting MPD Apr 14 16:27:15 volumio-3 volumio[962]: info: Output device has changed, restarting Shairport Sync Apr 14 16:27:15 volumio-3 sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:15 volumio-3 sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:15 volumio-3 sudo[1404]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:15 volumio-3 sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 16:27:15 volumio-3 sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:15 volumio-3 systemd[1]: Stopping Music Player Daemon... Apr 14 16:27:15 volumio-3 volumio[962]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 16:27:15 volumio-3 volumio[962]: info: Volume configurations have been set Apr 14 16:27:15 volumio-3 systemd[1]: mpd.service: Succeeded. Apr 14 16:27:15 volumio-3 systemd[1]: Stopped Music Player Daemon. Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 14 16:27:15 volumio-3 volumio[962]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC2 Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 14 16:27:15 volumio-3 volumio[962]: info: Disabling external Volume Control Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreStateMachine::pushState Apr 14 16:27:15 volumio-3 volumio[962]: info: CorePlayQueue::getTrack 0 Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::volumioPushState Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreStateMachine::pushState Apr 14 16:27:15 volumio-3 volumio[962]: info: CorePlayQueue::getTrack 0 Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::volumioPushState Apr 14 16:27:15 volumio-3 volumio[962]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 14 16:27:15 volumio-3 volumio[962]: info: MPD Permissions set Apr 14 16:27:15 volumio-3 volumio[962]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 14 16:27:15 volumio-3 volumio[962]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 14 16:27:15 volumio-3 volumio[962]: info: Software Volume ALSA configuration written Apr 14 16:27:15 volumio-3 volumio[962]: info: Preparing to generate the ALSA configuration file Apr 14 16:27:15 volumio-3 systemd[1]: Starting Music Player Daemon... Apr 14 16:27:15 volumio-3 volumio[962]: info: VolumeController:: Volume=undefined Mute =false Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreStateMachine::pushState Apr 14 16:27:15 volumio-3 volumio[962]: info: CorePlayQueue::getTrack 0 Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::volumioPushState Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 sudo[1414]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 16:27:15 volumio-3 sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:15 volumio-3 sudo[1414]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 14 16:27:15 volumio-3 volumio[962]: info: Getting Alsa Cards List without I2S DAC Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: Invalid card number. Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 14 16:27:15 volumio-3 volumio[962]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 14 16:27:15 volumio-3 volumio[962]: info: Reading ALSA contributions from plugins. Apr 14 16:27:15 volumio-3 volumio[962]: info: Starting Shairport Sync Apr 14 16:27:15 volumio-3 volumio[962]: info: Asound.conf file written Apr 14 16:27:15 volumio-3 sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 16:27:15 volumio-3 sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:15 volumio-3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 16:27:15 volumio-3 systemd[1]: shairport-sync.service: Succeeded. Apr 14 16:27:15 volumio-3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 16:27:15 volumio-3 sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 14 16:27:15 volumio-3 sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:15 volumio-3 volumio[962]: info: Output device has changed, restarting MPD Apr 14 16:27:15 volumio-3 sudo[1441]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:15 volumio-3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 16:27:15 volumio-3 sudo[1438]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:15 volumio-3 sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 16:27:15 volumio-3 sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:15 volumio-3 sudo[1447]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:15 volumio-3 volumio[962]: info: Output device has changed, restarting Shairport Sync Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:15 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:16 volumio-3 sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 16:27:16 volumio-3 sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:16 volumio-3 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 16:27:16 volumio-3 systemd[1]: mpd.service: Succeeded. Apr 14 16:27:16 volumio-3 systemd[1]: Stopped Music Player Daemon. Apr 14 16:27:16 volumio-3 volumio[962]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 16:27:16 volumio-3 systemd[1]: Starting Music Player Daemon... Apr 14 16:27:16 volumio-3 volumio[962]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Apr 14 16:27:16 volumio-3 volumio[962]: aplay: main:828: audio open error: No such device Apr 14 16:27:16 volumio-3 sudo[1457]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 16:27:16 volumio-3 sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:16 volumio-3 volumio[962]: info: Output device has changed, restarting MPD Apr 14 16:27:16 volumio-3 sudo[1457]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:16 volumio-3 volumio[962]: info: Output device has changed, restarting Shairport Sync Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:16 volumio-3 sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 16:27:16 volumio-3 sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:16 volumio-3 sudo[1465]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:16 volumio-3 sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 16:27:16 volumio-3 sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:16 volumio-3 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 16:27:16 volumio-3 systemd[1]: mpd.service: Succeeded. Apr 14 16:27:16 volumio-3 systemd[1]: Stopped Music Player Daemon. Apr 14 16:27:16 volumio-3 volumio[962]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 16:27:16 volumio-3 systemd[1]: Starting Music Player Daemon... Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 14 16:27:16 volumio-3 volumio[962]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 14 16:27:16 volumio-3 volumio[962]: info: Disabling external Volume Control Apr 14 16:27:16 volumio-3 volumio[962]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 14 16:27:16 volumio-3 sudo[1473]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 16:27:16 volumio-3 volumio[962]: info: MPD Permissions set Apr 14 16:27:16 volumio-3 volumio[962]: info: Shairport-Sync Started Apr 14 16:27:16 volumio-3 sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreStateMachine::pushState Apr 14 16:27:16 volumio-3 volumio[962]: info: CorePlayQueue::getTrack 0 Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::volumioPushState Apr 14 16:27:16 volumio-3 sudo[1473]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 16:27:16 volumio-3 volumio[962]: info: Starting Shairport Sync Apr 14 16:27:16 volumio-3 volumio[962]: info: Starting Shairport Sync Apr 14 16:27:16 volumio-3 sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 16:27:16 volumio-3 sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:16 volumio-3 sudo[1496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 16:27:16 volumio-3 sudo[1496]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:16 volumio-3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 16:27:16 volumio-3 systemd[1]: shairport-sync.service: Succeeded. Apr 14 16:27:16 volumio-3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 16:27:16 volumio-3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 16:27:16 volumio-3 sudo[1496]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:16 volumio-3 volumio[962]: info: Shairport-Sync Started Apr 14 16:27:16 volumio-3 sudo[1494]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:16 volumio-3 volumio[962]: info: Shairport-Sync Started Apr 14 16:27:16 volumio-3 mpd[1484]: Apr 14 16:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 16:27:16 volumio-3 systemd[1]: Started Music Player Daemon. Apr 14 16:27:16 volumio-3 sudo[1406]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:16 volumio-3 sudo[1450]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:16 volumio-3 sudo[1467]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:16 volumio-3 volumio[962]: error: MPD error: The expression evaluated to a falsy value: Apr 14 16:27:16 volumio-3 volumio[962]: assert.ok(self.idling) Apr 14 16:27:16 volumio-3 volumio[962]: error: The expression evaluated to a falsy value: Apr 14 16:27:16 volumio-3 volumio[962]: assert.ok(self.idling) Apr 14 16:27:16 volumio-3 volumio[962]: error: MPD error: The expression evaluated to a falsy value: Apr 14 16:27:16 volumio-3 volumio[962]: assert.ok(self.idling) Apr 14 16:27:16 volumio-3 volumio[962]: error: The expression evaluated to a falsy value: Apr 14 16:27:16 volumio-3 volumio[962]: assert.ok(self.idling) Apr 14 16:27:16 volumio-3 volumio[962]: error: updateQueue error: null Apr 14 16:27:17 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 14 16:27:17 volumio-3 sudo[1505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 16:27:17 volumio-3 sudo[1505]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:17 volumio-3 systemd[1]: Stopping Music Player Daemon... Apr 14 16:27:17 volumio-3 systemd[1]: mpd.service: Succeeded. Apr 14 16:27:17 volumio-3 systemd[1]: Stopped Music Player Daemon. Apr 14 16:27:17 volumio-3 systemd[1]: Starting Music Player Daemon... Apr 14 16:27:17 volumio-3 sudo[1507]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 16:27:17 volumio-3 sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 16:27:17 volumio-3 sudo[1507]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:19 volumio-3 ntpd[769]: receive: Unexpected origin timestamp 0xeba799c6.2742215b does not match aorg 0000000000.00000000 from server@80.60.67.39 xmt 0xeba799c7.446e7c71 Apr 14 16:27:19 volumio-3 ntpd[769]: receive: Unexpected origin timestamp 0xeba799c6.27454f7c does not match aorg 0000000000.00000000 from server@178.239.19.61 xmt 0xeba799c7.449553ef Apr 14 16:27:19 volumio-3 ntpd[769]: receive: Unexpected origin timestamp 0xeba799c6.2743c1f8 does not match aorg 0000000000.00000000 from server@213.239.234.28 xmt 0xeba799c7.446f38ed Apr 14 16:27:19 volumio-3 ntpd[769]: receive: Unexpected origin timestamp 0xeba799c6.273d3880 does not match aorg 0000000000.00000000 from server@94.16.122.152 xmt 0xeba799c7.44ba4f10 Apr 14 16:27:19 volumio-3 ntpd[769]: receive: Unexpected origin timestamp 0xeba799c6.273980c3 does not match aorg 0000000000.00000000 from server@185.232.69.65 xmt 0xeba799c7.4481c32c Apr 14 16:27:19 volumio-3 ntpd[769]: receive: Unexpected origin timestamp 0xeba799c6.273f11a1 does not match aorg 0000000000.00000000 from server@90.187.112.137 xmt 0xeba799c7.443da2e6 Apr 14 16:27:19 volumio-3 ntpd[769]: receive: Unexpected origin timestamp 0xeba799c6.274126a8 does not match aorg 0000000000.00000000 from server@159.69.64.189 xmt 0xeba799c7.4473bc9f Apr 14 16:27:19 volumio-3 mpd[1509]: Apr 14 16:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 16:27:19 volumio-3 systemd[1]: Started Music Player Daemon. Apr 14 16:27:19 volumio-3 sudo[1505]: pam_unix(sudo:session): session closed for user root Apr 14 16:27:19 volumio-3 volumio[962]: error: updateQueue error: null Apr 14 16:27:21 volumio-3 volumio[962]: info: VolumeController::SetAlsaVolumeNaN Apr 14 16:27:21 volumio-3 volumio[962]: info: CoreStateMachine::pushState Apr 14 16:27:21 volumio-3 volumio[962]: info: CorePlayQueue::getTrack 0 Apr 14 16:27:21 volumio-3 volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 16:27:21 volumio-3 volumio[962]: info: CoreCommandRouter::volumioPushState Apr 14 16:27:21 volumio-3 volumio[962]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 14 16:28:03 volumio-3 volumiologrotate[597]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 14 16:28:03 volumio-3 volumiologrotate[597]: ls: cannot access '3': No such file or directory Apr 14 16:28:18 volumio-3 wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Apr 14 16:28:20 volumio-3 wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Apr 14 16:28:20 volumio-3 wpa_supplicant[863]: wlan0: Associated with e8:df:70:7f:ac:98 Apr 14 16:28:20 volumio-3 dhcpcd[875]: wlan0: carrier lost Apr 14 16:28:20 volumio-3 wpa_supplicant[863]: wlan0: CTRL-EVENT-CONNECTED - Connection to e8:df:70:7f:ac:98 completed [id=0 id_str=] Apr 14 16:28:20 volumio-3 wpa_supplicant[863]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 14 16:28:20 volumio-3 avahi-daemon[604]: Withdrawing address record for 192.168.2.68 on wlan0. Apr 14 16:28:20 volumio-3 avahi-daemon[604]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.68. Apr 14 16:28:20 volumio-3 dhcpcd[875]: wlan0: deleting route to 192.168.2.0/24 Apr 14 16:28:20 volumio-3 dhcpcd[875]: wlan0: deleting default route via 192.168.2.1 Apr 14 16:28:20 volumio-3 avahi-daemon[604]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 14 16:28:20 volumio-3 volumio[962]: info: Discovery: A device disappeared from network Apr 14 16:28:20 volumio-3 volumio[962]: info: Discovery: Device volumio2-wz disappeared from network Apr 14 16:28:20 volumio-3 volumio[962]: info: Discovery: Disconnected from remote: 192.168.2.25 Apr 14 16:28:20 volumio-3 volumio[962]: info: Discovery: A device disappeared from network Apr 14 16:28:20 volumio-3 dhcpcd[875]: wlan0: carrier acquired Apr 14 16:28:20 volumio-3 dhcpcd[875]: wlan0: IAID 01:6b:9b:8c Apr 14 16:28:21 volumio-3 dhcpcd[875]: wlan0: soliciting an IPv6 router Apr 14 16:28:21 volumio-3 dhcpcd[875]: wlan0: rebinding lease of 192.168.2.68 Apr 14 16:28:21 volumio-3 dhcpcd[875]: wlan0: probing address 192.168.2.68/24 Apr 14 16:28:22 volumio-3 ntpd[769]: Deleting interface #3 wlan0, 192.168.2.68#123, interface stats: received=112, sent=112, dropped=0, active_time=120 secs Apr 14 16:28:22 volumio-3 ntpd[769]: 185.232.69.65 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 94.16.122.152 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 45.83.205.227 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 90.187.112.137 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 128.140.109.119 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 5.255.99.180 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 83.98.201.134 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 159.69.64.189 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 80.60.67.39 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 79.133.44.138 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 178.215.228.24 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 194.50.19.117 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 162.159.200.1 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 185.224.145.68 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 213.239.234.28 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 194.50.19.204 local addr 192.168.2.68 -> Apr 14 16:28:22 volumio-3 ntpd[769]: 178.239.19.61 local addr 192.168.2.68 -> Apr 14 16:28:25 volumio-3 volumio[962]: info: Volumio Network Manager: Network status updated: 0 Apr 14 16:28:27 volumio-3 dhcpcd[875]: wlan0: leased 192.168.2.68 for 315360000 seconds Apr 14 16:28:27 volumio-3 avahi-daemon[604]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.2.68. Apr 14 16:28:27 volumio-3 dhcpcd[875]: wlan0: adding route to 192.168.2.0/24 Apr 14 16:28:27 volumio-3 dhcpcd[875]: wlan0: adding default route via 192.168.2.1 Apr 14 16:28:27 volumio-3 avahi-daemon[604]: New relevant interface wlan0.IPv4 for mDNS. Apr 14 16:28:27 volumio-3 avahi-daemon[604]: Registering new address record for 192.168.2.68 on wlan0.IPv4. Apr 14 16:28:27 volumio-3 volumio[962]: info: Discovery: adding 4c3b49e6-b5c8-434c-93d1-7fc733830c94 Apr 14 16:28:27 volumio-3 volumio[962]: info: Discovery: Found device Volumio2-wz Apr 14 16:28:27 volumio-3 volumio[962]: info: Discovery: Connecting to remote: 192.168.2.25 Apr 14 16:28:27 volumio-3 volumio[962]: info: Discovery: Connected to remote: 192.168.2.25 Apr 14 16:28:27 volumio-3 wpa_supplicant[863]: RRM: Ignoring radio measurement request: Not RRM network Apr 14 16:28:28 volumio-3 volumio[962]: info: Discovery: adding 2116abc0-06ba-42fe-82f5-16debf9b20eb Apr 14 16:28:28 volumio-3 volumio[962]: info: Discovery: Found device Volumio 3 Apr 14 16:28:28 volumio-3 volumio[962]: info: CoreCommandRouter::volumioGetState Apr 14 16:28:28 volumio-3 volumio[962]: info: CorePlayQueue::getTrack 0 Apr 14 16:28:28 volumio-3 volumio[962]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 16:28:28 volumio-3 volumio[962]: Error: The value NaN is not a number Apr 14 16:28:28 volumio-3 volumio[962]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Apr 14 16:28:28 volumio-3 volumio[962]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Apr 14 16:28:28 volumio-3 volumio[962]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Apr 14 16:28:28 volumio-3 volumio[962]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Apr 14 16:28:28 volumio-3 volumio[962]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Apr 14 16:28:28 volumio-3 volumio[962]: at Browser.emit (events.js:315:20) Apr 14 16:28:28 volumio-3 volumio[962]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Apr 14 16:28:28 volumio-3 volumio[962]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Apr 14 16:28:28 volumio-3 volumio[962]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Apr 14 16:28:28 volumio-3 volumio[962]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Apr 14 16:28:28 volumio-3 volumio[962]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Apr 14 16:28:28 volumio-3 volumio[962]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Apr 14 16:28:28 volumio-3 volumio[962]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Apr 14 16:28:28 volumio-3 volumio[962]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 16:28:28 volumio-3 ntpd[769]: Listen normally on 4 wlan0 192.168.2.68:123 Apr 14 16:28:28 volumio-3 ntpd[769]: new interface(s) found: waking up resolver Apr 14 16:28:28 volumio-3 sudo[1729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-14 16:27 Apr 14 16:28:28 volumio-3 sudo[1729]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"