Oct 29 20:08:02 volumio upmpdcli[2190]: writing RSA key Oct 29 20:08:03 volumio ntpd[1137]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 29 20:08:03 volumio ntpd[1137]: DNS: dns_check: DNS error: -11, System error Oct 29 20:08:03 volumio ntpd[1137]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Oct 29 20:08:03 volumio ntpd[1137]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 29 20:08:04 volumio volumio[1279]: info: BOOT COMPLETED Oct 29 20:08:07 volumio sudo[2197]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 20:08:07 volumio sudo[2197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:07 volumio sudo[2197]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:07 volumio sudo[2199]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:08:07 volumio sudo[2199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:07 volumio sudo[2199]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:07 volumio sudo[2202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 29 20:08:07 volumio sudo[2202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:07 volumio sudo[2202]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:07 volumio volumio[1279]: info: Upmpdcli Daemon Started Oct 29 20:08:15 volumio sudo[2224]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 20:08:15 volumio sudo[2224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:15 volumio sudo[2224]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:15 volumio sudo[2226]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:08:15 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:15 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:15 volumio volumio[1279]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.172 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 29 20:08:15 volumio volumio[1279]: info: Received Get System Info Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:08:15 volumio volumio[1279]: info: Discovery: Getting this device information Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::volumioGetState Oct 29 20:08:15 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:08:15 volumio volumio-remote-updater[882]: Test mode disabled Oct 29 20:08:15 volumio volumio-remote-updater[882]: Alpha mode disabled Oct 29 20:08:15 volumio volumio-remote-updater[882]: Alpha legacy test mode disabled Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 29 20:08:15 volumio volumio[1279]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 20:08:15 volumio ntpd[1137]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 29 20:08:15 volumio ntpd[1137]: DNS: dns_check: DNS error: -11, System error Oct 29 20:08:15 volumio ntpd[1137]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Oct 29 20:08:17 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 29 20:08:17 volumio volumio[1279]: info: Loading i18n strings for locale ja Oct 29 20:08:17 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 29 20:08:17 volumio volumio[1279]: info: Setting timezone to Asia/Tokyo Oct 29 20:08:17 volumio sudo[2235]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 29 20:08:17 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:17 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:17 volumio sudo[2239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Oct 29 20:08:17 volumio sudo[2239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:17 volumio sudo[2239]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:17 volumio sudo[2243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 29 20:08:17 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:17 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:17 volumio sudo[2247]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Oct 29 20:08:17 volumio sudo[2247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:17 volumio dbus-daemon[872]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.21' (uid=0 pid=2248 comm="/usr/bin/timedatectl set-timezone Asia/Tokyo") Oct 29 20:08:17 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Oct 29 20:08:17 volumio dbus-daemon[872]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 29 20:08:17 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Oct 29 20:08:17 volumio sudo[2247]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:17 volumio volumio[1279]: Updating browse sources language Oct 29 20:08:17 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 20:08:17 volumio sudo[2254]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 20:08:17 volumio sudo[2254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:17 volumio sudo[2256]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:08:17 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:17 volumio sudo[2254]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:17 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:18 volumio volumio[1279]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.172 UA: Mozilla/5.0 (Linux; Android 16; 2412DPC0AG Build/BP2A.250605.031.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.97 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::volumioGetVisibleSources Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Oct 29 20:08:18 volumio volumio[1279]: info: Received Get System Info Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 29 20:08:18 volumio volumio[1279]: info: Discovery: Getting this device information Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::volumioGetState Oct 29 20:08:18 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 29 20:08:18 volumio volumio[1279]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 20:08:19 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 29 20:08:19 volumio volumio[1279]: info: Loading i18n strings for locale ja Oct 29 20:08:19 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 29 20:08:19 volumio volumio[1279]: info: Setting timezone to Asia/Tokyo Oct 29 20:08:19 volumio sudo[2261]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 29 20:08:19 volumio sudo[2261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:19 volumio sudo[2261]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:19 volumio sudo[2265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Oct 29 20:08:19 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:19 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:19 volumio sudo[2269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 29 20:08:19 volumio sudo[2269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:19 volumio sudo[2269]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:19 volumio sudo[2273]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Oct 29 20:08:19 volumio sudo[2273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:19 volumio sudo[2273]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:19 volumio volumio[1279]: Updating browse sources language Oct 29 20:08:19 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 20:08:19 volumio volumio[1279]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.062&uuid=a645ced490e62077c56dc9461401fb1f" http://updates.volumio.org/downloader-v1/track-device Oct 29 20:08:19 volumio volumio[1279]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 29 20:08:19 volumio volumio[1279]: Dload Upload Total Spent Left Speed Oct 29 20:08:19 volumio volumio[1279]: [1.0K blob data] Oct 29 20:08:19 volumio volumio[1279]: retrying in 5 seconds, trial 1 Oct 29 20:08:19 volumio volumio[1279]: info: Volumio Calling Home Oct 29 20:08:19 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Oct 29 20:08:19 volumio volumio[1279]: info: Loading i18n strings for locale ja Oct 29 20:08:19 volumio volumio[1279]: Updating browse sources language Oct 29 20:08:19 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 29 20:08:19 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Oct 29 20:08:19 volumio volumio[1279]: info: Setting timezone to Asia/Tokyo Oct 29 20:08:19 volumio sudo[2280]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Oct 29 20:08:19 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:19 volumio sudo[2280]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:19 volumio sudo[2284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Oct 29 20:08:19 volumio sudo[2284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:19 volumio sudo[2284]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:19 volumio sudo[2288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Oct 29 20:08:19 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:19 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:19 volumio sudo[2292]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Oct 29 20:08:19 volumio sudo[2292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:19 volumio sudo[2292]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:19 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 29 20:08:19 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 29 20:08:19 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 29 20:08:19 volumio sudo[2295]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 29 20:08:19 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:20 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Oct 29 20:08:20 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 29 20:08:20 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:20 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 29 20:08:22 volumio volumio[1279]: info: Preparing to save Alsa Options, stopping services first Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::volumioGetState Oct 29 20:08:22 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::volumioPause Oct 29 20:08:22 volumio volumio[1279]: info: CoreStateMachine::pause Oct 29 20:08:22 volumio volumio[1279]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"HDMI 0 Out"},"disallowPush":true} Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:22 volumio volumio[1279]: info: Found match in Cards Database: setting mixer for card HDMI 0 Out Oct 29 20:08:22 volumio volumio[1279]: info: Device 0 does not have any Mixer Control Available Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 29 20:08:22 volumio volumio[1279]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 29 20:08:22 volumio volumio[1279]: info: Disabling external Volume Control Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 20:08:22 volumio volumio[1279]: info: CoreStateMachine::pushState Oct 29 20:08:22 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::volumioPushState Oct 29 20:08:22 volumio volumio[1279]: info: Preparing to generate the ALSA configuration file Oct 29 20:08:22 volumio volumio[1279]: info: Asound.conf file unchanged, so no further update is needed Oct 29 20:08:22 volumio volumio[1279]: info: Output device has changed, restarting MPD Oct 29 20:08:22 volumio volumio[1279]: info: Output device has changed, restarting Shairport Sync Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:22 volumio sudo[2328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 29 20:08:22 volumio sudo[2328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:22 volumio sudo[2328]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:22 volumio sudo[2330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 29 20:08:22 volumio sudo[2330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:22 volumio volumio[1279]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 29 20:08:22 volumio volumio[1279]: info: MPD Permissions set Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:22 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:22 volumio volumio[1279]: info: Starting Shairport Sync Oct 29 20:08:22 volumio sudo[2351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 29 20:08:23 volumio sudo[2351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 29 20:08:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 29 20:08:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 29 20:08:23 volumio systemd[1]: shairport-sync.service: Consumed 1.600s CPU time. Oct 29 20:08:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 29 20:08:23 volumio sudo[2351]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:23 volumio volumio[1279]: info: Shairport-Sync Started Oct 29 20:08:23 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 29 20:08:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 29 20:08:23 volumio systemd[1]: mpd.service: Consumed 1.867s CPU time. Oct 29 20:08:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 29 20:08:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 29 20:08:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 29 20:08:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 29 20:08:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 29 20:08:23 volumio sudo[2369]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 29 20:08:23 volumio sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 20:08:23 volumio sudo[2369]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:23 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Oct 29 20:08:23 volumio sudo[2295]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:23 volumio mpd[2371]: 2025-10-29T20:08:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 29 20:08:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 29 20:08:23 volumio sudo[2330]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:23 volumio volumio[1279]: error: updateQueue error: null Oct 29 20:08:24 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Oct 29 20:08:24 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 29 20:08:24 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:24 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:25 volumio volumio-remote-updater[882]: curl_easy_perform() failed: Couldn't resolve host name Oct 29 20:08:25 volumio volumio-remote-updater[882]: terminate called after throwing an instance of 'std::invalid_argument' Oct 29 20:08:25 volumio volumio-remote-updater[882]: what(): parse error - unexpected end of input Oct 29 20:08:25 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Oct 29 20:08:25 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Oct 29 20:08:25 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Oct 29 20:08:25 volumio systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 29 20:08:25 volumio systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 29 20:08:25 volumio volumio-remote-updater[2377]: Volumio OTA Client: 1.7.0 Oct 29 20:08:25 volumio volumio-remote-updater[2377]: Error: No active session Oct 29 20:08:25 volumio volumio-remote-updater[2377]: [2025-10-29 20:08:25] [connect] Successful connection Oct 29 20:08:25 volumio volumio-remote-updater[2377]: [2025-10-29 20:08:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1761736105 101 Oct 29 20:08:25 volumio volumio[1279]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 29 20:08:25 volumio volumio[1279]: info: Preparing to save Alsa Options, stopping services first Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::volumioGetState Oct 29 20:08:25 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::volumioPause Oct 29 20:08:25 volumio volumio[1279]: info: CoreStateMachine::pause Oct 29 20:08:25 volumio volumio[1279]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"HDMI 0 Out"},"disallowPush":true} Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:25 volumio volumio[1279]: info: Found match in Cards Database: setting mixer for card HDMI 0 Out Oct 29 20:08:25 volumio volumio[1279]: info: Device 0 does not have any Mixer Control Available Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 29 20:08:25 volumio volumio[1279]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI 0 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 29 20:08:25 volumio volumio[1279]: info: Disabling external Volume Control Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 20:08:25 volumio volumio[1279]: info: CoreStateMachine::pushState Oct 29 20:08:25 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::volumioPushState Oct 29 20:08:25 volumio volumio[1279]: info: Preparing to generate the ALSA configuration file Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 29 20:08:25 volumio volumio[1279]: info: Asound.conf file unchanged, so no further update is needed Oct 29 20:08:25 volumio volumio[1279]: info: Output device has changed, restarting MPD Oct 29 20:08:25 volumio volumio[1279]: info: Output device has changed, restarting Shairport Sync Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:25 volumio sudo[2399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 29 20:08:25 volumio sudo[2399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:25 volumio sudo[2399]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:25 volumio volumio[1279]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 29 20:08:25 volumio sudo[2403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 29 20:08:25 volumio volumio[1279]: info: MPD Permissions set Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:25 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 29 20:08:25 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:25 volumio volumio[1279]: info: Starting Shairport Sync Oct 29 20:08:25 volumio sudo[2414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 29 20:08:25 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:25 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 29 20:08:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 29 20:08:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 29 20:08:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 29 20:08:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 29 20:08:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 29 20:08:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 29 20:08:25 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 29 20:08:25 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 29 20:08:25 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 29 20:08:25 volumio systemd[1]: shairport-sync.service: Consumed 1.531s CPU time. Oct 29 20:08:25 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 29 20:08:25 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:25 volumio volumio[1279]: info: Shairport-Sync Started Oct 29 20:08:25 volumio sudo[2417]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 29 20:08:25 volumio sudo[2417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 20:08:25 volumio sudo[2417]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:26 volumio mpd[2434]: 2025-10-29T20:08:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 29 20:08:26 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 29 20:08:26 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:26 volumio volumio[1279]: error: updateQueue error: null Oct 29 20:08:27 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Oct 29 20:08:27 volumio volumio[1279]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 29 20:08:27 volumio volumio[1279]: info: CoreStateMachine::getcurrentVolume Oct 29 20:08:27 volumio volumio[1279]: info: CoreCommandRouter::volumioRetrievevolume Oct 29 20:08:27 volumio volumio[1279]: info: CoreStateMachine::pushState Oct 29 20:08:27 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:27 volumio volumio[1279]: info: CoreCommandRouter::volumioPushState Oct 29 20:08:27 volumio sudo[2438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 29 20:08:27 volumio sudo[2438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:27 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 29 20:08:27 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 29 20:08:27 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 29 20:08:27 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 29 20:08:27 volumio sudo[2438]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:27 volumio volumio[1279]: info: Successfully started MPD Monitor Oct 29 20:08:27 volumio mpd_monitor.sh[2440]: MPD Monitor Service: Starting MPD Monitor Service Oct 29 20:08:27 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Oct 29 20:08:27 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 29 20:08:27 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:27 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:29 volumio sudo[2448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 29 20:08:29 volumio sudo[2448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:29 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 29 20:08:29 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 29 20:08:29 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 29 20:08:29 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 29 20:08:29 volumio sudo[2448]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:29 volumio mpd_monitor.sh[2450]: MPD Monitor Service: Starting MPD Monitor Service Oct 29 20:08:29 volumio volumio[1279]: info: Successfully started MPD Monitor Oct 29 20:08:29 volumio volumio[1279]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 29 20:08:29 volumio volumio[1279]: info: CoreStateMachine::getcurrentVolume Oct 29 20:08:29 volumio volumio[1279]: info: CoreCommandRouter::volumioRetrievevolume Oct 29 20:08:29 volumio volumio[1279]: info: CoreStateMachine::pushState Oct 29 20:08:29 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:29 volumio volumio[1279]: info: CoreCommandRouter::volumioPushState Oct 29 20:08:31 volumio volumio[1279]: info: Discovery: this is already registered, f3472ed8-ab5e-493d-bcb2-77f88c9ed70f Oct 29 20:08:31 volumio volumio[1279]: info: Discovery: Found device Volumio Oct 29 20:08:31 volumio volumio[1279]: info: CoreCommandRouter::volumioGetState Oct 29 20:08:31 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 29 20:08:39 volumio volumio[1279]: info: Preparing to save Alsa Options, stopping services first Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::volumioGetState Oct 29 20:08:39 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::volumioPause Oct 29 20:08:39 volumio volumio[1279]: info: CoreStateMachine::pause Oct 29 20:08:39 volumio volumio[1279]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"allo-katana-dac","label":"Allo Katana"},"output_device":{"value":1,"label":"Allo Katana"},"disallowPush":true} Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: Enabling I2S DAC: Allo Katana Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio sudo[2473]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Oct 29 20:08:39 volumio sudo[2473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio sudo[2473]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:39 volumio volumio[1279]: info: No Overlays Loaded Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio sudo[2476]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-katana-dac-audio Oct 29 20:08:39 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio volumio[1279]: warn: Unable to locate the device Allo Katana a reboot may be required. Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2s@a4000/#sound-dai-cells Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2s@a4000/status Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#address-cells Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#size-cells Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/status Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/clock-frequency Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/compatible Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/label Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/dais Oct 29 20:08:39 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/status Oct 29 20:08:39 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: Found match in i2s Card Database: setting mixer Master for card Allo Katana Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 29 20:08:39 volumio volumio[1279]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo Katana Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 29 20:08:39 volumio volumio[1279]: info: Disabling external Volume Control Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 29 20:08:39 volumio volumio[1279]: info: Preparing to generate the ALSA configuration file Oct 29 20:08:39 volumio sudo[2506]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 29 20:08:39 volumio sudo[2506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio volumio[1279]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Oct 29 20:08:39 volumio sudo[2508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 29 20:08:39 volumio sudo[2508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio volumio[1279]: info: I2S Param [object Object] successfully enabled Oct 29 20:08:39 volumio sudo[2508]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:39 volumio volumio[1279]: info: VolumeController:: Volume=undefined Mute =false Oct 29 20:08:39 volumio volumio[1279]: info: CoreStateMachine::pushState Oct 29 20:08:39 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::volumioPushState Oct 29 20:08:39 volumio volumio[1279]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 29 20:08:39 volumio volumio[1279]: wlan0 Interface doesn't support scanning : Device or resource busy Oct 29 20:08:39 volumio volumio[1279]: info: Cannot use regular scanning, forcing with ap-force Oct 29 20:08:39 volumio sudo[2515]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 29 20:08:39 volumio sudo[2515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio sudo[2515]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:39 volumio (udev-worker)[2491]: controlC2: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 2' failed with exit code 99. Oct 29 20:08:39 volumio volumio[1279]: command failed: Device or resource busy (-16) Oct 29 20:08:39 volumio volumio[1279]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 29 20:08:39 volumio volumio[1279]: command failed: Device or resource busy (-16) Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 29 20:08:39 volumio volumio[1279]: info: Getting Alsa Cards List without I2S DAC Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 29 20:08:39 volumio volumio[1279]: info: Asound.conf file written Oct 29 20:08:39 volumio sudo[2545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 29 20:08:39 volumio sudo[2545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio sudo[2545]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:39 volumio volumio[1279]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 29 20:08:39 volumio volumio[1279]: No state is present for card vc4hdmi0 Oct 29 20:08:39 volumio volumio[1279]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 29 20:08:39 volumio volumio[1279]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 29 20:08:39 volumio volumio[1279]: Hardware is initialized using a generic method Oct 29 20:08:39 volumio volumio[1279]: No state is present for card vc4hdmi0 Oct 29 20:08:39 volumio volumio[1279]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 29 20:08:39 volumio volumio[1279]: No state is present for card vc4hdmi1 Oct 29 20:08:39 volumio volumio[1279]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 29 20:08:39 volumio volumio[1279]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 29 20:08:39 volumio volumio[1279]: Hardware is initialized using a generic method Oct 29 20:08:39 volumio volumio[1279]: No state is present for card vc4hdmi1 Oct 29 20:08:39 volumio volumio[1279]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 29 20:08:39 volumio volumio[1279]: No state is present for card Katana Oct 29 20:08:39 volumio volumio[1279]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 29 20:08:39 volumio volumio[1279]: Found hardware: "Allo_Katana" "" "" "" "" Oct 29 20:08:39 volumio volumio[1279]: Hardware is initialized using a generic method Oct 29 20:08:39 volumio volumio[1279]: No state is present for card Katana Oct 29 20:08:39 volumio volumio[1279]: info: Output device has changed, restarting MPD Oct 29 20:08:39 volumio volumio[1279]: info: Output device has changed, restarting Shairport Sync Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio sudo[2551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 29 20:08:39 volumio sudo[2551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio sudo[2553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 29 20:08:39 volumio sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio sudo[2551]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:39 volumio volumio[1279]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 29 20:08:39 volumio volumio[1279]: info: Output device has changed, restarting MPD Oct 29 20:08:39 volumio volumio[1279]: info: Output device has changed, restarting Shairport Sync Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 29 20:08:39 volumio sudo[2561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 29 20:08:39 volumio sudo[2563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 29 20:08:39 volumio sudo[2563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio sudo[2561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:39 volumio sudo[2561]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:39 volumio volumio[1279]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 29 20:08:39 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 29 20:08:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 29 20:08:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 29 20:08:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 29 20:08:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 29 20:08:39 volumio volumio[1279]: info: MPD Permissions set Oct 29 20:08:39 volumio volumio[1279]: info: MPD Permissions set Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:39 volumio volumio[1279]: info: Starting Shairport Sync Oct 29 20:08:39 volumio volumio[1279]: info: Starting Shairport Sync Oct 29 20:08:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 29 20:08:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 29 20:08:40 volumio sudo[2586]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 29 20:08:40 volumio sudo[2583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 29 20:08:40 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 20:08:40 volumio sudo[2583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:40 volumio sudo[2585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 29 20:08:40 volumio sudo[2585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:40 volumio sudo[2586]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:40 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 29 20:08:40 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 29 20:08:40 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 29 20:08:40 volumio systemd[1]: shairport-sync.service: Consumed 1.544s CPU time. Oct 29 20:08:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 29 20:08:40 volumio sudo[2585]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:40 volumio sudo[2583]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:40 volumio volumio[1279]: info: Shairport-Sync Started Oct 29 20:08:40 volumio volumio[1279]: info: Shairport-Sync Started Oct 29 20:08:40 volumio mpd[2604]: 2025-10-29T20:08:40 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 29 20:08:40 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 29 20:08:40 volumio sudo[2563]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:40 volumio sudo[2553]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:40 volumio volumio[1279]: error: MPD error: The expression evaluated to a falsy value: Oct 29 20:08:40 volumio volumio[1279]: assert.ok(self.idling) Oct 29 20:08:40 volumio volumio[1279]: error: The expression evaluated to a falsy value: Oct 29 20:08:40 volumio volumio[1279]: assert.ok(self.idling) Oct 29 20:08:40 volumio volumio[1279]: error: updateQueue error: null Oct 29 20:08:41 volumio volumio[1279]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.062&uuid=a645ced490e62077c56dc9461401fb1f" http://updates.volumio.org/downloader-v1/track-device Oct 29 20:08:41 volumio volumio[1279]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 29 20:08:41 volumio volumio[1279]: Dload Upload Total Spent Left Speed Oct 29 20:08:41 volumio volumio[1279]: [1.0K blob data] Oct 29 20:08:41 volumio volumio[1279]: retrying in 5 seconds, trial 2 Oct 29 20:08:41 volumio volumio[1279]: info: Volumio Calling Home Oct 29 20:08:43 volumio sudo[2506]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:43 volumio volumio[1279]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 29 20:08:43 volumio volumio[1279]: info: CoreStateMachine::getcurrentVolume Oct 29 20:08:43 volumio volumio[1279]: info: CoreCommandRouter::volumioRetrievevolume Oct 29 20:08:43 volumio volumio[1279]: info: VolumeController:: Volume=46 Mute =false Oct 29 20:08:43 volumio volumio[1279]: info: CoreStateMachine::pushState Oct 29 20:08:43 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:43 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 29 20:08:43 volumio volumio[1279]: info: CoreCommandRouter::volumioPushState Oct 29 20:08:43 volumio volumio[1279]: info: CoreStateMachine::updateTrackBlock Oct 29 20:08:43 volumio volumio[1279]: info: CorePlayQueue::getTrackBlock Oct 29 20:08:43 volumio volumio[1279]: info: CoreCommandRouter::volumioRetrievevolume Oct 29 20:08:43 volumio volumio[1279]: info: VolumeController:: Volume=46 Mute =false Oct 29 20:08:43 volumio volumio[1279]: info: CoreStateMachine::pushState Oct 29 20:08:43 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:08:43 volumio volumio[1279]: info: CoreCommandRouter::volumioPushState Oct 29 20:08:43 volumio sudo[2630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 29 20:08:43 volumio sudo[2630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:43 volumio sudo[2632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Oct 29 20:08:43 volumio sudo[2632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:43 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Oct 29 20:08:43 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Oct 29 20:08:43 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Oct 29 20:08:43 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Oct 29 20:08:43 volumio sudo[2632]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:43 volumio sudo[2630]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:43 volumio volumio[1279]: info: Successfully started MPD Monitor Oct 29 20:08:43 volumio mpd_monitor.sh[2635]: MPD Monitor Service: Starting MPD Monitor Service Oct 29 20:08:43 volumio volumio[1279]: info: Successfully started MPD Monitor Oct 29 20:08:44 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:47 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 29 20:08:47 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 20:08:49 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 29 20:08:49 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 20:08:49 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Oct 29 20:08:49 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 29 20:08:49 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Oct 29 20:08:50 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Oct 29 20:08:50 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Oct 29 20:08:50 volumio sudo[2657]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 20:08:50 volumio sudo[2657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:50 volumio sudo[2657]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:51 volumio volumio[1279]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 20:08:53 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Oct 29 20:08:53 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Oct 29 20:08:53 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Oct 29 20:08:53 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Oct 29 20:08:53 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Oct 29 20:08:53 volumio volumio[1279]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 29 20:08:54 volumio volumio[1279]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Oct 29 20:08:54 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Oct 29 20:08:54 volumio volumio[1279]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'value') Oct 29 20:08:55 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Oct 29 20:08:55 volumio volumio[1279]: info: CoreCommandRouter::Reload Ui Oct 29 20:08:55 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Oct 29 20:08:55 volumio volumio[1279]: info: Wizard terminated Successfully Oct 29 20:08:55 volumio volumio[1279]: info: CoreCommandRouter::volumiosetStartupVolume Oct 29 20:08:55 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 29 20:08:55 volumio volumio[1279]: info: Player Reboot required after I2S DAC has been enabled in wizard Oct 29 20:08:55 volumio volumio[1279]: info: Executing Deferred Wifi Connection Oct 29 20:08:55 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Oct 29 20:08:55 volumio volumio[1279]: info: Saving new wireless network Oct 29 20:08:55 volumio sudo[2660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Oct 29 20:08:55 volumio sudo[2660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:58 volumio sudo[2660]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:58 volumio volumio[1279]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 29 20:08:58 volumio volumio[1279]: info: Discovery: Restarting Advertising due to device name change Oct 29 20:08:58 volumio sudo[2673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 29 20:08:58 volumio sudo[2673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:58 volumio systemd[1]: Stopping wireless.service - Wireless Services... Oct 29 20:08:58 volumio systemd[1]: wireless.service: Deactivated successfully. Oct 29 20:08:58 volumio systemd[1]: Stopped wireless.service - Wireless Services. Oct 29 20:08:58 volumio systemd[1]: Starting wireless.service - Wireless Services... Oct 29 20:08:58 volumio wireless.js[2675]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Oct 29 20:08:58 volumio wireless.js[2675]: WIRELESS.JS: Wireless.js initializing wireless flow Oct 29 20:08:58 volumio wireless.js[2675]: WIRELESS.JS: Cleaning previous... Oct 29 20:08:59 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Oct 29 20:08:59 volumio dnsmasq[2021]: exiting on receipt of SIGTERM Oct 29 20:08:59 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Oct 29 20:08:59 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Oct 29 20:08:59 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Oct 29 20:08:59 volumio sudo[2694]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 29 20:08:59 volumio sudo[2694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:59 volumio sudo[2696]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:08:59 volumio sudo[2696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:59 volumio sudo[2694]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:59 volumio sudo[2696]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:59 volumio volumio[1279]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.172 UA: Mozilla/5.0 (Linux; Android 16; 2412DPC0AG Build/BP2A.250605.031.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.97 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 29 20:08:59 volumio volumio[1279]: info: Discovery: A device disappeared from network Oct 29 20:08:59 volumio volumio[1279]: info: Discovery: Device volumio disappeared from network Oct 29 20:08:59 volumio volumio[1279]: info: Discovery: A device disappeared from network Oct 29 20:08:59 volumio sudo[2700]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:08:59 volumio sudo[2700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:08:59 volumio sudo[2700]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:59 volumio dhcpcd[959]: wlan0: carrier lost Oct 29 20:08:59 volumio systemd[1]: hostapd.service: Deactivated successfully. Oct 29 20:08:59 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Oct 29 20:08:59 volumio avahi-daemon[870]: Withdrawing address record for 169.254.36.217 on wlan0. Oct 29 20:08:59 volumio dhcpcd[959]: wlan0: deleting route to 169.254.0.0/16 Oct 29 20:08:59 volumio dhcpcd[959]: wlan0: deleting default route Oct 29 20:08:59 volumio sudo[2710]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Oct 29 20:08:59 volumio sudo[2710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 20:08:59 volumio avahi-daemon[870]: Withdrawing address record for 192.168.211.1 on wlan0. Oct 29 20:08:59 volumio avahi-daemon[870]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 29 20:08:59 volumio avahi-daemon[870]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 29 20:08:59 volumio sudo[2710]: pam_unix(sudo:session): session closed for user root Oct 29 20:08:59 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 29 20:08:59 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Oct 29 20:08:59 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 29 20:08:59 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 29 20:08:59 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 29 20:08:59 volumio sudo[2720]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Oct 29 20:08:59 volumio sudo[2720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 20:08:59 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 29 20:09:00 volumio sudo[2720]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:00 volumio welcome[2725]: Resolved ip:[0] Oct 29 20:09:00 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 29 20:09:00 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 29 20:09:00 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 29 20:09:00 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Oct 29 20:09:00 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 29 20:09:00 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 29 20:09:00 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 29 20:09:00 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 29 20:09:00 volumio welcome[2751]: Resolved ip:[0] Oct 29 20:09:00 volumio wireless.js[2675]: WIRELESS.JS: Stopped aP Oct 29 20:09:00 volumio sudo[2758]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 29 20:09:00 volumio sudo[2758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:00 volumio sudo[2758]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 29 20:09:00 volumio sudo[2760]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 29 20:09:00 volumio sudo[2760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:00 volumio sudo[2760]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:00 volumio sudo[2768]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 29 20:09:00 volumio sudo[2768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:00 volumio sudo[2768]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:00 volumio sudo[2770]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 29 20:09:00 volumio sudo[2770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:00 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 29 20:09:00 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 29 20:09:00 volumio sudo[2777]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:00 volumio sudo[2777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:00 volumio sudo[2777]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:01 volumio sudo[2780]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:01 volumio sudo[2780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:01 volumio sudo[2780]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:01 volumio ntpd[1137]: IO: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=106 secs Oct 29 20:09:01 volumio ntpd[1137]: IO: Deleting interface #6 wlan0, 169.254.36.217#123, interface stats: received=0, sent=0, dropped=0, active_time=94 secs Oct 29 20:09:02 volumio sudo[2783]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:02 volumio sudo[2783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:02 volumio sudo[2783]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:02 volumio sudo[2770]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:02 volumio wireless.js[2675]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: JP Oct 29 20:09:02 volumio sudo[2787]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 29 20:09:02 volumio sudo[2787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:02 volumio sudo[2787]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:02 volumio sudo[2789]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Oct 29 20:09:02 volumio sudo[2789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:02 volumio sudo[2789]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:02 volumio wireless.js[2675]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: JP Oct 29 20:09:02 volumio wireless.js[2675]: WIRELESS.JS: Start wireless flow Oct 29 20:09:02 volumio wireless.js[2675]: WIRELESS.JS: Stopped hotspot (if there).. Oct 29 20:09:02 volumio sudo[2797]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Oct 29 20:09:02 volumio sudo[2797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 20:09:02 volumio sudo[2797]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:02 volumio sudo[2799]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Oct 29 20:09:02 volumio sudo[2799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 20:09:03 volumio sudo[2799]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:03 volumio wireless.js[2675]: WIRELESS.JS: DHCP IP fallback Oct 29 20:09:03 volumio wireless.js[2675]: WIRELESS.JS: Start ap Oct 29 20:09:03 volumio wpa_supplicant[2804]: Successfully initialized wpa_supplicant Oct 29 20:09:03 volumio sudo[2805]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 29 20:09:03 volumio sudo[2805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 20:09:03 volumio dhcpcd[959]: control command: /sbin/dhcpcd Oct 29 20:09:03 volumio dhcpcd[959]: control_free: No such file or directory Oct 29 20:09:03 volumio sudo[2805]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:03 volumio volumio[1279]: info: Discovery: Started advertising with name: Volumio Oct 29 20:09:03 volumio sudo[2810]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:03 volumio sudo[2810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:03 volumio dhcpcd[959]: lo: IAID 00:00:00:00 Oct 29 20:09:03 volumio dhcpcd[959]: lo: adding address fe80::f5e:7656:c15:f76b Oct 29 20:09:03 volumio dhcpcd[959]: ipv6_addaddr1: Permission denied Oct 29 20:09:03 volumio dhcpcd[959]: lo: using static address 127.0.0.1/8 Oct 29 20:09:03 volumio dhcpcd[959]: lo: adding route to 127.0.0.0/8 Oct 29 20:09:03 volumio sudo[2810]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:03 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 29 20:09:03 volumio systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. Oct 29 20:09:03 volumio wpa_supplicant[2804]: nl80211: kernel reports: Registration to specific type not supported Oct 29 20:09:04 volumio volumio[1279]: info: Discovery: adding f3472ed8-ab5e-493d-bcb2-77f88c9ed70f Oct 29 20:09:04 volumio volumio[1279]: info: Discovery: Found device Volumio Oct 29 20:09:04 volumio volumio[1279]: info: CoreCommandRouter::volumioGetState Oct 29 20:09:04 volumio volumio[1279]: info: CorePlayQueue::getTrack 0 Oct 29 20:09:04 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:04 volumio sudo[2846]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:04 volumio sudo[2846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:04 volumio sudo[2846]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:04 volumio sudo[2849]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:04 volumio sudo[2849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:04 volumio sudo[2849]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:05 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:05 volumio sudo[2853]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:05 volumio sudo[2853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:05 volumio sudo[2853]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:05 volumio sudo[2856]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:05 volumio sudo[2856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:05 volumio sudo[2856]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:06 volumio wpa_supplicant[2839]: wlan0: Trying to associate with a4:12:42:90:aa:9f (SSID='ctc-a-98fb98' freq=5580 MHz) Oct 29 20:09:06 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:06 volumio sudo[2860]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:06 volumio sudo[2860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:06 volumio sudo[2860]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:06 volumio sudo[2863]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:06 volumio sudo[2863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:06 volumio sudo[2863]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:06 volumio wpa_supplicant[2839]: wlan0: Associated with a4:12:42:90:aa:9f Oct 29 20:09:06 volumio wpa_supplicant[2839]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 29 20:09:06 volumio wpa_supplicant[2839]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Oct 29 20:09:06 volumio wpa_supplicant[2839]: wlan0: WPA: Key negotiation completed with a4:12:42:90:aa:9f [PTK=CCMP GTK=CCMP] Oct 29 20:09:06 volumio wpa_supplicant[2839]: wlan0: CTRL-EVENT-CONNECTED - Connection to a4:12:42:90:aa:9f completed [id=0 id_str=] Oct 29 20:09:06 volumio dhcpcd[959]: wlan0: carrier acquired Oct 29 20:09:06 volumio dhcpcd[959]: wlan0: connected to Access Point: ctc-a-98fb98 Oct 29 20:09:06 volumio dhcpcd[959]: wlan0: IAID 67:43:a3:8f Oct 29 20:09:06 volumio dhcpcd[959]: wlan0: soliciting an IPv6 router Oct 29 20:09:07 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:07 volumio sudo[2868]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:07 volumio sudo[2868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:07 volumio sudo[2868]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:07 volumio sudo[2871]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:07 volumio sudo[2871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:07 volumio sudo[2871]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:07 volumio dhcpcd[959]: wlan0: soliciting a DHCP lease Oct 29 20:09:08 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:08 volumio sudo[2876]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:08 volumio sudo[2876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:08 volumio sudo[2876]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:08 volumio sudo[2879]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:08 volumio sudo[2879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:08 volumio sudo[2879]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:08 volumio dhcpcd[959]: wlan0: offered 192.168.0.2 from 192.168.0.1 Oct 29 20:09:08 volumio dhcpcd[959]: wlan0: probing address 192.168.0.2/24 Oct 29 20:09:09 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:09 volumio sudo[2884]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:09 volumio sudo[2884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:09 volumio sudo[2884]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:09 volumio sudo[2887]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:09 volumio sudo[2887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:09 volumio sudo[2887]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:10 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:10 volumio sudo[2905]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:10 volumio sudo[2905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:10 volumio sudo[2905]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:10 volumio sudo[2908]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:10 volumio sudo[2908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:10 volumio sudo[2908]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:11 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:11 volumio sudo[2912]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:11 volumio sudo[2912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:11 volumio sudo[2912]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:11 volumio sudo[2915]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:11 volumio sudo[2915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:11 volumio sudo[2915]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:12 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:12 volumio sudo[2919]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:12 volumio sudo[2919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:12 volumio sudo[2919]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:12 volumio sudo[2922]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:12 volumio sudo[2922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:12 volumio sudo[2922]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:13 volumio dhcpcd[959]: wlan0: leased 192.168.0.2 for 86400 seconds Oct 29 20:09:13 volumio avahi-daemon[870]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.2. Oct 29 20:09:13 volumio avahi-daemon[870]: New relevant interface wlan0.IPv4 for mDNS. Oct 29 20:09:13 volumio avahi-daemon[870]: Registering new address record for 192.168.0.2 on wlan0.IPv4. Oct 29 20:09:13 volumio dhcpcd[959]: wlan0: adding route to 192.168.0.0/24 Oct 29 20:09:13 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 29 20:09:13 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Oct 29 20:09:13 volumio systemd[1]: welcome.service: Deactivated successfully. Oct 29 20:09:13 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 29 20:09:13 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 29 20:09:13 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 29 20:09:13 volumio welcome[2939]: Resolved ip:[1] 192.168.0.2 Oct 29 20:09:13 volumio volumio[1279]: compat.c: read() failed: Resource temporarily unavailable Oct 29 20:09:13 volumio volumio[1279]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 20:09:13 volumio volumio[1279]: Error: dns service error: unknown Oct 29 20:09:13 volumio volumio[1279]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Oct 29 20:09:13 volumio volumio[1279]: errorCode: -65537 Oct 29 20:09:13 volumio volumio[1279]: } Oct 29 20:09:13 volumio volumio[1279]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 29 20:09:13 volumio wireless.js[2675]: WIRELESS.JS: trying... Oct 29 20:09:13 volumio sudo[2957]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 29 20:09:13 volumio sudo[2957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 29 20:09:13 volumio sudo[2957]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:13 volumio wireless.js[2675]: WIRELESS.JS: Connected to: ----ctc-a-98fb98---- Oct 29 20:09:13 volumio sudo[2960]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 29 20:09:13 volumio sudo[2960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 29 20:09:13 volumio sudo[2960]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:13 volumio wireless.js[2675]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 192.168.0.2, ipV6 is undefined Oct 29 20:09:13 volumio wireless.js[2675]: WIRELESS.JS: It's done! AP Oct 29 20:09:13 volumio wireless.js[2675]: WIRELESS.JS: Restarting avahi-daemon... Oct 29 20:09:13 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 29 20:09:13 volumio wireless.js[2675]: WIRELESS.JS: Notified systemd about wireless ready Oct 29 20:09:13 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 29 20:09:13 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 29 20:09:13 volumio systemd[1]: shairport-sync.service: Consumed 1.553s CPU time. Oct 29 20:09:13 volumio avahi-daemon[870]: Got SIGTERM, quitting. Oct 29 20:09:13 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 29 20:09:13 volumio avahi-daemon[870]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.2. Oct 29 20:09:13 volumio avahi-daemon[870]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 29 20:09:13 volumio systemd[1]: Started wireless.service - Wireless Services. Oct 29 20:09:13 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 29 20:09:13 volumio sudo[2673]: pam_unix(sudo:session): session closed for user root Oct 29 20:09:13 volumio dbus-daemon[872]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.34' (uid=0 pid=1353 comm="/usr/sbin/smbd --foreground --no-process-group") Oct 29 20:09:13 volumio avahi-daemon[870]: avahi-daemon 0.8 exiting. Oct 29 20:09:13 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Oct 29 20:09:13 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 29 20:09:13 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 29 20:09:13 volumio avahi-daemon[2968]: Process 870 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Oct 29 20:09:13 volumio avahi-daemon[2968]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Oct 29 20:09:13 volumio avahi-daemon[2968]: Successfully dropped root privileges. Oct 29 20:09:13 volumio avahi-daemon[2968]: avahi-daemon 0.8 starting up. Oct 29 20:09:13 volumio dbus-daemon[872]: [system] Successfully activated service 'org.freedesktop.Avahi' Oct 29 20:09:13 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Oct 29 20:09:13 volumio avahi-daemon[2968]: Successfully called chroot(). Oct 29 20:09:13 volumio avahi-daemon[2968]: Successfully dropped remaining capabilities. Oct 29 20:09:13 volumio avahi-daemon[2968]: No service file found in /etc/avahi/services. Oct 29 20:09:13 volumio avahi-daemon[2968]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.2. Oct 29 20:09:13 volumio avahi-daemon[2968]: New relevant interface wlan0.IPv4 for mDNS. Oct 29 20:09:13 volumio avahi-daemon[2968]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 29 20:09:13 volumio avahi-daemon[2968]: New relevant interface lo.IPv4 for mDNS. Oct 29 20:09:13 volumio avahi-daemon[2968]: Network interface enumeration completed. Oct 29 20:09:13 volumio avahi-daemon[2968]: Registering new address record for 192.168.0.2 on wlan0.IPv4. Oct 29 20:09:13 volumio avahi-daemon[2968]: Registering new address record for 127.0.0.1 on lo.IPv4. Oct 29 20:09:13 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 29 20:09:13 volumio dhcpcd[959]: wlan0: adding default route via 192.168.0.1 Oct 29 20:09:13 volumio sudo[2991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-29 20:08' Oct 29 20:09:13 volumio sudo[2991]: 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="5ef3d56f987d109f3e21bfa246dbbb723cf6a917" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="c7bff9654d2984a81c5720476c2c8ac531869c54" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Oct 9 18:08:11 UTC 2025" VOLUMIO_VERSION="4.062" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="00524295c246b7d72f125e4c54b86a88"