May 14 18:08:00 volumio volumio[1064]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=0.066&uuid=c53014a2eddf6c6f60df78f54a52e40f" http://updates.volumio.org/downloader-v1/track-device May 14 18:08:00 volumio volumio[1064]: % Total % Received % Xferd Average Speed Time Time Time Current May 14 18:08:00 volumio volumio[1064]: Dload Upload Total Spent Left Speed May 14 18:08:00 volumio volumio[1064]: [316B blob data] May 14 18:08:00 volumio volumio[1064]: curl: (7) Failed to connect to updates.volumio.org port 80 after 3150 ms: Couldn't connect to server May 14 18:08:00 volumio volumio[1064]: retrying in 5 seconds, trial 2 May 14 18:08:00 volumio volumio[1064]: info: Volumio Calling Home May 14 18:08:02 volumio sudo[1967]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 18:08:02 volumio sudo[1967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:02 volumio sudo[1967]: pam_unix(sudo:session): session closed for user root May 14 18:08:02 volumio sudo[1969]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 18:08:02 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:02 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root May 14 18:08:02 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.104 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::volumioGetVisibleSources May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 14 18:08:02 volumio volumio[1064]: info: Received Get System Info May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 14 18:08:02 volumio volumio[1064]: info: Discovery: Getting this device information May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState May 14 18:08:02 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 14 18:08:02 volumio volumio-remote-updater[739]: No test mode May 14 18:08:02 volumio volumio-remote-updater[739]: No alpha test mode May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 14 18:08:02 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 14 18:08:03 volumio volumio-remote-updater[739]: curl_easy_perform() failed: Couldn't connect to server May 14 18:08:03 volumio volumio-remote-updater[739]: terminate called after throwing an instance of 'std::invalid_argument' May 14 18:08:03 volumio volumio-remote-updater[739]: what(): parse error - unexpected end of input May 14 18:08:03 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT May 14 18:08:03 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. May 14 18:08:03 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. May 14 18:08:03 volumio systemd[1]: Stopped volumio-remote-updater.service. May 14 18:08:03 volumio systemd[1]: Started volumio-remote-updater.service. May 14 18:08:03 volumio volumio-remote-updater[1977]: Error: No active session May 14 18:08:03 volumio volumio-remote-updater[1977]: [2025-05-14 16:08:03] [connect] Successful connection May 14 18:08:03 volumio volumio-remote-updater[1977]: [2025-05-14 16:08:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747238883 101 May 14 18:08:03 volumio volumio[1064]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 May 14 18:08:04 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 14 18:08:04 volumio volumio[1064]: info: Loading i18n strings for locale en May 14 18:08:04 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 14 18:08:04 volumio volumio[1064]: info: Setting timezone to Europe/Amsterdam May 14 18:08:04 volumio sudo[1982]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 14 18:08:04 volumio sudo[1982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:04 volumio sudo[1982]: pam_unix(sudo:session): session closed for user root May 14 18:08:04 volumio sudo[1986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime May 14 18:08:04 volumio sudo[1986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:04 volumio sudo[1986]: pam_unix(sudo:session): session closed for user root May 14 18:08:04 volumio sudo[1991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 14 18:08:04 volumio sudo[1991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:04 volumio sudo[1991]: pam_unix(sudo:session): session closed for user root May 14 18:08:04 volumio sudo[1995]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam May 14 18:08:04 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:04 volumio systemd-timedated[1931]: Changed time zone to 'Europe/Amsterdam' (CEST). May 14 18:08:04 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root May 14 18:08:04 volumio volumio[1064]: Updating browse sources language May 14 18:08:04 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 18:08:04 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 14 18:08:05 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 14 18:08:05 volumio volumio[1064]: info: Loading i18n strings for locale en May 14 18:08:05 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 14 18:08:05 volumio volumio[1064]: info: Setting timezone to Europe/Amsterdam May 14 18:08:06 volumio sudo[1999]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 14 18:08:06 volumio sudo[1999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:06 volumio sudo[1999]: pam_unix(sudo:session): session closed for user root May 14 18:08:06 volumio sudo[2003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime May 14 18:08:06 volumio sudo[2003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:06 volumio sudo[2003]: pam_unix(sudo:session): session closed for user root May 14 18:08:06 volumio sudo[2007]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 14 18:08:06 volumio sudo[2007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:06 volumio sudo[2007]: pam_unix(sudo:session): session closed for user root May 14 18:08:06 volumio sudo[2011]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam May 14 18:08:06 volumio sudo[2011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:06 volumio sudo[2011]: pam_unix(sudo:session): session closed for user root May 14 18:08:06 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 14 18:08:06 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 14 18:08:06 volumio sudo[2014]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 14 18:08:06 volumio sudo[2014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:06 volumio volumio[1064]: Updating browse sources language May 14 18:08:06 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 18:08:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 14 18:08:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 14 18:08:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 14 18:08:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 18:08:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 18:08:10 volumio sudo[2014]: pam_unix(sudo:session): session closed for user root May 14 18:08:10 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 14 18:08:10 volumio volumio[1064]: info: Preparing to save Alsa Options, stopping services first May 14 18:08:10 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState May 14 18:08:10 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 May 14 18:08:10 volumio volumio[1064]: info: CoreCommandRouter::volumioPause May 14 18:08:10 volumio volumio[1064]: info: CoreStateMachine::pause May 14 18:08:10 volumio volumio[1064]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"Headphones"},"disallowPush":true} May 14 18:08:10 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 18:08:10 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 18:08:10 volumio volumio[1064]: info: Found match in Cards Database: setting mixer PCM for card Headphones May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 14 18:08:11 volumio volumio[1064]: info: Updating Volume Controller Parameters: Device: 0 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 14 18:08:11 volumio volumio[1064]: info: Disabling external Volume Control May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 18:08:11 volumio volumio[1064]: info: Preparing to generate the ALSA configuration file May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 14 18:08:11 volumio sudo[2031]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 14 18:08:11 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:11 volumio volumio[1064]: info: Asound.conf file unchanged, so no further update is needed May 14 18:08:11 volumio volumio[1064]: info: Output device has changed, restarting MPD May 14 18:08:11 volumio sudo[2033]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 14 18:08:11 volumio volumio[1064]: info: Output device has changed, restarting Shairport Sync May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 18:08:11 volumio sudo[2033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:11 volumio sudo[2033]: pam_unix(sudo:session): session closed for user root May 14 18:08:11 volumio volumio[1064]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 18:08:11 volumio sudo[2040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 14 18:08:11 volumio sudo[2039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 14 18:08:11 volumio sudo[2039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:11 volumio sudo[2040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:11 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 14 18:08:11 volumio sudo[2040]: pam_unix(sudo:session): session closed for user root May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 14 18:08:11 volumio systemd[1]: mpd.service: Deactivated successfully. May 14 18:08:11 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 14 18:08:11 volumio systemd[1]: mpd.service: Consumed 3.234s CPU time. May 14 18:08:11 volumio systemd[1]: mpd.socket: Deactivated successfully. May 14 18:08:11 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 14 18:08:11 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 14 18:08:11 volumio volumio[1064]: info: MPD Permissions set May 14 18:08:11 volumio volumio[1064]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 14 18:08:11 volumio volumio[1064]: wlan0 Interface doesn't support scanning : Device or resource busy May 14 18:08:11 volumio volumio[1064]: info: Cannot use regular scanning, forcing with ap-force May 14 18:08:11 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 14 18:08:11 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 14 18:08:11 volumio sudo[2058]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 14 18:08:11 volumio sudo[2056]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 14 18:08:11 volumio sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 18:08:11 volumio sudo[2058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:11 volumio sudo[2056]: pam_unix(sudo:session): session closed for user root May 14 18:08:11 volumio sudo[2058]: pam_unix(sudo:session): session closed for user root May 14 18:08:11 volumio volumio[1064]: command failed: Device or resource busy (-16) May 14 18:08:11 volumio volumio[1064]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 14 18:08:11 volumio volumio[1064]: command failed: Device or resource busy (-16) May 14 18:08:11 volumio volumio[1064]: info: VolumeController:: Volume=86 Mute =false May 14 18:08:11 volumio volumio[1064]: info: CoreStateMachine::pushState May 14 18:08:11 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:11 volumio volumio[1064]: info: Starting Shairport Sync May 14 18:08:11 volumio sudo[2069]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 14 18:08:11 volumio sudo[2069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 14 18:08:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 14 18:08:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 18:08:12 volumio systemd[1]: shairport-sync.service: Consumed 1.542s CPU time. May 14 18:08:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 18:08:12 volumio sudo[2069]: pam_unix(sudo:session): session closed for user root May 14 18:08:12 volumio volumio[1064]: info: Shairport-Sync Started May 14 18:08:13 volumio mpd[2061]: 2025-05-14T18:08:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 14 18:08:13 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 14 18:08:13 volumio sudo[2039]: pam_unix(sudo:session): session closed for user root May 14 18:08:13 volumio volumio[1064]: error: updateQueue error: null May 14 18:08:14 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root May 14 18:08:15 volumio volumio[1064]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 14 18:08:15 volumio volumio[1064]: info: CoreStateMachine::getcurrentVolume May 14 18:08:15 volumio volumio[1064]: info: CoreCommandRouter::volumioRetrievevolume May 14 18:08:15 volumio volumio[1064]: info: VolumeController:: Volume=86 Mute =false May 14 18:08:15 volumio volumio[1064]: info: CoreStateMachine::pushState May 14 18:08:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 May 14 18:08:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState May 14 18:08:15 volumio volumio[1064]: info: CoreStateMachine::updateTrackBlock May 14 18:08:15 volumio volumio[1064]: info: CorePlayQueue::getTrackBlock May 14 18:08:15 volumio volumio[1064]: info: CoreCommandRouter::volumioRetrievevolume May 14 18:08:15 volumio volumio[1064]: info: VolumeController:: Volume=86 Mute =false May 14 18:08:15 volumio volumio[1064]: info: CoreStateMachine::pushState May 14 18:08:15 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 May 14 18:08:15 volumio volumio[1064]: info: CoreCommandRouter::volumioPushState May 14 18:08:34 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 14 18:08:34 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 14 18:08:36 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 14 18:08:36 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 14 18:08:36 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. May 14 18:08:48 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork May 14 18:08:48 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo May 14 18:08:48 volumio sudo[2152]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 14 18:08:48 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:48 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root May 14 18:08:50 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 18:08:52 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 18:08:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation May 14 18:08:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 14 18:08:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray May 14 18:08:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage May 14 18:08:52 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware May 14 18:08:54 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction May 14 18:08:54 volumio volumio[1064]: info: CoreCommandRouter::Reload Ui May 14 18:08:54 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard May 14 18:08:54 volumio volumio[1064]: info: Wizard terminated Successfully May 14 18:08:54 volumio volumio[1064]: info: CoreCommandRouter::volumiosetStartupVolume May 14 18:08:54 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 18:08:54 volumio volumio[1064]: info: Executing Deferred Wifi Connection May 14 18:08:54 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 14 18:08:54 volumio volumio[1064]: info: Saving new wireless network May 14 18:08:54 volumio volumio[1064]: info: CoreCommandRouter::getUIConfigOnPlugin May 14 18:08:54 volumio sudo[2169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 14 18:08:54 volumio sudo[2169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:54 volumio sudo[2169]: pam_unix(sudo:session): session closed for user root May 14 18:08:54 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 14 18:08:54 volumio volumio[1064]: info: Discovery: Restarting Advertising due to device name change May 14 18:08:54 volumio sudo[2173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 14 18:08:54 volumio sudo[2173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:54 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 14 18:08:54 volumio systemd[1]: Starting wireless.service - Wireless Services... May 14 18:08:54 volumio dbus-daemon[725]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.30' (uid=0 pid=2177 comm="timedatectl show --property=NTPSynchronized --valu") May 14 18:08:54 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 14 18:08:54 volumio dbus-daemon[725]: [system] Successfully activated service 'org.freedesktop.timedate1' May 14 18:08:54 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. May 14 18:08:54 volumio setdatetime-helper.sh[2176]: Time is already synchronized. May 14 18:08:54 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 14 18:08:54 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 14 18:08:55 volumio volumio[1064]: info: Discovery: A device disappeared from network May 14 18:08:55 volumio volumio[1064]: info: Discovery: Device volumio disappeared from network May 14 18:08:55 volumio volumio[1064]: info: Discovery: A device disappeared from network May 14 18:08:55 volumio wireless.js[2179]: Cleaning previous... May 14 18:08:55 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... May 14 18:08:55 volumio dnsmasq[1790]: exiting on receipt of SIGTERM May 14 18:08:55 volumio systemd[1]: dnsmasq.service: Deactivated successfully. May 14 18:08:55 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. May 14 18:08:55 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... May 14 18:08:56 volumio dhcpcd[792]: wlan0: carrier lost May 14 18:08:56 volumio systemd[1]: hostapd.service: Deactivated successfully. May 14 18:08:56 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. May 14 18:08:56 volumio avahi-daemon[723]: Withdrawing address record for 169.254.84.47 on wlan0. May 14 18:08:56 volumio dhcpcd[792]: wlan0: deleting route to 169.254.0.0/16 May 14 18:08:56 volumio dhcpcd[792]: wlan0: deleting default route May 14 18:08:56 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 14 18:08:56 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 14 18:08:56 volumio systemd[1]: welcome.service: Deactivated successfully. May 14 18:08:56 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 14 18:08:56 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 14 18:08:56 volumio sudo[2223]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 14 18:08:56 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 14 18:08:56 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 18:08:56 volumio avahi-daemon[723]: Withdrawing address record for 192.168.211.1 on wlan0. May 14 18:08:56 volumio avahi-daemon[723]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 14 18:08:56 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root May 14 18:08:56 volumio avahi-daemon[723]: Interface wlan0.IPv4 no longer relevant for mDNS. May 14 18:08:56 volumio welcome[2225]: Resolved ip:[0] May 14 18:08:56 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 14 18:08:56 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 14 18:08:56 volumio sudo[2229]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 14 18:08:56 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 18:08:56 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 14 18:08:56 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 14 18:08:56 volumio systemd[1]: welcome.service: Deactivated successfully. May 14 18:08:56 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 14 18:08:56 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 14 18:08:56 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 14 18:08:57 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root May 14 18:08:57 volumio welcome[2234]: Resolved ip:[0] May 14 18:08:57 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 14 18:08:57 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 14 18:08:57 volumio wireless.js[2179]: Stopped aP May 14 18:08:57 volumio sudo[2245]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 14 18:08:57 volumio sudo[2245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:57 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 14 18:08:57 volumio sudo[2245]: pam_unix(sudo:session): session closed for user root May 14 18:08:57 volumio sudo[2247]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 14 18:08:57 volumio sudo[2247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:57 volumio sudo[2247]: pam_unix(sudo:session): session closed for user root May 14 18:08:57 volumio sudo[2255]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 14 18:08:57 volumio sudo[2255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:57 volumio sudo[2255]: pam_unix(sudo:session): session closed for user root May 14 18:08:57 volumio sudo[2257]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 14 18:08:57 volumio sudo[2257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:08:58 volumio ntpd[895]: IO: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=110 secs May 14 18:08:58 volumio ntpd[895]: IO: Deleting interface #6 wlan0, 169.254.84.47#123, interface stats: received=0, sent=0, dropped=0, active_time=99 secs May 14 18:08:59 volumio volumio[1064]: info: Discovery: Started advertising with name: Volumio May 14 18:09:00 volumio sudo[2257]: pam_unix(sudo:session): session closed for user root May 14 18:09:00 volumio wireless.js[2179]: SETTING APPROPRIATE REG DOMAIN: GB May 14 18:09:00 volumio sudo[2264]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 14 18:09:00 volumio sudo[2264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:00 volumio sudo[2264]: pam_unix(sudo:session): session closed for user root May 14 18:09:00 volumio sudo[2266]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB May 14 18:09:00 volumio sudo[2266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:00 volumio sudo[2266]: pam_unix(sudo:session): session closed for user root May 14 18:09:00 volumio wireless.js[2179]: SUCCESSFULLY SET NEW REGDOMAIN: GB May 14 18:09:00 volumio wireless.js[2179]: Start wireless flow May 14 18:09:00 volumio wireless.js[2179]: Interface wlan0 released. Proceeding with startAP... May 14 18:09:00 volumio wireless.js[2179]: Stopped hotspot (if there).. May 14 18:09:00 volumio volumio[1064]: info: Discovery: adding 7ca13e02-15d9-4ef1-8c64-dc7642dd1e4e May 14 18:09:00 volumio volumio[1064]: info: Discovery: Found device Volumio May 14 18:09:00 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState May 14 18:09:00 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 May 14 18:09:00 volumio sudo[2275]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 14 18:09:00 volumio sudo[2275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 18:09:00 volumio sudo[2275]: pam_unix(sudo:session): session closed for user root May 14 18:09:00 volumio sudo[2277]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 14 18:09:00 volumio sudo[2277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 18:09:00 volumio sudo[2277]: pam_unix(sudo:session): session closed for user root May 14 18:09:00 volumio wireless.js[2179]: DHCP IP fallback May 14 18:09:00 volumio wpa_supplicant[2282]: Successfully initialized wpa_supplicant May 14 18:09:00 volumio wireless.js[2179]: Start ap May 14 18:09:00 volumio sudo[2283]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 14 18:09:00 volumio sudo[2283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 18:09:00 volumio dhcpcd[792]: control command: /sbin/dhcpcd May 14 18:09:00 volumio dhcpcd[792]: control_free: No such file or directory May 14 18:09:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 14 18:09:00 volumio sudo[2283]: pam_unix(sudo:session): session closed for user root May 14 18:09:00 volumio dhcpcd[792]: lo: IAID 00:00:00:00 May 14 18:09:00 volumio dhcpcd[792]: lo: adding address fe80::a397:83c1:6f53:d2f6 May 14 18:09:00 volumio dhcpcd[792]: ipv6_addaddr1: Permission denied May 14 18:09:00 volumio dhcpcd[792]: lo: using static address 127.0.0.1/8 May 14 18:09:00 volumio dhcpcd[792]: lo: adding route to 127.0.0.0/8 May 14 18:09:01 volumio systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. May 14 18:09:01 volumio wpa_supplicant[2282]: nl80211: kernel reports: Registration to specific type not supported May 14 18:09:01 volumio wireless.js[2179]: trying... May 14 18:09:01 volumio sudo[2316]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:01 volumio sudo[2316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:01 volumio sudo[2316]: pam_unix(sudo:session): session closed for user root May 14 18:09:02 volumio wireless.js[2179]: trying... May 14 18:09:02 volumio sudo[2320]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:02 volumio sudo[2320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:02 volumio sudo[2320]: pam_unix(sudo:session): session closed for user root May 14 18:09:03 volumio wpa_supplicant[2313]: wlan0: Trying to associate with 30:23:03:43:74:bf (SSID='WieFieWafie' freq=2457 MHz) May 14 18:09:03 volumio wpa_supplicant[2313]: wlan0: Associated with 30:23:03:43:74:bf May 14 18:09:03 volumio wpa_supplicant[2313]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 14 18:09:03 volumio wpa_supplicant[2313]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB May 14 18:09:03 volumio wireless.js[2179]: trying... May 14 18:09:03 volumio wpa_supplicant[2313]: wlan0: WPA: Key negotiation completed with 30:23:03:43:74:bf [PTK=CCMP GTK=CCMP] May 14 18:09:03 volumio wpa_supplicant[2313]: wlan0: CTRL-EVENT-CONNECTED - Connection to 30:23:03:43:74:bf completed [id=0 id_str=] May 14 18:09:03 volumio dhcpcd[792]: wlan0: carrier acquired May 14 18:09:03 volumio dhcpcd[792]: wlan0: connected to Access Point: WieFieWafie May 14 18:09:03 volumio sudo[2338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:03 volumio dhcpcd[792]: wlan0: IAID 32:f0:59:21 May 14 18:09:03 volumio sudo[2338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:03 volumio sudo[2338]: pam_unix(sudo:session): session closed for user root May 14 18:09:04 volumio dhcpcd[792]: wlan0: soliciting an IPv6 router May 14 18:09:04 volumio wireless.js[2179]: trying... May 14 18:09:04 volumio sudo[2343]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:04 volumio sudo[2343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:04 volumio sudo[2343]: pam_unix(sudo:session): session closed for user root May 14 18:09:05 volumio dhcpcd[792]: wlan0: soliciting a DHCP lease May 14 18:09:05 volumio dhcpcd[792]: wlan0: offered 192.168.1.69 from 192.168.1.1 May 14 18:09:05 volumio dhcpcd[792]: wlan0: probing address 192.168.1.69/24 May 14 18:09:05 volumio wireless.js[2179]: trying... May 14 18:09:05 volumio sudo[2349]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:05 volumio sudo[2349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:05 volumio sudo[2349]: pam_unix(sudo:session): session closed for user root May 14 18:09:06 volumio wireless.js[2179]: trying... May 14 18:09:06 volumio sudo[2353]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:06 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:06 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root May 14 18:09:07 volumio wireless.js[2179]: trying... May 14 18:09:07 volumio sudo[2357]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:07 volumio sudo[2357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:07 volumio sudo[2357]: pam_unix(sudo:session): session closed for user root May 14 18:09:08 volumio wireless.js[2179]: trying... May 14 18:09:08 volumio sudo[2361]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:08 volumio sudo[2361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:08 volumio sudo[2361]: pam_unix(sudo:session): session closed for user root May 14 18:09:09 volumio wireless.js[2179]: trying... May 14 18:09:09 volumio sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:09 volumio sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:09 volumio sudo[2365]: pam_unix(sudo:session): session closed for user root May 14 18:09:10 volumio wireless.js[2179]: trying... May 14 18:09:10 volumio sudo[2369]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:10 volumio sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:10 volumio sudo[2369]: pam_unix(sudo:session): session closed for user root May 14 18:09:11 volumio dhcpcd[792]: wlan0: leased 192.168.1.69 for 86400 seconds May 14 18:09:11 volumio avahi-daemon[723]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.69. May 14 18:09:11 volumio dhcpcd[792]: wlan0: adding route to 192.168.1.0/24 May 14 18:09:11 volumio dhcpcd[792]: wlan0: adding default route via 192.168.1.1 May 14 18:09:11 volumio avahi-daemon[723]: New relevant interface wlan0.IPv4 for mDNS. May 14 18:09:11 volumio avahi-daemon[723]: Registering new address record for 192.168.1.69 on wlan0.IPv4. May 14 18:09:11 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 14 18:09:11 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 14 18:09:11 volumio systemd[1]: welcome.service: Deactivated successfully. May 14 18:09:11 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 14 18:09:11 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 14 18:09:11 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 14 18:09:11 volumio welcome[2380]: Resolved ip:[1] 192.168.1.69 May 14 18:09:11 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 14 18:09:11 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 14 18:09:11 volumio volumio[1064]: compat.c: read() failed: Resource temporarily unavailable May 14 18:09:11 volumio volumio[1064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 18:09:11 volumio volumio[1064]: Error: dns service error: unknown May 14 18:09:11 volumio volumio[1064]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 14 18:09:11 volumio volumio[1064]: errorCode: -65537 May 14 18:09:11 volumio volumio[1064]: } May 14 18:09:11 volumio volumio[1064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 18:09:11 volumio wireless.js[2179]: trying... May 14 18:09:11 volumio sudo[2412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 18:09:11 volumio sudo[2412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 14 18:09:11 volumio sudo[2412]: pam_unix(sudo:session): session closed for user root May 14 18:09:11 volumio wireless.js[2179]: Connected to: ----WieFieWafie May 14 18:09:11 volumio wireless.js[2179]: ---- May 14 18:09:12 volumio sudo[2415]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 18:09:12 volumio sudo[2415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 14 18:09:12 volumio sudo[2415]: pam_unix(sudo:session): session closed for user root May 14 18:09:12 volumio wireless.js[2179]: ... joined AP, wlan0 IPv4 is 192.168.1.69, ipV6 is undefined May 14 18:09:12 volumio wireless.js[2179]: It's done! AP May 14 18:09:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 14 18:09:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 14 18:09:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 18:09:12 volumio systemd[1]: shairport-sync.service: Consumed 1.575s CPU time. May 14 18:09:12 volumio avahi-daemon[723]: Got SIGTERM, quitting. May 14 18:09:12 volumio avahi-daemon[723]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.69. May 14 18:09:12 volumio avahi-daemon[723]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 14 18:09:12 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 14 18:09:12 volumio dbus-daemon[725]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.35' (uid=0 pid=1106 comm="/usr/sbin/smbd --foreground --no-process-group") May 14 18:09:12 volumio avahi-daemon[723]: avahi-daemon 0.8 exiting. May 14 18:09:12 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. May 14 18:09:12 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 14 18:09:12 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 14 18:09:12 volumio avahi-daemon[2420]: Process 723 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) May 14 18:09:12 volumio avahi-daemon[2420]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 14 18:09:12 volumio avahi-daemon[2420]: Successfully dropped root privileges. May 14 18:09:12 volumio avahi-daemon[2420]: avahi-daemon 0.8 starting up. May 14 18:09:12 volumio dbus-daemon[725]: [system] Successfully activated service 'org.freedesktop.Avahi' May 14 18:09:12 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 14 18:09:12 volumio avahi-daemon[2420]: Successfully called chroot(). May 14 18:09:12 volumio avahi-daemon[2420]: Successfully dropped remaining capabilities. May 14 18:09:12 volumio avahi-daemon[2420]: No service file found in /etc/avahi/services. May 14 18:09:12 volumio avahi-daemon[2420]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.69. May 14 18:09:12 volumio avahi-daemon[2420]: New relevant interface wlan0.IPv4 for mDNS. May 14 18:09:12 volumio avahi-daemon[2420]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 14 18:09:12 volumio avahi-daemon[2420]: New relevant interface lo.IPv4 for mDNS. May 14 18:09:12 volumio avahi-daemon[2420]: Network interface enumeration completed. May 14 18:09:12 volumio avahi-daemon[2420]: Registering new address record for 192.168.1.69 on wlan0.IPv4. May 14 18:09:12 volumio avahi-daemon[2420]: Registering new address record for 127.0.0.1 on lo.IPv4. May 14 18:09:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 14 18:09:12 volumio systemd[1]: Started wireless.service - Wireless Services. May 14 18:09:12 volumio sudo[2173]: pam_unix(sudo:session): session closed for user root May 14 18:09:12 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 14 18:09:12 volumio ntpd[895]: IO: Listen normally on 7 wlan0 192.168.1.69:123 May 14 18:09:12 volumio ntpd[895]: IO: new interface(s) found: waking up resolver May 14 18:09:12 volumio ntpd[895]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 14 18:09:12 volumio ntpd[895]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 14 18:09:12 volumio ntpd[895]: DNS: Pool taking: 209.250.249.159 May 14 18:09:12 volumio ntpd[895]: DNS: Pool taking: 178.239.19.59 May 14 18:09:12 volumio ntpd[895]: DNS: Pool taking: 191.96.11.19 May 14 18:09:12 volumio ntpd[895]: DNS: Pool taking: 158.101.216.150 May 14 18:09:12 volumio ntpd[895]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 14 18:09:13 volumio avahi-daemon[2420]: Server startup complete. Host name is volumio.local. Local service cookie is 730902720. May 14 18:09:13 volumio sudo[2456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-14 18:08' May 14 18:09:13 volumio sudo[2456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="74e551e797a3a333b0b20739125f2f8b0b974aba" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 14 16:06:36 UTC 2025" VOLUMIO_VERSION="0.066" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="72f9e088cb36b840954a7bc22b48bde7"