-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2023-03-30 15:54:44 UTC. -- Mar 30 15:53:02 volumio sudo[1335]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:06 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Mar 30 15:53:06 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 30 15:53:07 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 30 15:53:07 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:07 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:53:19 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 30 15:53:19 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:53:19 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 30 15:53:19 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:19 volumio volumio[763]: info: Enabling I2S DAC: HiFiBerry DAC Plus Mar 30 15:53:19 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Mar 30 15:53:19 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:19 volumio sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 30 15:53:19 volumio sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:19 volumio sudo[1341]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:19 volumio volumio[763]: info: No Overlays Loaded Mar 30 15:53:19 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:19 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 30 15:53:19 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:19 volumio sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus Mar 30 15:53:19 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:19 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Mar 30 15:53:19 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Mar 30 15:53:19 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Mar 30 15:53:19 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Mar 30 15:53:19 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Mar 30 15:53:19 volumio volumio[763]: warn: Unable to locate the device HiFiBerry DAC Plus a reboot may be required. Mar 30 15:53:19 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 30 15:53:20 volumio volumio[763]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 30 15:53:20 volumio volumio[763]: info: Disabling external Volume Control Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:53:20 volumio volumio[763]: info: Preparing to generate the ALSA configuration file Mar 30 15:53:20 volumio systemd-udevd[1359]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 2' failed with exit code 99. Mar 30 15:53:20 volumio volumio[763]: info: I2S Param [object Object] successfully enabled Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 30 15:53:20 volumio sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 15:53:20 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:53:20 volumio volumio[763]: info: Getting Alsa Cards List without I2S DAC Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 30 15:53:20 volumio volumio[763]: info: VolumeController:: Volume=100 Mute =false Mar 30 15:53:20 volumio volumio[763]: info: CoreStateMachine::pushState Mar 30 15:53:20 volumio volumio[763]: info: CorePlayQueue::getTrack 0 Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 15:53:20 volumio volumio[763]: info: CoreCommandRouter::volumioPushState Mar 30 15:53:20 volumio volumio[763]: info: Asound.conf file written Mar 30 15:53:21 volumio sudo[1394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 30 15:53:21 volumio sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:21 volumio sudo[1394]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:21 volumio volumio[763]: No state is present for card b1 Mar 30 15:53:21 volumio volumio[763]: No state is present for card Headphones Mar 30 15:53:21 volumio volumio[763]: No state is present for card sndrpihifiberry Mar 30 15:53:21 volumio volumio[763]: Found hardware: "HifiberryDacp" "" "" "" "" Mar 30 15:53:21 volumio volumio[763]: Hardware is initialized using a generic method Mar 30 15:53:21 volumio volumio[763]: No state is present for card sndrpihifiberry Mar 30 15:53:21 volumio volumio[763]: info: Output device has changed, restarting MPD Mar 30 15:53:21 volumio volumio[763]: info: Output device has changed, restarting Shairport Sync Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:21 volumio sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 15:53:21 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:21 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:21 volumio sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 15:53:21 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:21 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 15:53:21 volumio volumio[763]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 15:53:21 volumio volumio[763]: info: Output device has changed, restarting MPD Mar 30 15:53:21 volumio systemd[1]: mpd.service: Succeeded. Mar 30 15:53:21 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 15:53:21 volumio systemd[1]: Starting Music Player Daemon... Mar 30 15:53:21 volumio volumio[763]: info: Output device has changed, restarting Shairport Sync Mar 30 15:53:21 volumio sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 15:53:21 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:21 volumio sudo[1408]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:21 volumio sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 15:53:21 volumio sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:21 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 30 15:53:21 volumio volumio[763]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 30 15:53:21 volumio volumio[763]: info: MPD Permissions set Mar 30 15:53:21 volumio volumio[763]: info: MPD Permissions set Mar 30 15:53:21 volumio sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 15:53:21 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:21 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:21 volumio volumio[763]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 15:53:21 volumio volumio[763]: wlan0 Interface doesn't support scanning : Device or resource busy Mar 30 15:53:21 volumio volumio[763]: info: Cannot use regular scanning, forcing with ap-force Mar 30 15:53:21 volumio sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 15:53:21 volumio sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:21 volumio sudo[1422]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:21 volumio volumio[763]: command failed: Device or resource busy (-16) Mar 30 15:53:21 volumio volumio[763]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 15:53:21 volumio volumio[763]: command failed: Device or resource busy (-16) Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio systemd[1]: mpd.service: Succeeded. Mar 30 15:53:21 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:21 volumio systemd[1]: Starting Music Player Daemon... Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:21 volumio volumio[763]: info: Starting Shairport Sync Mar 30 15:53:21 volumio volumio[763]: info: Starting Shairport Sync Mar 30 15:53:21 volumio sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 30 15:53:21 volumio sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:21 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 30 15:53:21 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 30 15:53:22 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 30 15:53:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 30 15:53:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 30 15:53:22 volumio sudo[1434]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:22 volumio volumio[763]: info: Shairport-Sync Started Mar 30 15:53:22 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:22 volumio volumio[763]: info: Shairport-Sync Started Mar 30 15:53:24 volumio mpd[1443]: Mar 30 15:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 15:53:24 volumio mpd[1443]: Mar 30 15:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 30 15:53:24 volumio mpd[1443]: Mar 30 15:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 30 15:53:24 volumio systemd[1]: Started Music Player Daemon. Mar 30 15:53:24 volumio sudo[1412]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:24 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:24 volumio volumio[763]: error: MPD error: The expression evaluated to a falsy value: Mar 30 15:53:24 volumio volumio[763]: assert.ok(self.idling) Mar 30 15:53:24 volumio volumio[763]: error: The expression evaluated to a falsy value: Mar 30 15:53:24 volumio volumio[763]: assert.ok(self.idling) Mar 30 15:53:24 volumio volumio[763]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 30 15:53:24 volumio volumio[763]: info: CoreStateMachine::getcurrentVolume Mar 30 15:53:24 volumio volumio[763]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 15:53:24 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:24 volumio volumio[763]: error: updateQueue error: null Mar 30 15:53:24 volumio volumio[763]: info: VolumeController:: Volume=100 Mute =false Mar 30 15:53:24 volumio volumio[763]: info: CoreStateMachine::pushState Mar 30 15:53:24 volumio volumio[763]: info: CorePlayQueue::getTrack 0 Mar 30 15:53:24 volumio volumio[763]: info: CoreCommandRouter::volumioPushState Mar 30 15:53:24 volumio volumio[763]: info: CoreStateMachine::updateTrackBlock Mar 30 15:53:24 volumio volumio[763]: info: CorePlayQueue::getTrackBlock Mar 30 15:53:24 volumio volumio[763]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 15:53:24 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 30 15:53:24 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 30 15:53:24 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:24 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:53:24 volumio volumio[763]: info: VolumeController:: Volume=100 Mute =false Mar 30 15:53:24 volumio volumio[763]: info: CoreStateMachine::pushState Mar 30 15:53:24 volumio volumio[763]: info: CorePlayQueue::getTrack 0 Mar 30 15:53:24 volumio volumio[763]: info: CoreCommandRouter::volumioPushState Mar 30 15:53:27 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 30 15:53:27 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:53:27 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 30 15:53:27 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:27 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 30 15:53:27 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 30 15:53:28 volumio volumio[763]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 30 15:53:28 volumio volumio[763]: info: Disabling external Volume Control Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:53:28 volumio volumio[763]: info: Preparing to generate the ALSA configuration file Mar 30 15:53:28 volumio volumio[763]: info: Asound.conf file unchanged, so no further update is needed Mar 30 15:53:28 volumio volumio[763]: info: Output device has changed, restarting MPD Mar 30 15:53:28 volumio volumio[763]: info: Output device has changed, restarting Shairport Sync Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:28 volumio sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 15:53:28 volumio sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:28 volumio sudo[1460]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:28 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 15:53:28 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:28 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 15:53:28 volumio volumio[763]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 15:53:28 volumio systemd[1]: mpd.service: Succeeded. Mar 30 15:53:28 volumio systemd[1]: Stopped Music Player Daemon. Mar 30 15:53:28 volumio systemd[1]: Starting Music Player Daemon... Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 30 15:53:28 volumio volumio[763]: info: Getting Alsa Cards List without I2S DAC Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 30 15:53:28 volumio volumio[763]: info: MPD Permissions set Mar 30 15:53:28 volumio sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 15:53:28 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 30 15:53:28 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:28 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 15:53:28 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 15:53:28 volumio volumio[763]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 30 15:53:28 volumio volumio[763]: wlan0 Interface doesn't support scanning : Device or resource busy Mar 30 15:53:28 volumio volumio[763]: info: Cannot use regular scanning, forcing with ap-force Mar 30 15:53:28 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 30 15:53:28 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:28 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:28 volumio volumio[763]: command failed: Device or resource busy (-16) Mar 30 15:53:29 volumio volumio[763]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 30 15:53:29 volumio volumio[763]: command failed: Device or resource busy (-16) Mar 30 15:53:29 volumio volumio[763]: info: VolumeController:: Volume=100 Mute =false Mar 30 15:53:29 volumio volumio[763]: info: CoreStateMachine::pushState Mar 30 15:53:29 volumio volumio[763]: info: CorePlayQueue::getTrack 0 Mar 30 15:53:29 volumio volumio[763]: info: CoreCommandRouter::volumioPushState Mar 30 15:53:29 volumio volumio[763]: info: Starting Shairport Sync Mar 30 15:53:29 volumio sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 30 15:53:29 volumio sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 30 15:53:29 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 30 15:53:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 30 15:53:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 30 15:53:29 volumio sudo[1494]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:29 volumio volumio[763]: info: Shairport-Sync Started Mar 30 15:53:30 volumio mpd[1480]: Mar 30 15:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 30 15:53:30 volumio mpd[1480]: Mar 30 15:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 30 15:53:30 volumio mpd[1480]: Mar 30 15:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 30 15:53:30 volumio systemd[1]: Started Music Player Daemon. Mar 30 15:53:30 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:30 volumio volumio[763]: error: updateQueue error: null Mar 30 15:53:32 volumio volumio[763]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 30 15:53:32 volumio volumio[763]: info: CoreStateMachine::getcurrentVolume Mar 30 15:53:32 volumio volumio[763]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 15:53:32 volumio volumio[763]: info: VolumeController:: Volume=100 Mute =false Mar 30 15:53:32 volumio volumio[763]: info: CoreStateMachine::pushState Mar 30 15:53:32 volumio volumio[763]: info: CorePlayQueue::getTrack 0 Mar 30 15:53:32 volumio volumio[763]: info: CoreCommandRouter::volumioPushState Mar 30 15:53:32 volumio volumio[763]: info: CoreStateMachine::updateTrackBlock Mar 30 15:53:32 volumio volumio[763]: info: CorePlayQueue::getTrackBlock Mar 30 15:53:32 volumio volumio[763]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 15:53:32 volumio volumio[763]: info: VolumeController:: Volume=100 Mute =false Mar 30 15:53:32 volumio volumio[763]: info: CoreStateMachine::pushState Mar 30 15:53:32 volumio volumio[763]: info: CorePlayQueue::getTrack 0 Mar 30 15:53:32 volumio volumio[763]: info: CoreCommandRouter::volumioPushState Mar 30 15:53:32 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:43 volumio ntpd[655]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 30 15:53:44 volumio ntpd[655]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 30 15:53:44 volumio ntpd[655]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 30 15:53:45 volumio ntpd[655]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 30 15:53:48 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Mar 30 15:53:48 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Mar 30 15:53:48 volumio sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 15:53:48 volumio sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:48 volumio sudo[1507]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:49 volumio volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:53:51 volumio volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:53:51 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Mar 30 15:53:51 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Mar 30 15:53:51 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Mar 30 15:53:51 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Mar 30 15:53:51 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Mar 30 15:53:53 volumio volumio[763]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 30 15:53:55 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Mar 30 15:53:55 volumio volumio[763]: info: CoreCommandRouter::Reload Ui Mar 30 15:53:55 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Mar 30 15:53:55 volumio volumio[763]: info: Wizard terminated Successfully Mar 30 15:53:55 volumio volumio[763]: info: Executing Deferred Wifi Connection Mar 30 15:53:55 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Mar 30 15:53:55 volumio volumio[763]: info: Saving new wireless network Mar 30 15:53:55 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Mar 30 15:53:55 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:55 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:55 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 30 15:53:55 volumio volumio[763]: info: Discovery: Restarting Advertising due to device name change Mar 30 15:53:55 volumio sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 30 15:53:55 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:55 volumio systemd[1]: Starting Wireless Services... Mar 30 15:53:56 volumio volumio[763]: info: Discovery: A device disappeared from network Mar 30 15:53:56 volumio volumio[763]: info: Discovery: Device volumio disappeared from network Mar 30 15:53:56 volumio wireless.js[1518]: Cleaning previous... Mar 30 15:53:56 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 30 15:53:57 volumio systemd[1]: hostapd.service: Succeeded. Mar 30 15:53:57 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 30 15:53:57 volumio sudo[1532]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 30 15:53:57 volumio sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:57 volumio avahi-daemon[471]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 30 15:53:57 volumio avahi-daemon[471]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 30 15:53:57 volumio avahi-daemon[471]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 30 15:53:57 volumio sudo[1532]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:57 volumio sudo[1534]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 30 15:53:57 volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:57 volumio sudo[1534]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:57 volumio wireless.js[1518]: Stopped aP Mar 30 15:53:57 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 30 15:53:57 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:57 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:57 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 30 15:53:57 volumio sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 30 15:53:57 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:58 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:58 volumio sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 30 15:53:58 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:58 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root Mar 30 15:53:58 volumio sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 30 15:53:58 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:53:59 volumio ntpd[655]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=1, sent=1, dropped=0, active_time=170 secs Mar 30 15:54:00 volumio volumio[763]: info: Discovery: Started advertising with name: Volumio Mar 30 15:54:00 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:00 volumio wireless.js[1518]: Start wireless flow Mar 30 15:54:00 volumio wireless.js[1518]: Stopped hotspot (if there).. Mar 30 15:54:00 volumio sudo[1558]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 30 15:54:00 volumio sudo[1558]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:00 volumio sudo[1558]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:00 volumio sudo[1560]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 30 15:54:00 volumio sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:01 volumio sudo[1560]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:01 volumio wireless.js[1518]: DHCP IP Mar 30 15:54:01 volumio wireless.js[1518]: Start ap Mar 30 15:54:01 volumio wpa_supplicant[1562]: Successfully initialized wpa_supplicant Mar 30 15:54:01 volumio sudo[1563]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 30 15:54:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 30 15:54:01 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:01 volumio dhcpcd[1564]: dev: loaded udev Mar 30 15:54:01 volumio dhcpcd-run-hooks[1582]: wlan0: starting wpa_supplicant Mar 30 15:54:01 volumio dhcpcd-run-hooks[1587]: wlan0: failed to start wpa_supplicant Mar 30 15:54:01 volumio dhcpcd-run-hooks[1588]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Mar 30 15:54:01 volumio dhcpcd[1564]: wlan0: connected to Access Point `' Mar 30 15:54:01 volumio dhcpcd[1564]: no interfaces have a carrier Mar 30 15:54:01 volumio dhcpcd[1564]: forked to background, child pid 1598 Mar 30 15:54:01 volumio dhcpcd[1598]: eth0: waiting for carrier Mar 30 15:54:01 volumio dhcpcd[1598]: wlan0: waiting for carrier Mar 30 15:54:01 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:02 volumio wireless.js[1518]: trying... Mar 30 15:54:02 volumio sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:02 volumio sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:02 volumio sudo[1600]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:03 volumio wireless.js[1518]: trying... Mar 30 15:54:03 volumio sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:03 volumio sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:03 volumio sudo[1603]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:04 volumio wpa_supplicant[1586]: wlan0: Trying to associate with SSID 'mote 5' Mar 30 15:54:04 volumio wireless.js[1518]: trying... Mar 30 15:54:04 volumio sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:04 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:04 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:05 volumio wireless.js[1518]: trying... Mar 30 15:54:05 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:05 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:05 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:06 volumio wireless.js[1518]: trying... Mar 30 15:54:06 volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:06 volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:06 volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:06 volumio dhcpcd[1598]: wlan0: carrier acquired Mar 30 15:54:06 volumio dhcpcd[1598]: wlan0: connected to Access Point `mote 5' Mar 30 15:54:06 volumio wpa_supplicant[1586]: wlan0: Associated with 80:35:c1:38:33:3d Mar 30 15:54:06 volumio wpa_supplicant[1586]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:35:c1:38:33:3d completed [id=0 id_str=] Mar 30 15:54:06 volumio wpa_supplicant[1586]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 30 15:54:06 volumio dhcpcd[1598]: DUID 00:01:00:01:2b:b8:6d:1e:b8:27:eb:46:5a:a3 Mar 30 15:54:06 volumio dhcpcd[1598]: wlan0: IAID eb:46:5a:a3 Mar 30 15:54:06 volumio dhcpcd[1598]: wlan0: adding address fe80::bcd4:2011:6e2e:d502 Mar 30 15:54:06 volumio dhcpcd[1598]: ipv6_addaddr1: Permission denied Mar 30 15:54:06 volumio dhcpcd[1598]: wlan0: carrier lost Mar 30 15:54:06 volumio dhcpcd[1598]: wlan0: carrier acquired Mar 30 15:54:06 volumio dhcpcd[1598]: wlan0: IAID eb:46:5a:a3 Mar 30 15:54:07 volumio wireless.js[1518]: trying... Mar 30 15:54:07 volumio sudo[1627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:07 volumio sudo[1627]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:07 volumio sudo[1627]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:07 volumio dhcpcd[1598]: wlan0: soliciting an IPv6 router Mar 30 15:54:07 volumio dhcpcd[1598]: wlan0: soliciting a DHCP lease Mar 30 15:54:07 volumio dhcpcd[1598]: wlan0: offered 192.168.43.35 from 192.168.43.1 Mar 30 15:54:07 volumio dhcpcd[1598]: wlan0: probing address 192.168.43.35/24 Mar 30 15:54:08 volumio wireless.js[1518]: trying... Mar 30 15:54:08 volumio sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:08 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:08 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:09 volumio wireless.js[1518]: trying... Mar 30 15:54:09 volumio sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:09 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:09 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:10 volumio wireless.js[1518]: trying... Mar 30 15:54:10 volumio sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:10 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:10 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:11 volumio wireless.js[1518]: trying... Mar 30 15:54:11 volumio sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:11 volumio sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:11 volumio sudo[1639]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:12 volumio wireless.js[1518]: trying... Mar 30 15:54:12 volumio sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:12 volumio sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:12 volumio sudo[1642]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:13 volumio dhcpcd[1598]: wlan0: leased 192.168.43.35 for 3600 seconds Mar 30 15:54:13 volumio avahi-daemon[471]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.43.35. Mar 30 15:54:13 volumio dhcpcd[1598]: wlan0: adding route to 192.168.43.0/24 Mar 30 15:54:13 volumio dhcpcd[1598]: wlan0: adding default route via 192.168.43.1 Mar 30 15:54:13 volumio avahi-daemon[471]: New relevant interface wlan0.IPv4 for mDNS. Mar 30 15:54:13 volumio avahi-daemon[471]: Registering new address record for 192.168.43.35 on wlan0.IPv4. Mar 30 15:54:13 volumio dnsmasq[756]: reading /etc/resolv.conf Mar 30 15:54:13 volumio dnsmasq[756]: using nameserver 192.168.43.1#53 Mar 30 15:54:13 volumio dnsmasq[756]: using nameserver 208.67.222.222#53 Mar 30 15:54:13 volumio dnsmasq[756]: using nameserver 208.67.220.220#53 Mar 30 15:54:13 volumio wireless.js[1518]: trying... Mar 30 15:54:13 volumio sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 30 15:54:13 volumio sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:13 volumio sudo[1666]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:13 volumio wireless.js[1518]: Connected to: ----mote 5 Mar 30 15:54:13 volumio wireless.js[1518]: ---- Mar 30 15:54:13 volumio sudo[1669]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 15:54:13 volumio sudo[1669]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:13 volumio sudo[1669]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:13 volumio wireless.js[1518]: ... joined AP, wlan0 IPv4 is 192.168.43.35, ipV6 is undefined Mar 30 15:54:13 volumio wireless.js[1518]: It's done! AP Mar 30 15:54:13 volumio volumio[763]: verbose: New Socket.io Connection to 192.168.43.35 from 192.168.43.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 3 Mar 30 15:54:13 volumio systemd[1]: Started Wireless Services. Mar 30 15:54:13 volumio volumio[763]: verbose: New Socket.io Connection to 192.168.43.35 from 192.168.43.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 4 Mar 30 15:54:13 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:13 volumio volumio[763]: verbose: New Socket.io Connection to 192.168.43.35 from 192.168.43.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 5 Mar 30 15:54:13 volumio volumio[763]: verbose: New Socket.io Connection to 192.168.43.35 from 192.168.43.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 6 Mar 30 15:54:14 volumio volumio[763]: info: Discovery: adding undefined Mar 30 15:54:14 volumio volumio[763]: info: Discovery: Found device Volumio Mar 30 15:54:14 volumio volumio[763]: info: CoreCommandRouter::volumioGetState Mar 30 15:54:14 volumio volumio[763]: info: CorePlayQueue::getTrack 0 Mar 30 15:54:14 volumio ntpd[655]: Listen normally on 5 wlan0 192.168.43.35:123 Mar 30 15:54:14 volumio ntpd[655]: new interface(s) found: waking up resolver Mar 30 15:54:17 volumio volumio[763]: verbose: New Socket.io Connection to 192.168.43.35 from 192.168.43.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 7 Mar 30 15:54:17 volumio volumio[763]: info: CoreCommandRouter::volumioGetVisibleSources Mar 30 15:54:17 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 15:54:17 volumio volumio[763]: info: CoreCommandRouter::volumioGetState Mar 30 15:54:17 volumio volumio[763]: info: CorePlayQueue::getTrack 0 Mar 30 15:54:17 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 30 15:54:17 volumio volumio[763]: info: Listing playlists Mar 30 15:54:17 volumio volumio[763]: info: CoreCommandRouter::volumioGetQueue Mar 30 15:54:17 volumio volumio[763]: info: CoreStateMachine::getQueue Mar 30 15:54:17 volumio volumio[763]: info: CorePlayQueue::getQueue Mar 30 15:54:18 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Mar 30 15:54:18 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Mar 30 15:54:18 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 15:54:18 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:18 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:18 volumio sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 30 15:54:18 volumio sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:18 volumio sudo[1678]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:23 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Mar 30 15:54:23 volumio volumio[763]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Mar 30 15:54:23 volumio volumio[763]: info: Refreshing Cached IP Addresses Mar 30 15:54:23 volumio sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 30 15:54:23 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:23 volumio sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 15:54:23 volumio systemd[1]: upmpdcli.service: Succeeded. Mar 30 15:54:23 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:23 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:23 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:23 volumio sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 15:54:23 volumio sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:23 volumio sudo[1689]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:33 volumio sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 15:54:33 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:33 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:33 volumio sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 15:54:33 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:33 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:33 volumio sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 30 15:54:33 volumio sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:33 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 30 15:54:33 volumio sudo[1698]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:33 volumio volumio[763]: info: Upmpdcli Daemon Started Mar 30 15:54:33 volumio volumio[1700]: writing RSA key Mar 30 15:54:42 volumio sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 30 15:54:42 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:42 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:42 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 15:54:42 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 15:54:42 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root Mar 30 15:54:42 volumio volumio[763]: verbose: New Socket.io Connection to 192.168.43.35 from 192.168.43.184 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Total Clients: 7 Mar 30 15:54:43 volumio volumio[763]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 15:54:43 volumio volumio[763]: Error: certificate is not yet valid Mar 30 15:54:43 volumio volumio[763]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Mar 30 15:54:43 volumio volumio[763]: at TLSSocket.emit (events.js:315:20) Mar 30 15:54:43 volumio volumio[763]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Mar 30 15:54:43 volumio volumio[763]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Mar 30 15:54:43 volumio volumio[763]: code: 'CERT_NOT_YET_VALID' Mar 30 15:54:43 volumio volumio[763]: } Mar 30 15:54:43 volumio volumio[763]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 15:54:44 volumio sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-03-30 15:53 Mar 30 15:54:44 volumio sudo[1757]: 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"