Aug 29 21:07:00 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 21:07:00 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 29 21:07:00 volumio sudo[16164]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 29 21:07:00 volumio sudo[16164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:00 volumio sudo[16164]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:00 volumio sudo[16170]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 29 21:07:00 volumio sudo[16170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:00 volumio sudo[16170]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:00 volumio sudo[16177]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 29 21:07:00 volumio sudo[16177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:00 volumio sudo[16180]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 29 21:07:00 volumio sudo[16180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:00 volumio sudo[16180]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:00 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 29 21:07:00 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 29 21:07:00 volumio sudo[16177]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:00 volumio sudo[16186]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 21:07:00 volumio sudo[16186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:00 volumio sudo[16186]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:00 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 29 21:07:00 volumio sudo[16191]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:00 volumio sudo[16191]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:00 volumio volumio[1258]: info: CoreStateMachine::getcurrentVolume Aug 29 21:07:00 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:00 volumio sudo[16191]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:00 volumio sudo[16196]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 21:07:00 volumio sudo[16196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:00 volumio sudo[16200]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:00 volumio sudo[16200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:00 volumio sudo[16200]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:00 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:00 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:00 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:00 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:01 volumio volumio[1258]: info: CoreStateMachine::updateTrackBlock Aug 29 21:07:01 volumio volumio[1258]: info: CorePlayQueue::getTrackBlock Aug 29 21:07:01 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:01 volumio mpd[16152]: 2026-08-29T21:07:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:07:01 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:01 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:01 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:01 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:01 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 21:07:01 volumio sudo[16038]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:01 volumio sudo[16124]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:01 volumio sudo[15942]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:01 volumio volumio[1258]: error: MPD error: The expression evaluated to a falsy value: Aug 29 21:07:01 volumio volumio[1258]: assert.ok(self.idling) Aug 29 21:07:01 volumio volumio[1258]: error: The expression evaluated to a falsy value: Aug 29 21:07:01 volumio volumio[1258]: assert.ok(self.idling) Aug 29 21:07:01 volumio volumio[1258]: error: MPD error: The expression evaluated to a falsy value: Aug 29 21:07:01 volumio volumio[1258]: assert.ok(self.idling) Aug 29 21:07:01 volumio volumio[1258]: error: The expression evaluated to a falsy value: Aug 29 21:07:01 volumio volumio[1258]: assert.ok(self.idling) Aug 29 21:07:01 volumio volumio[1258]: error: MPD error: The expression evaluated to a falsy value: Aug 29 21:07:01 volumio volumio[1258]: assert.ok(self.idling) Aug 29 21:07:01 volumio volumio[1258]: error: The expression evaluated to a falsy value: Aug 29 21:07:01 volumio volumio[1258]: assert.ok(self.idling) Aug 29 21:07:01 volumio volumio[1258]: error: updateQueue error: null Aug 29 21:07:01 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 21:07:01 volumio sudo[16208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:01 volumio sudo[16208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:01 volumio sudo[16208]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 29 21:07:02 volumio volumio[1258]: info: CoreStateMachine::getcurrentVolume Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:02 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:02 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:02 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:02 volumio volumio[1258]: info: CoreStateMachine::updateTrackBlock Aug 29 21:07:02 volumio volumio[1258]: info: CorePlayQueue::getTrackBlock Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:02 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:02 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:02 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 21:07:02 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:02 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:02 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:02 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:02 volumio sudo[16215]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:02 volumio sudo[16215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:02 volumio sudo[16215]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:03 volumio sudo[16196]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:03 volumio sudo[16218]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:03 volumio sudo[16218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:03 volumio sudo[16218]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 21:07:03 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:03 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:03 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:03 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:03 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:04 volumio sudo[16221]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 21:07:04 volumio sudo[16221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:04 volumio sudo[16221]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:04 volumio sudo[16223]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:04 volumio sudo[16223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:04 volumio sudo[16223]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:04 volumio sudo[16227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 29 21:07:04 volumio sudo[16227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:04 volumio sudo[16227]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:04 volumio volumio[1258]: info: Upmpdcli Daemon Started Aug 29 21:07:04 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:04 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:04 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:04 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:04 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:04 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:04 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:04 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:07:04 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:07:04 volumio sudo[16231]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:04 volumio sudo[16231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:04 volumio sudo[16231]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:05 volumio volumio5-onboarding[1566]: time=2026-08-29T21:07:05.251+03:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 21:07:05 volumio sudo[16234]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:05 volumio sudo[16234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:05 volumio sudo[16234]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:06 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 21:07:06 volumio sudo[16237]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:06 volumio sudo[16237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:06 volumio sudo[16237]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:07 volumio sudo[16254]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:07 volumio sudo[16254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:07 volumio sudo[16254]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:08 volumio sudo[16257]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:08 volumio sudo[16257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:08 volumio sudo[16257]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:12 volumio dhcpcd[880]: eth0: carrier lost Aug 29 21:07:12 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 29 21:07:12 volumio avahi-daemon[1372]: Withdrawing address record for 192.168.3.22 on eth0. Aug 29 21:07:12 volumio avahi-daemon[1372]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.3.22. Aug 29 21:07:12 volumio avahi-daemon[1372]: Interface eth0.IPv4 no longer relevant for mDNS. Aug 29 21:07:12 volumio dhcpcd[880]: eth0: deleting route to 192.168.3.0/24 Aug 29 21:07:12 volumio dhcpcd[880]: eth0: deleting default route via 192.168.3.1 Aug 29 21:07:12 volumio dhcpcd[880]: eth0: dhcp_envoption 213: Operation not supported Aug 29 21:07:12 volumio volumio[1258]: info: Discovery: A device disappeared from network Aug 29 21:07:12 volumio volumio[1258]: info: Discovery: A device disappeared from network Aug 29 21:07:12 volumio volumio[1258]: info: Discovery: A device disappeared from network Aug 29 21:07:12 volumio volumio[1258]: info: Discovery: A device disappeared from network Aug 29 21:07:12 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Aug 29 21:07:12 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Aug 29 21:07:12 volumio systemd[1]: welcome.service: Deactivated successfully. Aug 29 21:07:12 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 29 21:07:12 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 29 21:07:12 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 29 21:07:12 volumio welcome[16284]: Resolved ip:[0] Aug 29 21:07:12 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 29 21:07:12 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Aug 29 21:07:13 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:13 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:13 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:07:13 volumio ifplugd(eth0)[1033]: Link beat lost. Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 21:07:13 volumio volumio[1258]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 21:07:13 volumio volumio[1258]: info: Received Get System Version Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 21:07:13 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:13 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:13 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:13 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:14 volumio ntpd[1002]: IO: Deleting interface #17 eth0, 192.168.3.22#123, interface stats: received=67, sent=69, dropped=0, active_time=101 secs Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 162.159.200.1 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 87.103.245.205 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 51.250.53.172 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 195.54.177.84 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 62.113.114.46 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 89.223.121.15 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 79.111.119.96 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 89.179.240.219 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 85.193.65.152 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 93.95.100.66 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 51.250.68.198 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 94.100.180.133 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 77.50.202.25 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 93.95.100.85 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 51.250.35.68 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio ntpd[1002]: PROTO: 92.255.126.1 unlink local addr 192.168.3.22 -> Aug 29 21:07:14 volumio volumio5-onboarding[1566]: time=2026-08-29T21:07:14.219+03:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 21:07:15 volumio kernel: usb 1-1.2: USB disconnect, device number 39 Aug 29 21:07:15 volumio volumio[1258]: info: Aug 29 21:07:15 volumio volumio[1258]: ---------------------------- USB Audio Device Detached Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 29 21:07:15 volumio kernel: usb 1-1.2: new high-speed USB device number 40 using xhci_hcd Aug 29 21:07:15 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.07 Aug 29 21:07:15 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Aug 29 21:07:15 volumio kernel: usb 1-1.2: Product: E70 Velvet Aug 29 21:07:15 volumio kernel: usb 1-1.2: Manufacturer: Topping Aug 29 21:07:15 volumio (udev-worker)[16290]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Aug 29 21:07:15 volumio volumio[1258]: info: Aug 29 21:07:15 volumio volumio[1258]: ---------------------------- USB Audio Device Attached Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::Close All Modals sent Aug 29 21:07:15 volumio volumio[1258]: info: Preparing to save Alsa Options, stopping services first Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:15 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::volumioPause Aug 29 21:07:15 volumio volumio[1258]: info: CoreStateMachine::pause Aug 29 21:07:15 volumio volumio[1258]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E70 Velvet","alsacard":"Velvet"},"i2s":false} Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:15 volumio volumio[1258]: info: Setting mixer E70 Velvet for card E70 Velvet Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 21:07:15 volumio volumio[1258]: info: Updating Volume Controller Parameters: Device: 5 Name: E70 Velvet Mixer: E70 Velvet Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 21:07:15 volumio volumio[1258]: info: Disabling external Volume Control Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:07:15 volumio volumio[1258]: info: Preparing to generate the ALSA configuration file Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:15 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 21:07:16 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:16 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:16 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:16 volumio volumio[1258]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:07:16 volumio volumio[1258]: info: Output device has changed, restarting MPD Aug 29 21:07:16 volumio volumio[1258]: info: Output device has changed, restarting Shairport Sync Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:16 volumio sudo[16326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:07:16 volumio sudo[16326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:16 volumio sudo[16326]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:16 volumio sudo[16327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:07:16 volumio sudo[16327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:16 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:07:16 volumio volumio[1258]: info: Output device has changed, restarting Shairport Sync Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:16 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 21:07:16 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:07:16 volumio volumio[1258]: info: MPD Permissions set Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 29 21:07:16 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 21:07:16 volumio systemd[1]: mpd.service: Consumed 2.074s CPU time. Aug 29 21:07:16 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 29 21:07:16 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 21:07:16 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:16 volumio volumio[1258]: info: Starting Shairport Sync Aug 29 21:07:16 volumio volumio[1258]: info: Starting Shairport Sync Aug 29 21:07:16 volumio sudo[16346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:07:16 volumio sudo[16346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:16 volumio sudo[16348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:07:16 volumio sudo[16348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 21:07:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 21:07:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 21:07:16 volumio sudo[16348]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:16 volumio volumio[1258]: info: Shairport-Sync Started Aug 29 21:07:16 volumio sudo[16346]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:16 volumio volumio[1258]: info: Shairport-Sync Started Aug 29 21:07:16 volumio sudo[16351]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:07:16 volumio sudo[16351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:16 volumio sudo[16351]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 29 21:07:16 volumio kernel: usb 1-1.2: USB disconnect, device number 40 Aug 29 21:07:16 volumio volumio[1258]: info: Aug 29 21:07:16 volumio volumio[1258]: ---------------------------- USB Audio Device Detached Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:16 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 29 21:07:17 volumio volumio[1258]: info: Volumio Network Manager: Network status updated: 0 Aug 29 21:07:17 volumio kernel: usb 1-1.2: new high-speed USB device number 41 using xhci_hcd Aug 29 21:07:17 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Aug 29 21:07:17 volumio mpd[16368]: 2026-08-29T21:07:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:07:17 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 21:07:17 volumio sudo[16327]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:17 volumio volumio[1258]: error: updateQueue error: null Aug 29 21:07:18 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Aug 29 21:07:18 volumio kernel: usb 1-1.2: new high-speed USB device number 42 using xhci_hcd Aug 29 21:07:19 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.07 Aug 29 21:07:19 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Aug 29 21:07:19 volumio kernel: usb 1-1.2: Product: E70 Velvet Aug 29 21:07:19 volumio kernel: usb 1-1.2: Manufacturer: Topping Aug 29 21:07:19 volumio (udev-worker)[16292]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Aug 29 21:07:19 volumio volumio[1258]: info: Aug 29 21:07:19 volumio volumio[1258]: ---------------------------- USB Audio Device Attached Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::Close All Modals sent Aug 29 21:07:19 volumio volumio[1258]: info: Preparing to save Alsa Options, stopping services first Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:19 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::volumioPause Aug 29 21:07:19 volumio volumio[1258]: info: CoreStateMachine::pause Aug 29 21:07:19 volumio volumio[1258]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E70 Velvet","alsacard":"Velvet"},"i2s":false} Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:19 volumio volumio[1258]: info: Setting mixer E70 Velvet for card E70 Velvet Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 21:07:19 volumio volumio[1258]: info: Updating Volume Controller Parameters: Device: 5 Name: E70 Velvet Mixer: E70 Velvet Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 21:07:19 volumio volumio[1258]: info: Disabling external Volume Control Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:07:19 volumio volumio[1258]: info: Preparing to generate the ALSA configuration file Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 21:07:19 volumio volumio[1258]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:07:19 volumio volumio[1258]: info: Output device has changed, restarting MPD Aug 29 21:07:19 volumio volumio[1258]: info: Output device has changed, restarting Shairport Sync Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:19 volumio sudo[16417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:07:19 volumio sudo[16417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:19 volumio sudo[16417]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:19 volumio sudo[16419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:07:19 volumio sudo[16419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:19 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:07:19 volumio volumio[1258]: info: Output device has changed, restarting Shairport Sync Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:07:19 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:19 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:19 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:19 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 21:07:19 volumio volumio[1258]: info: MPD Permissions set Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:19 volumio volumio[1258]: info: Starting Shairport Sync Aug 29 21:07:19 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 29 21:07:19 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 21:07:19 volumio systemd[1]: mpd.service: Consumed 1.678s CPU time. Aug 29 21:07:19 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 29 21:07:19 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 21:07:19 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 21:07:19 volumio volumio[1258]: info: Starting Shairport Sync Aug 29 21:07:19 volumio sudo[16436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:07:19 volumio sudo[16436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:19 volumio sudo[16439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:07:19 volumio sudo[16439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:19 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 21:07:19 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 21:07:19 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 21:07:19 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 21:07:19 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 21:07:19 volumio systemd[1]: shairport-sync.service: Consumed 1.558s CPU time. Aug 29 21:07:19 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 21:07:19 volumio sudo[16436]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:19 volumio sudo[16439]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:19 volumio volumio[1258]: info: Shairport-Sync Started Aug 29 21:07:19 volumio volumio[1258]: info: Shairport-Sync Started Aug 29 21:07:19 volumio sudo[16442]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:07:19 volumio sudo[16442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:19 volumio sudo[16442]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 29 21:07:19 volumio volumio[1258]: info: CoreStateMachine::getcurrentVolume Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:19 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:19 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:19 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:19 volumio volumio[1258]: info: CoreStateMachine::updateTrackBlock Aug 29 21:07:19 volumio volumio[1258]: info: CorePlayQueue::getTrackBlock Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:19 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:19 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:19 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:19 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:21 volumio mpd[16459]: 2026-08-29T21:07:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:07:21 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 21:07:21 volumio sudo[16419]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:21 volumio volumio[1258]: error: updateQueue error: null Aug 29 21:07:21 volumio kernel: usb 1-1.2: USB disconnect, device number 42 Aug 29 21:07:21 volumio volumio[1258]: info: Aug 29 21:07:21 volumio volumio[1258]: ---------------------------- USB Audio Device Detached Aug 29 21:07:21 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Aug 29 21:07:21 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:21 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 29 21:07:22 volumio kernel: usb 1-1.2: new high-speed USB device number 43 using xhci_hcd Aug 29 21:07:22 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.07 Aug 29 21:07:22 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Aug 29 21:07:22 volumio kernel: usb 1-1.2: Product: E70 Velvet Aug 29 21:07:22 volumio kernel: usb 1-1.2: Manufacturer: Topping Aug 29 21:07:22 volumio (udev-worker)[16292]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Aug 29 21:07:22 volumio volumio[1258]: info: Aug 29 21:07:22 volumio volumio[1258]: ---------------------------- USB Audio Device Attached Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::Close All Modals sent Aug 29 21:07:22 volumio volumio[1258]: info: Preparing to save Alsa Options, stopping services first Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:22 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::volumioPause Aug 29 21:07:22 volumio volumio[1258]: info: CoreStateMachine::pause Aug 29 21:07:22 volumio volumio[1258]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E70 Velvet","alsacard":"Velvet"},"i2s":false} Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:22 volumio volumio[1258]: info: Setting mixer E70 Velvet for card E70 Velvet Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 29 21:07:22 volumio volumio[1258]: info: Updating Volume Controller Parameters: Device: 5 Name: E70 Velvet Mixer: E70 Velvet Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 29 21:07:22 volumio volumio[1258]: info: Disabling external Volume Control Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:07:22 volumio volumio[1258]: info: Preparing to generate the ALSA configuration file Aug 29 21:07:22 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:22 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:22 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 21:07:22 volumio volumio[1258]: info: Asound.conf file unchanged, so no further update is needed Aug 29 21:07:22 volumio volumio[1258]: info: Output device has changed, restarting MPD Aug 29 21:07:22 volumio volumio[1258]: info: Output device has changed, restarting Shairport Sync Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:22 volumio sudo[16498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 21:07:22 volumio sudo[16498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:22 volumio sudo[16498]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:22 volumio sudo[16500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 21:07:22 volumio sudo[16500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:22 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:07:22 volumio volumio[1258]: info: Output device has changed, restarting Shairport Sync Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:22 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 21:07:22 volumio volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 21:07:22 volumio volumio[1258]: info: MPD Permissions set Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 21:07:22 volumio volumio[1258]: info: Starting Shairport Sync Aug 29 21:07:22 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 29 21:07:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 21:07:22 volumio systemd[1]: mpd.service: Consumed 1.563s CPU time. Aug 29 21:07:22 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 29 21:07:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 21:07:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 21:07:22 volumio volumio[1258]: info: Starting Shairport Sync Aug 29 21:07:22 volumio sudo[16518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:07:22 volumio sudo[16518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:22 volumio sudo[16520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 21:07:22 volumio sudo[16520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 21:07:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 21:07:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 21:07:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 21:07:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 21:07:22 volumio systemd[1]: shairport-sync.service: Consumed 1.563s CPU time. Aug 29 21:07:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 21:07:22 volumio sudo[16518]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:22 volumio sudo[16520]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:23 volumio volumio[1258]: info: Shairport-Sync Started Aug 29 21:07:23 volumio volumio[1258]: info: Shairport-Sync Started Aug 29 21:07:23 volumio sudo[16523]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 21:07:23 volumio sudo[16523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:23 volumio sudo[16523]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:23 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 29 21:07:23 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 29 21:07:23 volumio volumio[1258]: info: CoreStateMachine::getcurrentVolume Aug 29 21:07:23 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:23 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:23 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:23 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:23 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:23 volumio volumio[1258]: info: CoreStateMachine::updateTrackBlock Aug 29 21:07:23 volumio volumio[1258]: info: CorePlayQueue::getTrackBlock Aug 29 21:07:23 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:23 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:23 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:23 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:23 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:23 volumio ifplugd(eth0)[1033]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Aug 29 21:07:23 volumio dhcpcd[16555]: dhcpcd not running Aug 29 21:07:23 volumio ifplugd(eth0)[1033]: client: dhcpcd not running Aug 29 21:07:23 volumio ifplugd(eth0)[1033]: Program executed successfully. Aug 29 21:07:23 volumio kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Aug 29 21:07:23 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 29 21:07:24 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:24 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:24 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:24 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:24 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:24 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:24 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:24 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:07:24 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:07:24 volumio mpd[16540]: 2026-08-29T21:07:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 21:07:24 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 21:07:24 volumio sudo[16500]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:24 volumio volumio[1258]: error: updateQueue error: null Aug 29 21:07:24 volumio wireless.js[15567]: WIRELESS.JS - INFO: === SNM TRANSITION === Aug 29 21:07:24 volumio wireless.js[15567]: WIRELESS.JS - INFO: Previous ethernet state: connected Aug 29 21:07:24 volumio wireless.js[15567]: WIRELESS.JS - INFO: New ethernet state: disconnected Aug 29 21:07:24 volumio wireless.js[15567]: WIRELESS.JS - INFO: Single Network Mode: enabled Aug 29 21:07:24 volumio wireless.js[15567]: WIRELESS.JS - INFO: First start: no Aug 29 21:07:24 volumio wireless.js[15567]: WIRELESS.JS - INFO: Action: Reconnect WiFi Aug 29 21:07:24 volumio wireless.js[15567]: WIRELESS.JS - INFO: === END TRANSITION === Aug 29 21:07:24 volumio sudo[16575]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 21:07:24 volumio sudo[16575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:24 volumio sudo[16575]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:24 volumio wireless.js[15567]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Aug 29 21:07:24 volumio wireless.js[15567]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Aug 29 21:07:25 volumio wireless.js[15567]: WIRELESS.JS - INFO: reconnectWiFi: WiFi reconnection triggered Aug 29 21:07:25 volumio volumio5-onboarding[1566]: time=2026-08-29T21:07:25.298+03:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 21:07:26 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 29 21:07:26 volumio volumio[1258]: info: CoreStateMachine::getcurrentVolume Aug 29 21:07:26 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:26 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:26 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:26 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:26 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:26 volumio volumio[1258]: info: CoreStateMachine::updateTrackBlock Aug 29 21:07:26 volumio volumio[1258]: info: CorePlayQueue::getTrackBlock Aug 29 21:07:26 volumio volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 21:07:26 volumio volumio[1258]: info: VolumeController:: Volume=93 Mute =false Aug 29 21:07:26 volumio volumio[1258]: info: CoreStateMachine::pushState Aug 29 21:07:26 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:26 volumio volumio[1258]: info: CoreCommandRouter::volumioPushState Aug 29 21:07:27 volumio wpa_supplicant[15643]: wlan0: Trying to associate with 08:f4:58:a6:99:dc (SSID='HUAWEI-2.4G' freq=5180 MHz) Aug 29 21:07:28 volumio wireless.js[15567]: WIRELESS.JS - INFO: reconnectWiFi: WiFi reconnect incomplete (wpa_state=ASSOCIATING), reinitializing wireless flow Aug 29 21:07:28 volumio wireless.js[15567]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Aug 29 21:07:28 volumio wpa_supplicant[15643]: wlan0: Associated with 08:f4:58:a6:99:dc Aug 29 21:07:28 volumio wpa_supplicant[15643]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 29 21:07:28 volumio wpa_supplicant[15643]: wlan0: WPA: Key negotiation completed with 08:f4:58:a6:99:dc [PTK=CCMP GTK=CCMP] Aug 29 21:07:28 volumio wpa_supplicant[15643]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:f4:58:a6:99:dc completed [id=0 id_str=] Aug 29 21:07:28 volumio dhcpcd[880]: wlan0: carrier acquired Aug 29 21:07:28 volumio dhcpcd[880]: wlan0: connected to Access Point: HUAWEI-2.4G Aug 29 21:07:28 volumio dhcpcd[880]: wlan0: IAID 67:a5:8a:90 Aug 29 21:07:28 volumio wpa_supplicant[15643]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Aug 29 21:07:28 volumio wpa_supplicant[15643]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Aug 29 21:07:28 volumio wpa_supplicant[15643]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Aug 29 21:07:28 volumio sudo[16612]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 29 21:07:28 volumio sudo[16612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:28 volumio wpa_supplicant[15643]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Aug 29 21:07:28 volumio wpa_supplicant[15643]: wlan0: CTRL-EVENT-DISCONNECTED bssid=08:f4:58:a6:99:dc reason=3 locally_generated=1 Aug 29 21:07:28 volumio sudo[16612]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:28 volumio dhcpcd[880]: wlan0: carrier lost - roaming Aug 29 21:07:28 volumio wpa_supplicant[15643]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Aug 29 21:07:28 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Aug 29 21:07:28 volumio sudo[16616]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 29 21:07:28 volumio sudo[16616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:28 volumio dhcpcd[880]: wlan0: carrier lost Aug 29 21:07:28 volumio sudo[16616]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:28 volumio wpa_supplicant[15643]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Aug 29 21:07:28 volumio wpa_supplicant[15643]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Aug 29 21:07:28 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:28 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:28 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:28 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:28 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:28 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:28 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:28 volumio wireless.js[15567]: WIRELESS.JS - INFO: Cleaning previous... Aug 29 21:07:28 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:07:28 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:07:28 volumio sudo[16630]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 29 21:07:28 volumio sudo[16630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:28 volumio dhcpcd[880]: wlan0: carrier acquired Aug 29 21:07:28 volumio wpa_supplicant[15643]: wlan0: CTRL-EVENT-TERMINATING Aug 29 21:07:28 volumio sudo[16630]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:28 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 29 21:07:28 volumio dhcpcd[880]: wlan0: IAID 67:a5:8a:90 Aug 29 21:07:28 volumio dhcpcd[880]: wlan0: carrier lost Aug 29 21:07:28 volumio wireless.js[15567]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Aug 29 21:07:28 volumio wireless.js[15567]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 6ms Aug 29 21:07:28 volumio wireless.js[15567]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 2c:cf:67:a5:8a:90) Aug 29 21:07:28 volumio sudo[16645]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 29 21:07:28 volumio sudo[16645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:28 volumio sudo[16645]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:29 volumio sudo[16655]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 29 21:07:29 volumio sudo[16655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:29 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:29 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:29 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:29 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:29 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:29 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:29 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:29 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:07:29 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:07:29 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:29 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:30 volumio volumio5-onboarding[1566]: time=2026-08-29T21:07:30.635+03:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 21:07:31 volumio sudo[16655]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:31 volumio wireless.js[15567]: WIRELESS.JS - INFO: Regdomain already correct: RU Aug 29 21:07:31 volumio wireless.js[15567]: WIRELESS.JS - INFO: Start wireless flow Aug 29 21:07:31 volumio wireless.js[15567]: WIRELESS.JS - INFO: Stopped hotspot (if there).. Aug 29 21:07:31 volumio sudo[16663]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 29 21:07:31 volumio sudo[16663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:31 volumio sudo[16663]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:31 volumio sudo[16665]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 29 21:07:31 volumio sudo[16665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:32 volumio sudo[16665]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:32 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:32 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:32 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:07:32 volumio wireless.js[15567]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Aug 29 21:07:32 volumio wireless.js[15567]: WIRELESS.JS - INFO: STAGE 1: wlan0 validated and ready (MAC: 2c:cf:67:a5:8a:90, USB: false) Aug 29 21:07:32 volumio wpa_supplicant[16671]: Successfully initialized wpa_supplicant Aug 29 21:07:32 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 29 21:07:32 volumio wpa_supplicant[16671]: nl80211: kernel reports: Registration to specific type not supported Aug 29 21:07:32 volumio sudo[16677]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 29 21:07:32 volumio sudo[16677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:32 volumio sudo[16677]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:32 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:32 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:32 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:07:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:07:33 volumio wireless.js[15567]: WIRELESS.JS - INFO: DHCP IP fallback Aug 29 21:07:33 volumio wireless.js[15567]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor Aug 29 21:07:33 volumio wireless.js[15567]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0 Aug 29 21:07:34 volumio wireless.js[15567]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms) Aug 29 21:07:34 volumio volumio5-onboarding[1566]: time=2026-08-29T21:07:34.240+03:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 21:07:35 volumio wpa_supplicant[16674]: wlan0: Trying to associate with 08:f4:58:a6:99:dc (SSID='HUAWEI-2.4G' freq=5180 MHz) Aug 29 21:07:35 volumio wpa_supplicant[16674]: wlan0: Associated with 08:f4:58:a6:99:dc Aug 29 21:07:35 volumio wpa_supplicant[16674]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 29 21:07:35 volumio wpa_supplicant[16674]: wlan0: WPA: Key negotiation completed with 08:f4:58:a6:99:dc [PTK=CCMP GTK=CCMP] Aug 29 21:07:35 volumio wpa_supplicant[16674]: wlan0: CTRL-EVENT-CONNECTED - Connection to 08:f4:58:a6:99:dc completed [id=0 id_str=] Aug 29 21:07:35 volumio dhcpcd[880]: wlan0: carrier acquired Aug 29 21:07:35 volumio dhcpcd[880]: wlan0: IAID 67:a5:8a:90 Aug 29 21:07:35 volumio wireless.js[15567]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> COMPLETED (duration: 1522ms) Aug 29 21:07:35 volumio wireless.js[15567]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful Aug 29 21:07:35 volumio wireless.js[15567]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to 08:f4:58:a6:99:dc Aug 29 21:07:35 volumio wireless.js[15567]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow Aug 29 21:07:35 volumio dhcpcd[880]: wlan0: soliciting a DHCP lease Aug 29 21:07:35 volumio dhcpcd[880]: wlan0: offered 192.168.3.23 from 192.168.3.1 Aug 29 21:07:35 volumio dhcpcd[880]: wlan0: probing address 192.168.3.23/24 Aug 29 21:07:36 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:36 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:36 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:36 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:36 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:36 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:36 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:36 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:07:36 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:07:36 volumio dhcpcd[880]: wlan0: soliciting an IPv6 router Aug 29 21:07:36 volumio sudo[16704]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0 Aug 29 21:07:36 volumio sudo[16704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:36 volumio dhcpcd[880]: ps_ctl_dispatch: cannot handle another client Aug 29 21:07:36 volumio sudo[16704]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:36 volumio dhcpcd[880]: control_free: No such file or directory Aug 29 21:07:36 volumio volumio5-onboarding[1566]: time=2026-08-29T21:07:36.911+03:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 21:07:38 volumio wireless.js[15567]: WIRELESS.JS - INFO: Start ap Aug 29 21:07:38 volumio wireless.js[15567]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 29 21:07:39 volumio wireless.js[15567]: WIRELESS.JS - INFO: trying... Aug 29 21:07:39 volumio sudo[16733]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 21:07:39 volumio sudo[16733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:39 volumio sudo[16733]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:39 volumio sudo[16736]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:39 volumio sudo[16736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:39 volumio sudo[16736]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:39 volumio wireless.js[15567]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 29 21:07:40 volumio wireless.js[15567]: WIRELESS.JS - INFO: trying... Aug 29 21:07:40 volumio sudo[16747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 21:07:40 volumio sudo[16747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:40 volumio sudo[16747]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:40 volumio sudo[16750]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:40 volumio sudo[16750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:40 volumio sudo[16750]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:40 volumio wireless.js[15567]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined Aug 29 21:07:40 volumio dhcpcd[880]: wlan0: leased 192.168.3.23 for 86400 seconds Aug 29 21:07:40 volumio avahi-daemon[1372]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.23. Aug 29 21:07:40 volumio avahi-daemon[1372]: New relevant interface wlan0.IPv4 for mDNS. Aug 29 21:07:40 volumio avahi-daemon[1372]: Registering new address record for 192.168.3.23 on wlan0.IPv4. Aug 29 21:07:40 volumio dhcpcd[880]: wlan0: adding route to 192.168.3.0/24 Aug 29 21:07:40 volumio dhcpcd[880]: wlan0: adding default route via 192.168.3.1 Aug 29 21:07:40 volumio dhcpcd[880]: wlan0: dhcp_envoption 213: Operation not supported Aug 29 21:07:41 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 29 21:07:41 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Aug 29 21:07:41 volumio systemd[1]: welcome.service: Deactivated successfully. Aug 29 21:07:41 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 29 21:07:41 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Aug 29 21:07:41 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Aug 29 21:07:41 volumio welcome[16770]: Resolved ip:[1] 192.168.3.23 Aug 29 21:07:41 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Aug 29 21:07:41 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Aug 29 21:07:41 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:41 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:07:41 volumio wireless.js[15567]: WIRELESS.JS - INFO: trying... Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: adding b5af038c-c3dc-4251-84d8-0bc21bbecf53 Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Found device Volumio Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:41 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: this is already registered, b5af038c-c3dc-4251-84d8-0bc21bbecf53 Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Found device Volumio Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:41 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:41 volumio sudo[16794]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 29 21:07:41 volumio sudo[16794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:07:41 volumio sudo[16794]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:41 volumio sudo[16797]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:07:41 volumio sudo[16797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:41 volumio sudo[16797]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: this is already registered, b5af038c-c3dc-4251-84d8-0bc21bbecf53 Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Found device Volumio Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:41 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: this is already registered, b5af038c-c3dc-4251-84d8-0bc21bbecf53 Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Found device Volumio Aug 29 21:07:41 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:41 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:41 volumio wireless.js[15567]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.3.23, ipV6 is undefined Aug 29 21:07:41 volumio wireless.js[15567]: WIRELESS.JS - INFO: Connected to SSID: HUAWEI-2.4G Aug 29 21:07:41 volumio wireless.js[15567]: WIRELESS.JS - INFO: It's done! AP Aug 29 21:07:41 volumio wireless.js[15567]: WIRELESS.JS - INFO: Restarting avahi-daemon... Aug 29 21:07:41 volumio sudo[16802]: root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon Aug 29 21:07:41 volumio sudo[16802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 21:07:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 21:07:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 21:07:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 21:07:41 volumio systemd[1]: shairport-sync.service: Consumed 1.627s CPU time. Aug 29 21:07:41 volumio avahi-daemon[1372]: Got SIGTERM, quitting. Aug 29 21:07:41 volumio systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 29 21:07:41 volumio avahi-daemon[1372]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.23. Aug 29 21:07:41 volumio avahi-daemon[1372]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Browse raised the following error Error: dns service error: unknown Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Restarting Browsing Aug 29 21:07:41 volumio volumio[1258]: error: Discovery: Advertisement error: Error: dns service error: unknown Aug 29 21:07:41 volumio volumio[1258]: error: Discovery: advertisement error: Error: dns service error: unknown Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Stopping Advertising Immediately Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Stopping existing advertisement Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Browse raised the following error Error: dns service error: unknown Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Restarting Browsing Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Restart already pending, ignoring duplicate call Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Browse raised the following error Error: dns service error: unknown Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Restarting Browsing Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Restart already pending, ignoring duplicate call Aug 29 21:07:41 volumio volumio5-onboarding[1566]: time=2026-08-29T21:07:41.965+03:00 level=WARN msg="disconnected from Avahi daemon, trying to reconnect" component=discovery/localnet error="avahi: Daemon connection failed" Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Browse raised the following error Error: dns service error: unknown Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Restarting Browsing Aug 29 21:07:41 volumio volumio[1258]: info: Discovery: Restart already pending, ignoring duplicate call Aug 29 21:07:41 volumio dbus-daemon[768]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.156' (uid=0 pid=1386 comm="/usr/sbin/smbd --foreground --no-process-group") Aug 29 21:07:42 volumio avahi-daemon[1372]: avahi-daemon 0.8 exiting. Aug 29 21:07:42 volumio systemd[1]: avahi-daemon.service: Deactivated successfully. Aug 29 21:07:42 volumio systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 29 21:07:42 volumio systemd[1]: avahi-daemon.service: Consumed 12min 9.271s CPU time. Aug 29 21:07:42 volumio systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 29 21:07:42 volumio avahi-daemon[16804]: Process 1372 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Aug 29 21:07:42 volumio avahi-daemon[16804]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Aug 29 21:07:42 volumio avahi-daemon[16804]: Successfully dropped root privileges. Aug 29 21:07:42 volumio avahi-daemon[16804]: avahi-daemon 0.8 starting up. Aug 29 21:07:42 volumio dbus-daemon[768]: [system] Successfully activated service 'org.freedesktop.Avahi' Aug 29 21:07:42 volumio systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 29 21:07:42 volumio avahi-daemon[16804]: Successfully called chroot(). Aug 29 21:07:42 volumio avahi-daemon[16804]: Successfully dropped remaining capabilities. Aug 29 21:07:42 volumio avahi-daemon[16804]: No service file found in /etc/avahi/services. Aug 29 21:07:42 volumio avahi-daemon[16804]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.23. Aug 29 21:07:42 volumio avahi-daemon[16804]: New relevant interface wlan0.IPv4 for mDNS. Aug 29 21:07:42 volumio avahi-daemon[16804]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 29 21:07:42 volumio avahi-daemon[16804]: New relevant interface lo.IPv4 for mDNS. Aug 29 21:07:42 volumio avahi-daemon[16804]: Network interface enumeration completed. Aug 29 21:07:42 volumio avahi-daemon[16804]: Registering new address record for 192.168.3.23 on wlan0.IPv4. Aug 29 21:07:42 volumio avahi-daemon[16804]: Registering new address record for 127.0.0.1 on lo.IPv4. Aug 29 21:07:42 volumio sudo[16802]: pam_unix(sudo:session): session closed for user root Aug 29 21:07:42 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 21:07:42 volumio wireless.js[15567]: WIRELESS.JS - INFO: Notified systemd about wireless ready Aug 29 21:07:42 volumio ntpd[1002]: IO: Listen normally on 18 wlan0 192.168.3.23:123 Aug 29 21:07:42 volumio ntpd[1002]: IO: new interface(s) found: waking up resolver Aug 29 21:07:42 volumio avahi-daemon[16804]: Server startup complete. Host name is volumio.local. Local service cookie is 1710877530. Aug 29 21:07:43 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 21:07:43 volumio volumio[1258]: info: Received Get System Info Aug 29 21:07:43 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:07:43 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:07:43 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:07:43 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:43 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:43 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:07:43 volumio volumio5-onboarding[1566]: time=2026-08-29T21:07:43.882+03:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 29 21:07:46 volumio volumio[1258]: info: Discovery: Restarting Advertising Aug 29 21:07:47 volumio volumio[1258]: info: Volumio Network Manager: Network status updated: 2 Aug 29 21:07:51 volumio volumio[1258]: info: Discovery: Started advertising with name: Volumio Aug 29 21:07:52 volumio volumio[1258]: info: Discovery: this is already registered, b5af038c-c3dc-4251-84d8-0bc21bbecf53 Aug 29 21:07:52 volumio volumio[1258]: info: Discovery: Found device Volumio Aug 29 21:07:52 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:52 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:07:52 volumio volumio[1258]: info: Discovery: this is already registered, b5af038c-c3dc-4251-84d8-0bc21bbecf53 Aug 29 21:07:52 volumio volumio[1258]: info: Discovery: Found device Volumio Aug 29 21:07:52 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:07:52 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:17 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:08:17 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:08:17 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:08:17 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:08:17 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:17 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:08:17 volumio volumio[1258]: verbose: New Socket.io Connection to 192.168.3.23 from 192.168.3.6 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 8 Aug 29 21:08:17 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:08:17 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:17 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:08:17 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:08:30 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:08:30 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:08:30 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:08:30 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:08:30 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:30 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:08:30 volumio volumio[1258]: verbose: New Socket.io Connection to 192.168.3.23 from 192.168.3.6 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 9 Aug 29 21:08:30 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:08:30 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:30 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 21:08:30 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 21:08:32 volumio sudo[16913]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 21:08:32 volumio sudo[16913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:08:32 volumio sudo[16913]: pam_unix(sudo:session): session closed for user root Aug 29 21:08:32 volumio sudo[16915]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 21:08:32 volumio sudo[16915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:08:32 volumio sudo[16915]: pam_unix(sudo:session): session closed for user root Aug 29 21:08:32 volumio volumio[1258]: verbose: New Socket.io Connection to 192.168.3.23 from 192.168.3.6 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 10 Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:08:32 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 21:08:32 volumio volumio[1258]: info: Received Get System Info Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:08:32 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:08:32 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:08:32 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:32 volumio volumio[1258]: info: Listing playlists Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 21:08:32 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 21:08:34 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 21:08:34 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 21:08:34 volumio volumio[1258]: info: Received Get System Info Aug 29 21:08:34 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:08:34 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:08:34 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:08:34 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:08:34 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:34 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:08:35 volumio volumio[1258]: info: Retrieving Cloud Streaming UI Aug 29 21:08:35 volumio volumio[1258]: info: Getting Tidal Cloud Configuration Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:08:35 volumio volumio[1258]: info: Getting Qobuz Cloud Configuration Aug 29 21:08:35 volumio volumio[1258]: info: Asking plugin for UI Config Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:08:35 volumio volumio[1258]: info: Getting Spotify Cloud Configuration Aug 29 21:08:35 volumio volumio[1258]: info: Asking plugin for UI Config Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:08:35 volumio volumio[1258]: info: Saving Spotify Acccount Aug 29 21:08:35 volumio volumio[1258]: info: Got Tidal Cloud Configuration Aug 29 21:08:35 volumio volumio[1258]: info: Got it Aug 29 21:08:35 volumio volumio[1258]: info: Got it Aug 29 21:08:35 volumio volumio[1258]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 21:08:35 volumio volumio[1258]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::volumioGetBrowseSources Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 21:08:35 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 29 21:08:36 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 21:08:36 volumio volumio[1258]: info: Received Get System Info Aug 29 21:08:36 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 21:08:36 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 21:08:36 volumio volumio[1258]: info: Discovery: Getting this device information Aug 29 21:08:36 volumio volumio[1258]: info: CoreCommandRouter::volumioGetState Aug 29 21:08:36 volumio volumio[1258]: info: CorePlayQueue::getTrack 7 Aug 29 21:08:36 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 21:08:39 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 21:08:42 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 21:08:43 volumio volumio[1258]: info: Disabling MyMusic plugin upnp Aug 29 21:08:43 volumio sudo[16939]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 29 21:08:43 volumio sudo[16939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 21:08:43 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Aug 29 21:08:43 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 29 21:08:44 volumio volumio[1258]: info: Enabling MyMusic plugin upnp Aug 29 21:08:44 volumio volumio[1258]: info: Enabling plugin upnp Aug 29 21:08:44 volumio volumio[1258]: info: Loading plugin "upnp"... Aug 29 21:08:44 volumio volumio[1258]: info: [1788026924716] Starting Upmpd Daemon Aug 29 21:08:44 volumio volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 21:08:44 volumio volumio[1258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:08:44 volumio volumio[1258]: Error: listen EADDRINUSE: address already in use :::6599 Aug 29 21:08:44 volumio volumio[1258]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Aug 29 21:08:44 volumio volumio[1258]: at listenInCluster (node:net:1920:12) Aug 29 21:08:44 volumio volumio[1258]: at Server.listen (node:net:2008:7) Aug 29 21:08:44 volumio volumio[1258]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 29 21:08:44 volumio volumio[1258]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 29 21:08:44 volumio volumio[1258]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 29 21:08:44 volumio volumio[1258]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 29 21:08:44 volumio volumio[1258]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Aug 29 21:08:44 volumio volumio[1258]: code: 'EADDRINUSE', Aug 29 21:08:44 volumio volumio[1258]: errno: -98, Aug 29 21:08:44 volumio volumio[1258]: syscall: 'listen', Aug 29 21:08:44 volumio volumio[1258]: address: '::', Aug 29 21:08:44 volumio volumio[1258]: port: 6599 Aug 29 21:08:44 volumio volumio[1258]: } Aug 29 21:08:44 volumio volumio[1258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 21:08:45 volumio sudo[16956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 21:07' Aug 29 21:08:45 volumio sudo[16956]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"