-- 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"