-- Logs begin at Thu 2023-03-30 15:50:15 UTC, end at Thu 2023-03-30 15:55:45 UTC. -- Mar 30 15:54:02 volumio volumio[1144]: .....................................................................................................++++ Mar 30 15:54:05 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Mar 30 15:54:05 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 30 15:54:05 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 30 15:54:05 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:05 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:54:17 volumio ntpd[547]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 30 15:54:17 volumio ntpd[547]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 30 15:54:17 volumio ntpd[547]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 30 15:54:19 volumio ntpd[547]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 30 15:54:38 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 30 15:54:38 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:54:38 volumio volumio[1144]: ...............................................................................................................................++++ Mar 30 15:54:38 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 30 15:54:38 volumio volumio[1144]: e is 65537 (0x010001) Mar 30 15:54:38 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:38 volumio volumio[655]: info: Enabling I2S DAC: Generic I2S DAC Mar 30 15:54:38 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Mar 30 15:54:38 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:39 volumio volumio[1144]: writing RSA key Mar 30 15:54:39 volumio sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 30 15:54:39 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:39 volumio sudo[1181]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:39 volumio volumio[655]: info: No Overlays Loaded Mar 30 15:54:39 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:39 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 30 15:54:39 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:39 volumio sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac Mar 30 15:54:39 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Mar 30 15:54:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Mar 30 15:54:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Mar 30 15:54:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Mar 30 15:54:39 volumio volumio[655]: warn: Unable to locate the device Generic I2S DAC a reboot may be required. Mar 30 15:54:39 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:40 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:54:40 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 30 15:54:40 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:40 volumio volumio[655]: Invalid card number. Mar 30 15:54:40 volumio systemd-udevd[1202]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 99. Mar 30 15:54:40 volumio volumio[655]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 30 15:54:40 volumio volumio[655]: info: Updating Volume Controller Parameters: Device: 2 Name: Generic I2S DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 30 15:54:40 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 30 15:54:40 volumio volumio[655]: info: Disabling external Volume Control Mar 30 15:54:40 volumio volumio[655]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:54:40 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Mar 30 15:54:40 volumio volumio[655]: info: Preparing to generate the ALSA configuration file Mar 30 15:54:40 volumio volumio[655]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 30 15:54:41 volumio volumio[655]: info: I2S Param [object Object] successfully enabled Mar 30 15:54:41 volumio volumio[655]: info: VolumeController:: Volume=undefined Mute =false Mar 30 15:54:41 volumio volumio[655]: info: CoreStateMachine::pushState Mar 30 15:54:41 volumio volumio[655]: info: CorePlayQueue::getTrack 0 Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::volumioPushState Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:54:41 volumio volumio[655]: info: Getting Alsa Cards List without I2S DAC Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:41 volumio volumio[655]: Invalid card number. Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 30 15:54:41 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 30 15:54:41 volumio sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 15:54:42 volumio sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:42 volumio volumio[655]: info: Asound.conf file written Mar 30 15:54:42 volumio sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 30 15:54:42 volumio sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:42 volumio sudo[1231]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:42 volumio volumio[655]: No state is present for card b1 Mar 30 15:54:42 volumio volumio[655]: No state is present for card sndrpihifiberry Mar 30 15:54:42 volumio volumio[655]: Found hardware: "RPi-simple" "" "" "" "" Mar 30 15:54:42 volumio volumio[655]: Hardware is initialized using a generic method Mar 30 15:54:42 volumio volumio[655]: No state is present for card sndrpihifiberry Mar 30 15:54:42 volumio volumio[655]: info: Output device has changed, restarting MPD Mar 30 15:54:42 volumio volumio[655]: info: Output device has changed, restarting Shairport Sync Mar 30 15:54:42 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:42 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:42 volumio sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 15:54:42 volumio sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:42 volumio sudo[1236]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:42 volumio sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 15:54:42 volumio sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:42 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 15:54:42 volumio volumio[655]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 15:54:42 volumio volumio[655]: info: Output device has changed, restarting MPD Mar 30 15:54:42 volumio systemd[1]: mpd.service: Succeeded. Mar 30 15:54:42 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 15:54:42 volumio sudo[1228]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:42 volumio systemd[1]: Starting Music Player Daemon... Mar 30 15:54:43 volumio volumio[655]: info: Output device has changed, restarting Shairport Sync Mar 30 15:54:43 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:43 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:43 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 15:54:43 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:43 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:43 volumio sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 15:54:43 volumio sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:43 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 30 15:54:43 volumio systemd[1]: mpd.service: Succeeded. Mar 30 15:54:43 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 15:54:43 volumio volumio[655]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 15:54:43 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 30 15:54:43 volumio systemd[1]: Starting Music Player Daemon... Mar 30 15:54:43 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 30 15:54:44 volumio sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 15:54:44 volumio sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:44 volumio volumio[655]: info: MPD Permissions set Mar 30 15:54:44 volumio volumio[655]: info: MPD Permissions set Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 30 15:54:44 volumio volumio[655]: info: CoreStateMachine::getcurrentVolume Mar 30 15:54:44 volumio volumio[655]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 15:54:45 volumio volumio[655]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 30 15:54:45 volumio volumio[655]: info: Starting Shairport Sync Mar 30 15:54:45 volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:45 volumio volumio[655]: info: Starting Shairport Sync Mar 30 15:54:45 volumio volumio[655]: info: VolumeController:: Volume=undefined Mute =false Mar 30 15:54:45 volumio volumio[655]: info: CoreStateMachine::pushState Mar 30 15:54:45 volumio volumio[655]: info: CorePlayQueue::getTrack 0 Mar 30 15:54:45 volumio volumio[655]: info: CoreCommandRouter::volumioPushState Mar 30 15:54:45 volumio sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 30 15:54:45 volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:45 volumio volumio[655]: info: CoreStateMachine::updateTrackBlock Mar 30 15:54:45 volumio volumio[655]: info: CorePlayQueue::getTrackBlock Mar 30 15:54:45 volumio volumio[655]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 15:54:45 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 30 15:54:45 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 30 15:54:46 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 30 15:54:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 30 15:54:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 30 15:54:46 volumio sudo[1272]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:46 volumio volumio[655]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 30 15:54:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 30 15:54:46 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 30 15:54:46 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 30 15:54:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 30 15:54:46 volumio volumio[655]: info: Shairport-Sync Started Mar 30 15:54:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 30 15:54:46 volumio volumio[655]: info: VolumeController:: Volume=undefined Mute =false Mar 30 15:54:46 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:46 volumio volumio[655]: info: CoreStateMachine::pushState Mar 30 15:54:46 volumio volumio[655]: info: CorePlayQueue::getTrack 0 Mar 30 15:54:46 volumio volumio[655]: info: CoreCommandRouter::volumioPushState Mar 30 15:54:47 volumio volumio[655]: info: Shairport-Sync Started Mar 30 15:54:51 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 30 15:54:51 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 30 15:54:51 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:51 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:54:52 volumio mpd[1269]: Mar 30 15:54 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 15:54:52 volumio mpd[1269]: Mar 30 15:54 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 30 15:54:52 volumio mpd[1269]: Mar 30 15:54 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 30 15:54:52 volumio systemd[1]: Started Music Player Daemon. Mar 30 15:54:52 volumio sudo[1238]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:52 volumio sudo[1248]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:52 volumio volumio[655]: error: MPD error: The expression evaluated to a falsy value: Mar 30 15:54:52 volumio volumio[655]: assert.ok(self.idling) Mar 30 15:54:52 volumio volumio[655]: error: The expression evaluated to a falsy value: Mar 30 15:54:52 volumio volumio[655]: assert.ok(self.idling) Mar 30 15:54:52 volumio volumio[655]: error: updateQueue error: null Mar 30 15:54:53 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 30 15:54:53 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:54:53 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 30 15:54:53 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:53 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 30 15:54:53 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:54 volumio volumio[655]: info: Device 1 does not have any Mixer Control Available Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 30 15:54:54 volumio volumio[655]: info: Updating Volume Controller Parameters: Device: 1 Name: Generic I2S DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 30 15:54:54 volumio volumio[655]: info: Disabling external Volume Control Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:54:54 volumio volumio[655]: info: CoreStateMachine::pushState Mar 30 15:54:54 volumio volumio[655]: info: CorePlayQueue::getTrack 0 Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::volumioPushState Mar 30 15:54:54 volumio volumio[655]: info: Preparing to generate the ALSA configuration file Mar 30 15:54:54 volumio volumio[655]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:54:54 volumio volumio[655]: info: Getting Alsa Cards List without I2S DAC Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 30 15:54:54 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 30 15:54:55 volumio volumio[655]: info: Asound.conf file unchanged, so no further update is needed Mar 30 15:54:55 volumio volumio[655]: info: Output device has changed, restarting MPD Mar 30 15:54:55 volumio volumio[655]: info: Output device has changed, restarting Shairport Sync Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:55 volumio sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 15:54:55 volumio sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:55 volumio sudo[1305]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:55 volumio sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 15:54:55 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:55 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 15:54:55 volumio volumio[655]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 15:54:55 volumio systemd[1]: mpd.service: Succeeded. Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 30 15:54:55 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 15:54:55 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 30 15:54:55 volumio systemd[1]: Starting Music Player Daemon... Mar 30 15:54:56 volumio volumio[655]: info: MPD Permissions set Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 30 15:54:56 volumio sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 15:54:56 volumio sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:56 volumio sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 15:54:56 volumio sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:54:56 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:54:56 volumio sudo[1318]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:56 volumio volumio[655]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 15:54:56 volumio volumio[655]: wlan0 Interface doesn't support scanning : Device or resource busy Mar 30 15:54:56 volumio volumio[655]: info: Cannot use regular scanning, forcing with ap-force Mar 30 15:54:56 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 15:54:56 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:56 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:56 volumio volumio[655]: command failed: Device or resource busy (-16) Mar 30 15:54:56 volumio volumio[655]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 15:54:56 volumio volumio[655]: command failed: Device or resource busy (-16) Mar 30 15:54:57 volumio volumio[655]: info: Starting Shairport Sync Mar 30 15:54:57 volumio sudo[1315]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:57 volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 30 15:54:57 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:57 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 30 15:54:57 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 30 15:54:57 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 30 15:54:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 30 15:54:57 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:57 volumio volumio[655]: info: Shairport-Sync Started Mar 30 15:54:58 volumio volumio[655]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 30 15:54:58 volumio volumio[655]: info: CoreStateMachine::getcurrentVolume Mar 30 15:54:58 volumio volumio[655]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 15:54:58 volumio volumio[655]: info: CoreStateMachine::pushState Mar 30 15:54:58 volumio volumio[655]: info: CorePlayQueue::getTrack 0 Mar 30 15:54:58 volumio volumio[655]: info: CoreCommandRouter::volumioPushState Mar 30 15:55:02 volumio mpd[1330]: Mar 30 15:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 15:55:02 volumio mpd[1330]: Mar 30 15:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 30 15:55:02 volumio mpd[1330]: Mar 30 15:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 30 15:55:02 volumio systemd[1]: Started Music Player Daemon. Mar 30 15:55:02 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:02 volumio volumio[655]: error: updateQueue error: null Mar 30 15:55:11 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Mar 30 15:55:11 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Mar 30 15:55:11 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 15:55:11 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:11 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:13 volumio volumio[655]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:55:15 volumio volumio[655]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:55:16 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Mar 30 15:55:16 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Mar 30 15:55:16 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Mar 30 15:55:16 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Mar 30 15:55:16 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Mar 30 15:55:17 volumio volumio[655]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:55:18 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Mar 30 15:55:18 volumio volumio[655]: info: CoreCommandRouter::Reload Ui Mar 30 15:55:18 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Mar 30 15:55:18 volumio volumio[655]: info: Wizard terminated Successfully Mar 30 15:55:18 volumio volumio[655]: info: Player Reboot required after I2S DAC has been enabled in wizard Mar 30 15:55:18 volumio volumio[655]: info: Executing Deferred Wifi Connection Mar 30 15:55:18 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Mar 30 15:55:18 volumio volumio[655]: info: Saving new wireless network Mar 30 15:55:18 volumio sudo[1346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Mar 30 15:55:18 volumio sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:18 volumio sudo[1346]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:18 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 30 15:55:18 volumio volumio[655]: info: Discovery: Restarting Advertising due to device name change Mar 30 15:55:18 volumio sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 30 15:55:18 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:19 volumio systemd[1]: Starting Wireless Services... Mar 30 15:55:19 volumio volumio[655]: info: Discovery: A device disappeared from network Mar 30 15:55:19 volumio volumio[655]: info: Discovery: Device volumio disappeared from network Mar 30 15:55:22 volumio ntpd[547]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 30 15:55:22 volumio ntpd[547]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 30 15:55:23 volumio volumio[655]: info: Discovery: Started advertising with name: Volumio Mar 30 15:55:23 volumio wireless.js[1351]: Cleaning previous... Mar 30 15:55:24 volumio ntpd[547]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 30 15:55:24 volumio ntpd[547]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 30 15:55:24 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 30 15:55:24 volumio volumio[655]: info: Discovery: adding undefined Mar 30 15:55:24 volumio volumio[655]: info: Discovery: Found device Volumio Mar 30 15:55:24 volumio volumio[655]: info: CoreCommandRouter::volumioGetState Mar 30 15:55:24 volumio volumio[655]: info: CorePlayQueue::getTrack 0 Mar 30 15:55:24 volumio systemd[1]: hostapd.service: Succeeded. Mar 30 15:55:24 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 30 15:55:25 volumio sudo[1369]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 30 15:55:25 volumio sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:25 volumio avahi-daemon[365]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 30 15:55:25 volumio avahi-daemon[365]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 30 15:55:25 volumio avahi-daemon[365]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 30 15:55:25 volumio volumio[655]: info: Discovery: A device disappeared from network Mar 30 15:55:25 volumio volumio[655]: info: Discovery: Device volumio disappeared from network Mar 30 15:55:25 volumio sudo[1369]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:25 volumio sudo[1371]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 30 15:55:25 volumio sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:25 volumio sudo[1371]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:25 volumio wireless.js[1351]: Stopped aP Mar 30 15:55:26 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 30 15:55:26 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:26 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 30 15:55:26 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:26 volumio sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 30 15:55:26 volumio sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:26 volumio sudo[1380]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:26 volumio sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 30 15:55:26 volumio sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:26 volumio sudo[1387]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:26 volumio sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 30 15:55:26 volumio sudo[1389]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:27 volumio sudo[1389]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:27 volumio wireless.js[1351]: Start wireless flow Mar 30 15:55:27 volumio wireless.js[1351]: Stopped hotspot (if there).. Mar 30 15:55:27 volumio sudo[1394]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 30 15:55:27 volumio sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:27 volumio sudo[1394]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:27 volumio sudo[1396]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 30 15:55:27 volumio sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:27 volumio sudo[1396]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:27 volumio wireless.js[1351]: DHCP IP Mar 30 15:55:27 volumio wireless.js[1351]: Start ap Mar 30 15:55:27 volumio wpa_supplicant[1398]: Successfully initialized wpa_supplicant Mar 30 15:55:27 volumio sudo[1399]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 30 15:55:28 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:28 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 30 15:55:28 volumio dhcpcd[1400]: dev: loaded udev Mar 30 15:55:28 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 30 15:55:28 volumio dhcpcd[1400]: wlan0: connected to Access Point `' Mar 30 15:55:28 volumio dnsmasq[648]: reading /etc/resolv.conf Mar 30 15:55:28 volumio dnsmasq[648]: using nameserver 208.67.222.222#53 Mar 30 15:55:28 volumio dnsmasq[648]: using nameserver 208.67.220.220#53 Mar 30 15:55:28 volumio dhcpcd[1400]: no interfaces have a carrier Mar 30 15:55:28 volumio dhcpcd[1427]: wlan0: waiting for carrier Mar 30 15:55:28 volumio dhcpcd[1400]: forked to background, child pid 1427 Mar 30 15:55:28 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:28 volumio wireless.js[1351]: trying... Mar 30 15:55:28 volumio wpa_supplicant[1407]: wlan0: Trying to associate with SSID 'mote 5' Mar 30 15:55:29 volumio sudo[1429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:55:29 volumio sudo[1429]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:29 volumio sudo[1429]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:29 volumio wpa_supplicant[1407]: wlan0: Associated with 80:35:c1:38:33:3d Mar 30 15:55:29 volumio wpa_supplicant[1407]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:35:c1:38:33:3d completed [id=0 id_str=] Mar 30 15:55:29 volumio dhcpcd[1427]: wlan0: carrier acquired Mar 30 15:55:29 volumio dhcpcd[1427]: wlan0: connected to Access Point `mote 5' Mar 30 15:55:29 volumio wpa_supplicant[1407]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 30 15:55:29 volumio dhcpcd[1427]: DUID 00:01:00:01:2b:b8:6d:71:b8:27:eb:bd:ec:b9 Mar 30 15:55:29 volumio dhcpcd[1427]: wlan0: IAID eb:bd:ec:b9 Mar 30 15:55:29 volumio dhcpcd[1427]: wlan0: adding address fe80::83d8:8d5c:5f00:e984 Mar 30 15:55:29 volumio dhcpcd[1427]: ipv6_addaddr1: Permission denied Mar 30 15:55:29 volumio wireless.js[1351]: trying... Mar 30 15:55:29 volumio dhcpcd[1427]: wlan0: soliciting a DHCP lease Mar 30 15:55:29 volumio dhcpcd[1427]: wlan0: offered 192.168.43.224 from 192.168.43.1 Mar 30 15:55:29 volumio dhcpcd[1427]: wlan0: probing address 192.168.43.224/24 Mar 30 15:55:29 volumio sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:55:30 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:30 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:30 volumio dhcpcd[1427]: wlan0: soliciting an IPv6 router Mar 30 15:55:30 volumio wireless.js[1351]: trying... Mar 30 15:55:30 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:55:30 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:30 volumio ntpd[547]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=251 secs Mar 30 15:55:31 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:31 volumio wireless.js[1351]: trying... Mar 30 15:55:31 volumio sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:55:31 volumio sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:32 volumio sudo[1439]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:32 volumio wireless.js[1351]: trying... Mar 30 15:55:32 volumio sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:55:32 volumio sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:33 volumio sudo[1442]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:33 volumio wireless.js[1351]: trying... Mar 30 15:55:33 volumio sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:55:33 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:34 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:34 volumio dhcpcd[1427]: wlan0: leased 192.168.43.224 for 3600 seconds Mar 30 15:55:34 volumio avahi-daemon[365]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.43.224. Mar 30 15:55:34 volumio avahi-daemon[365]: New relevant interface wlan0.IPv4 for mDNS. Mar 30 15:55:34 volumio avahi-daemon[365]: Registering new address record for 192.168.43.224 on wlan0.IPv4. Mar 30 15:55:34 volumio dhcpcd[1427]: wlan0: adding route to 192.168.43.0/24 Mar 30 15:55:34 volumio dhcpcd[1427]: wlan0: adding default route via 192.168.43.1 Mar 30 15:55:34 volumio avahi-daemon[365]: Host name conflict, retrying with volumio-2 Mar 30 15:55:34 volumio avahi-daemon[365]: Registering new address record for 192.168.43.224 on wlan0.IPv4. Mar 30 15:55:34 volumio dnsmasq[648]: reading /etc/resolv.conf Mar 30 15:55:34 volumio dnsmasq[648]: using nameserver 192.168.43.1#53 Mar 30 15:55:34 volumio dnsmasq[648]: using nameserver 208.67.222.222#53 Mar 30 15:55:34 volumio dnsmasq[648]: using nameserver 208.67.220.220#53 Mar 30 15:55:34 volumio wireless.js[1351]: trying... Mar 30 15:55:34 volumio sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:55:34 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:35 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:35 volumio wireless.js[1351]: Connected to: ----mote 5 Mar 30 15:55:35 volumio wireless.js[1351]: ---- Mar 30 15:55:35 volumio sudo[1472]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 15:55:35 volumio sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:35 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:35 volumio wireless.js[1351]: ... joined AP, wlan0 IPv4 is 192.168.43.224, ipV6 is undefined Mar 30 15:55:35 volumio wireless.js[1351]: It's done! AP Mar 30 15:55:35 volumio systemd[1]: Started Wireless Services. Mar 30 15:55:35 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:35 volumio volumio[655]: info: Discovery: adding 0ed21b40-d54e-40e6-9f19-6a0f8f659d05 Mar 30 15:55:35 volumio volumio[655]: info: Discovery: Found device Volumio Mar 30 15:55:35 volumio volumio[655]: info: Discovery: Connecting to remote: 192.168.43.28 Mar 30 15:55:35 volumio ntpd[547]: Listen normally on 4 wlan0 192.168.43.224:123 Mar 30 15:55:36 volumio ntpd[547]: new interface(s) found: waking up resolver Mar 30 15:55:36 volumio volumio[655]: info: Discovery: Connected to remote: 192.168.43.28 Mar 30 15:55:36 volumio avahi-daemon[365]: Server startup complete. Host name is volumio-2.local. Local service cookie is 2118465700. Mar 30 15:55:36 volumio volumio[655]: error: Discovery: Advertisement error: Error: dns service error: bad state Mar 30 15:55:36 volumio volumio[655]: error: Discovery: advertisement error: Error: dns service error: bad state Mar 30 15:55:36 volumio volumio[655]: info: Discovery: Advertisement raised the following error Error: dns service error: bad state Mar 30 15:55:36 volumio volumio[655]: info: Discovery: adding undefined Mar 30 15:55:36 volumio volumio[655]: info: Discovery: Found device Volumio Mar 30 15:55:36 volumio volumio[655]: info: CoreCommandRouter::volumioGetState Mar 30 15:55:36 volumio volumio[655]: info: CorePlayQueue::getTrack 0 Mar 30 15:55:36 volumio volumio[655]: verbose: New Socket.io Connection to 192.168.43.224:3000 from 192.168.43.28 UA: node-XMLHttpRequest Total Clients: 3 Mar 30 15:55:36 volumio volumio[655]: info: CoreCommandRouter::volumioGetState Mar 30 15:55:36 volumio volumio[655]: info: CorePlayQueue::getTrack 0 Mar 30 15:55:40 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Mar 30 15:55:40 volumio volumio[655]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Mar 30 15:55:40 volumio sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 15:55:40 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:40 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:40 volumio sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 30 15:55:40 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:55:40 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Mar 30 15:55:41 volumio volumio[655]: info: Discovery: Started advertising with name: Volumio Mar 30 15:55:42 volumio nmbd[546]: [2023/03/30 15:55:42.293759, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) Mar 30 15:55:42 volumio nmbd[546]: my_name_register_failed: Failed to register my name VOLUMIO<20> on subnet 192.168.43.224. Mar 30 15:55:42 volumio nmbd[546]: [2023/03/30 15:55:42.294630, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) Mar 30 15:55:42 volumio nmbd[546]: standard_fail_register: Failed to register/refresh name VOLUMIO<20> on subnet 192.168.43.224 Mar 30 15:55:42 volumio nmbd[546]: [2023/03/30 15:55:42.295084, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) Mar 30 15:55:42 volumio nmbd[546]: my_name_register_failed: Failed to register my name VOLUMIO<03> on subnet 192.168.43.224. Mar 30 15:55:42 volumio nmbd[546]: [2023/03/30 15:55:42.295529, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) Mar 30 15:55:42 volumio nmbd[546]: standard_fail_register: Failed to register/refresh name VOLUMIO<03> on subnet 192.168.43.224 Mar 30 15:55:42 volumio nmbd[546]: [2023/03/30 15:55:42.295967, 0] ../source3/nmbd/nmbd_mynames.c:36(my_name_register_failed) Mar 30 15:55:42 volumio nmbd[546]: my_name_register_failed: Failed to register my name VOLUMIO<00> on subnet 192.168.43.224. Mar 30 15:55:42 volumio nmbd[546]: [2023/03/30 15:55:42.296402, 0] ../source3/nmbd/nmbd_namelistdb.c:320(standard_fail_register) Mar 30 15:55:42 volumio nmbd[546]: standard_fail_register: Failed to register/refresh name VOLUMIO<00> on subnet 192.168.43.224 Mar 30 15:55:42 volumio volumio[655]: info: Discovery: this is already registered, undefined Mar 30 15:55:42 volumio volumio[655]: info: Discovery: Found device Volumio Mar 30 15:55:42 volumio volumio[655]: info: CoreCommandRouter::volumioGetState Mar 30 15:55:42 volumio volumio[655]: info: CorePlayQueue::getTrack 0 Mar 30 15:55:43 volumio volumio[655]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 15:55:43 volumio volumio[655]: Error: certificate is not yet valid Mar 30 15:55:43 volumio volumio[655]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Mar 30 15:55:43 volumio volumio[655]: at TLSSocket.emit (events.js:315:20) Mar 30 15:55:43 volumio volumio[655]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Mar 30 15:55:43 volumio volumio[655]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Mar 30 15:55:43 volumio volumio[655]: code: 'CERT_NOT_YET_VALID' Mar 30 15:55:43 volumio volumio[655]: } Mar 30 15:55:43 volumio volumio[655]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 15:55:45 volumio sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-03-30 15:54 Mar 30 15:55:45 volumio sudo[1497]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"