-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Fri 2026-06-19 17:02:58 CDT. -- Feb 14 04:11:58 volumio-topping50 systemd[1]: Starting Load/Save Random Seed... Jun 19 17:02:40 volumio-topping50 fake-hwclock[333]: Fri Jun 19 22:02:40 UTC 2026 Jun 19 17:02:40 volumio-topping50 systemd[1]: Started Restore / save the current clock. Jun 19 17:02:40 volumio-topping50 systemd[1]: Started Apply Kernel Variables. Jun 19 17:02:40 volumio-topping50 systemd[1]: Started Load/Save Random Seed. Jun 19 17:02:40 volumio-topping50 systemd[1]: Started udev Coldplug all Devices. Jun 19 17:02:40 volumio-topping50 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jun 19 17:02:40 volumio-topping50 systemd[1]: dynamicswap.service: Succeeded. Jun 19 17:02:40 volumio-topping50 systemd[1]: Started Create System Users. Jun 19 17:02:40 volumio-topping50 systemd[1]: Starting Create Static Device Nodes in /dev... Jun 19 17:02:40 volumio-topping50 systemd[1]: Started Create Static Device Nodes in /dev. Jun 19 17:02:40 volumio-topping50 systemd[1]: Starting udev Kernel Device Manager... Jun 19 17:02:40 volumio-topping50 systemd[1]: Reached target Local File Systems (Pre). Jun 19 17:02:40 volumio-topping50 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 19 17:02:40 volumio-topping50 systemd[1]: Mounting /tmp... Jun 19 17:02:40 volumio-topping50 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 19 17:02:40 volumio-topping50 systemd[1]: Mounting /var/log... Jun 19 17:02:40 volumio-topping50 systemd[1]: Mounting /var/spool/cups... Jun 19 17:02:40 volumio-topping50 systemd[1]: Mounted /tmp. Jun 19 17:02:40 volumio-topping50 systemd[1]: Mounted /var/log. Jun 19 17:02:40 volumio-topping50 systemd[1]: Mounted /var/spool/cups. Jun 19 17:02:40 volumio-topping50 systemd[1]: Mounting /var/spool/cups/tmp... Jun 19 17:02:40 volumio-topping50 systemd[1]: Starting Flush Journal to Persistent Storage... Jun 19 17:02:40 volumio-topping50 systemd[1]: Mounted /var/spool/cups/tmp. Jun 19 17:02:40 volumio-topping50 systemd-udevd[360]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jun 19 17:02:40 volumio-topping50 systemd-journald[327]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. Jun 19 17:02:40 volumio-topping50 systemd[1]: Started udev Kernel Device Manager. Jun 19 17:02:40 volumio-topping50 systemd[1]: Starting Show Plymouth Boot Screen... Jun 19 17:02:40 volumio-topping50 systemd[1]: Started Flush Journal to Persistent Storage. Jun 19 17:02:40 volumio-topping50 systemd[1]: plymouth-start.service: Succeeded. Jun 19 17:02:40 volumio-topping50 systemd[1]: Started Show Plymouth Boot Screen. Jun 19 17:02:40 volumio-topping50 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jun 19 17:02:40 volumio-topping50 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 19 17:02:40 volumio-topping50 systemd[1]: Reached target Local Encrypted Volumes. Jun 19 17:02:40 volumio-topping50 systemd[1]: Reached target Paths. Jun 19 17:02:40 volumio-topping50 systemd-udevd[391]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 19 17:02:40 volumio-topping50 systemd-udevd[367]: Using default interface naming scheme 'v240'. Jun 19 17:02:40 volumio-topping50 systemd-udevd[367]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 19 17:02:40 volumio-topping50 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jun 19 17:02:40 volumio-topping50 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jun 19 17:02:40 volumio-topping50 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jun 19 17:02:40 volumio-topping50 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jun 19 17:02:40 volumio-topping50 kernel: [vc_sm_connected_init]: start Jun 19 17:02:40 volumio-topping50 kernel: [vc_sm_connected_init]: installed successfully Jun 19 17:02:40 volumio-topping50 kernel: mc: Linux media interface: v0.10 Jun 19 17:02:40 volumio-topping50 kernel: videodev: Linux video capture interface: v2.00 Jun 19 17:02:40 volumio-topping50 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jun 19 17:02:40 volumio-topping50 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jun 19 17:02:40 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jun 19 17:02:40 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jun 19 17:02:40 volumio-topping50 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jun 19 17:02:40 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jun 19 17:02:40 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jun 19 17:02:40 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 19 17:02:40 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 19 17:02:40 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 19 17:02:40 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 19 17:02:41 volumio-topping50 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jun 19 17:02:41 volumio-topping50 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jun 19 17:02:41 volumio-topping50 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jun 19 17:02:41 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jun 19 17:02:41 volumio-topping50 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 19 17:02:41 volumio-topping50 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jun 19 17:02:41 volumio-topping50 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jun 19 17:02:41 volumio-topping50 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 19 17:02:41 volumio-topping50 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jun 19 17:02:41 volumio-topping50 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jun 19 17:02:41 volumio-topping50 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 19 17:02:41 volumio-topping50 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 19 17:02:41 volumio-topping50 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: Core ver 2.22 Jun 19 17:02:41 volumio-topping50 kernel: NET: Registered PF_BLUETOOTH protocol family Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: HCI device and connection manager initialized Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: HCI socket layer initialized Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: L2CAP socket layer initialized Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: SCO socket layer initialized Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: HCI UART driver ver 2.3 Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: HCI UART protocol H4 registered Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jun 19 17:02:41 volumio-topping50 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jun 19 17:02:41 volumio-topping50 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: HCI UART protocol Broadcom registered Jun 19 17:02:41 volumio-topping50 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jun 19 17:02:41 volumio-topping50 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jun 19 17:02:41 volumio-topping50 kernel: usbcore: registered new interface driver brcmfmac Jun 19 17:02:41 volumio-topping50 kernel: uart-pl011 fe201000.serial: no DMA platform data Jun 19 17:02:41 volumio-topping50 kernel: alsactl[507]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jun 19 17:02:41 volumio-topping50 systemd-udevd[398]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 17. Jun 19 17:02:41 volumio-topping50 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jun 19 17:02:41 volumio-topping50 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Jun 19 17:02:41 volumio-topping50 kernel: usb 1-1.4: 1:3 : unsupported format bits 0x100000000 Jun 19 17:02:41 volumio-topping50 kernel: usbcore: registered new interface driver snd-usb-audio Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: hci0: BCM: chip id 107 Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: hci0: BCM: features 0x2f Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: hci0: BCM4345C0 Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jun 19 17:02:41 volumio-topping50 systemd-udevd[368]: Using default interface naming scheme 'v240'. Jun 19 17:02:41 volumio-topping50 systemd-udevd[368]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 19 17:02:41 volumio-topping50 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jun 19 17:02:41 volumio-topping50 systemd[1]: Found device /dev/disk/by-uuid/7F72-C251. Jun 19 17:02:41 volumio-topping50 systemd-udevd[366]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jun 19 17:02:41 volumio-topping50 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Jun 19 17:02:41 volumio-topping50 systemd[1]: Created slice system-bthelper.slice. Jun 19 17:02:41 volumio-topping50 systemd[1]: Starting Show Plymouth Boot Screen... Jun 19 17:02:41 volumio-topping50 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jun 19 17:02:41 volumio-topping50 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jun 19 17:02:41 volumio-topping50 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jun 19 17:02:41 volumio-topping50 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 19 17:02:41 volumio-topping50 systemd[1]: Starting File System Check on /dev/disk/by-uuid/7F72-C251... Jun 19 17:02:41 volumio-topping50 systemd[1]: Started Helper to synchronize boot up for ifupdown. Jun 19 17:02:42 volumio-topping50 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jun 19 17:02:42 volumio-topping50 systemd[1]: plymouth-start.service: Succeeded. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Show Plymouth Boot Screen. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started File System Check Daemon to report status. Jun 19 17:02:42 volumio-topping50 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Load/Save RF Kill Switch Status... Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Load/Save RF Kill Switch Status. Jun 19 17:02:42 volumio-topping50 systemd-fsck[531]: fsck.fat 4.1 (2017-01-24) Jun 19 17:02:42 volumio-topping50 systemd-fsck[531]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jun 19 17:02:42 volumio-topping50 systemd-fsck[531]: Automatically removing dirty bit. Jun 19 17:02:42 volumio-topping50 systemd-fsck[531]: Performing changes. Jun 19 17:02:42 volumio-topping50 systemd-fsck[531]: /dev/mmcblk0p1: 412 files, 41776/46774 clusters Jun 19 17:02:42 volumio-topping50 systemd[1]: Started File System Check on /dev/disk/by-uuid/7F72-C251. Jun 19 17:02:42 volumio-topping50 systemd[1]: Mounting /boot... Jun 19 17:02:42 volumio-topping50 systemd[1]: Mounted /boot. Jun 19 17:02:42 volumio-topping50 systemd[1]: Reached target Local File Systems. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jun 19 17:02:42 volumio-topping50 systemd[1]: Started ifup for wlan0. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Create Volatile Files and Directories... Jun 19 17:02:42 volumio-topping50 systemd[1]: Started ifup for eth0. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Preprocess NFS configuration... Jun 19 17:02:42 volumio-topping50 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Raise network interfaces... Jun 19 17:02:42 volumio-topping50 systemd[1]: plymouth-read-write.service: Succeeded. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jun 19 17:02:42 volumio-topping50 systemd[1]: Received SIGRTMIN+20 from PID 221 (plymouthd). Jun 19 17:02:42 volumio-topping50 systemd[1]: nfs-config.service: Succeeded. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Preprocess NFS configuration. Jun 19 17:02:42 volumio-topping50 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jun 19 17:02:42 volumio-topping50 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jun 19 17:02:42 volumio-topping50 systemd[1]: Reached target NFS client services. Jun 19 17:02:42 volumio-topping50 kernel: Bluetooth: hci0: BCM: features 0x2f Jun 19 17:02:42 volumio-topping50 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jun 19 17:02:42 volumio-topping50 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jun 19 17:02:42 volumio-topping50 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Create Volatile Files and Directories. Jun 19 17:02:42 volumio-topping50 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting RPC bind portmap service... Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Update UTMP about System Boot/Shutdown. Jun 19 17:02:42 volumio-topping50 systemd[1]: Reached target System Initialization. Jun 19 17:02:42 volumio-topping50 systemd[1]: apt-daily.timer: Not using persistent file timestamp Sat 2026-10-17 03:39:17 CDT as it is in the future. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Daily apt download activities. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Daily Cleanup of Temporary Directories. Jun 19 17:02:42 volumio-topping50 systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Fri 2026-10-16 06:08:48 CDT as it is in the future. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Daily apt upgrade and clean activities. Jun 19 17:02:42 volumio-topping50 systemd[1]: Reached target Timers. Jun 19 17:02:42 volumio-topping50 systemd[1]: Listening on triggerhappy.socket. Jun 19 17:02:42 volumio-topping50 systemd[1]: Listening on D-Bus System Message Bus Socket. Jun 19 17:02:42 volumio-topping50 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jun 19 17:02:42 volumio-topping50 systemd[1]: Reached target Sockets. Jun 19 17:02:42 volumio-topping50 systemd[1]: Reached target Basic System. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Volumio Time Update Utility... Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Volumio Log Rotation Service. Jun 19 17:02:42 volumio-topping50 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started D-Bus System Message Bus. Jun 19 17:02:42 volumio-topping50 volumio-time-update[611]: volumio-time-update-util: Fetching time from Volumio... Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Login Service... Jun 19 17:02:42 volumio-topping50 kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 19 17:02:42 volumio-topping50 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Volumio Iptables Module. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting triggerhappy global hotkey daemon... Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jun 19 17:02:42 volumio-topping50 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jun 19 17:02:42 volumio-topping50 systemd[1]: Started volumio-remote-updater.service. Jun 19 17:02:42 volumio-topping50 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Wireless Services... Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting dhcpcd on all interfaces... Jun 19 17:02:42 volumio-topping50 systemd[1]: Started Manage Sound Card State (restore and store). Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting Save/Restore Sound Card State... Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting WPA supplicant... Jun 19 17:02:42 volumio-topping50 systemd[1]: Started UPnP Renderer front-end to MPD. Jun 19 17:02:42 volumio-topping50 alsactl[639]: alsactl 1.1.8 daemon started Jun 19 17:02:42 volumio-topping50 systemd[1]: Started RPC bind portmap service. Jun 19 17:02:42 volumio-topping50 systemd[1]: Reached target RPC Port Mapper. Jun 19 17:02:42 volumio-topping50 systemd[1]: Reached target Remote File Systems (Pre). Jun 19 17:02:42 volumio-topping50 systemd[1]: Reached target Remote File Systems. Jun 19 17:02:42 volumio-topping50 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jun 19 17:02:42 volumio-topping50 thd[627]: Unable to parse trigger line: Jun 19 17:02:42 volumio-topping50 thd[627]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 19 17:02:42 volumio-topping50 thd[627]: Unable to parse trigger line: Jun 19 17:02:42 volumio-topping50 thd[627]: Found socket passed from systemd Jun 19 17:02:42 volumio-topping50 dhcpcd[638]: Not running dhcpcd because /etc/network/interfaces Jun 19 17:02:42 volumio-topping50 dhcpcd[638]: defines some interfaces that will use a Jun 19 17:02:42 volumio-topping50 dhcpcd[638]: DHCP client or static address Jun 19 17:02:42 volumio-topping50 systemd-logind[619]: New seat seat0. Jun 19 17:02:42 volumio-topping50 avahi-daemon[635]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jun 19 17:02:42 volumio-topping50 avahi-daemon[635]: Successfully dropped root privileges. Jun 19 17:02:42 volumio-topping50 avahi-daemon[635]: avahi-daemon 0.7 starting up. Jun 19 17:02:42 volumio-topping50 volumio-remote-updater[633]: Error: No active session Jun 19 17:02:42 volumio-topping50 volumio-remote-updater[633]: [2026-06-19 17:02:42] [info] asio async_connect error: system:111 (Connection refused) Jun 19 17:02:42 volumio-topping50 volumio-remote-updater[633]: [2026-06-19 17:02:42] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 19 17:02:42 volumio-topping50 volumio-remote-updater[633]: [2026-06-19 17:02:42] [error] handle_connect error: Underlying Transport Error Jun 19 17:02:42 volumio-topping50 volumio-time-update[611]: volumio-time-update-util: Date not found in response Jun 19 17:02:42 volumio-topping50 volumio-time-update[611]: volumio-time-update-util: Retrying in 5 seconds... Jun 19 17:02:43 volumio-topping50 dbus-daemon[614]: [system] Successfully activated service 'org.freedesktop.systemd1' Jun 19 17:02:43 volumio-topping50 avahi-daemon[635]: Successfully called chroot(). Jun 19 17:02:43 volumio-topping50 avahi-daemon[635]: Successfully dropped remaining capabilities. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Login Service. Jun 19 17:02:43 volumio-topping50 wpa_supplicant[641]: Successfully initialized wpa_supplicant Jun 19 17:02:43 volumio-topping50 systemd[1]: Started triggerhappy global hotkey daemon. Jun 19 17:02:43 volumio-topping50 systemd[1]: hciuart.service: Succeeded. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Configure Bluetooth Modems connected by UART. Jun 19 17:02:43 volumio-topping50 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jun 19 17:02:43 volumio-topping50 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jun 19 17:02:43 volumio-topping50 systemd[1]: Failed to start dhcpcd on all interfaces. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Save/Restore Sound Card State. Jun 19 17:02:43 volumio-topping50 avahi-daemon[635]: Loading service file /services/volumio.service. Jun 19 17:02:43 volumio-topping50 sh[543]: eth0: waiting for carrier Jun 19 17:02:43 volumio-topping50 dhcpcd[593]: eth0: waiting for carrier Jun 19 17:02:43 volumio-topping50 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jun 19 17:02:43 volumio-topping50 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jun 19 17:02:43 volumio-topping50 avahi-daemon[635]: Network interface enumeration completed. Jun 19 17:02:43 volumio-topping50 avahi-daemon[635]: Server startup complete. Host name is volumio-topping50.local. Local service cookie is 4026616713. Jun 19 17:02:43 volumio-topping50 avahi-daemon[635]: Service "Volumio Topping50" (/services/volumio.service) successfully established. Jun 19 17:02:43 volumio-topping50 ifup[548]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jun 19 17:02:43 volumio-topping50 volumio[642]: Could not open config: /tmp/upmpdcli.conf Jun 19 17:02:43 volumio-topping50 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 19 17:02:43 volumio-topping50 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started WPA supplicant. Jun 19 17:02:43 volumio-topping50 systemd[1]: Reached target Sound Card. Jun 19 17:02:43 volumio-topping50 sh[540]: wlan0=wlan0 Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting Raspberry Pi bluetooth helper... Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Raise network interfaces. Jun 19 17:02:43 volumio-topping50 systemd[1]: Reached target Network. Jun 19 17:02:43 volumio-topping50 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 19 17:02:43 volumio-topping50 systemd[1]: Reached target Network is Online. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting LSB: Brings up/down network automatically... Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting Samba NMB Daemon... Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting Permit User Sessions... Jun 19 17:02:43 volumio-topping50 bthelper[698]: Raspberry Pi BDADDR already set Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting /etc/rc.local Compatibility... Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting Network Time Service... Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Raspberry Pi bluetooth helper. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Permit User Sessions. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started /etc/rc.local Compatibility. Jun 19 17:02:43 volumio-topping50 loadcpufreq[643]: Loading cpufreq kernel modules...done (none). Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting Hold until boot process finishes up... Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting Bluetooth service... Jun 19 17:02:43 volumio-topping50 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 19 17:02:43 volumio-topping50 systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Hold until boot process finishes up. Jun 19 17:02:43 volumio-topping50 systemd[1]: plymouth-quit.service: Succeeded. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Terminate Plymouth Boot Screen. Jun 19 17:02:43 volumio-topping50 systemd[1]: iptables.service: Succeeded. Jun 19 17:02:43 volumio-topping50 systemd[1]: Received SIGRTMIN+21 from PID 221 (n/a). Jun 19 17:02:43 volumio-topping50 systemd[1]: Received SIGRTMIN+21 from PID 221 (n/a). Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Getty on tty1. Jun 19 17:02:43 volumio-topping50 systemd[1]: Reached target Login Prompts. Jun 19 17:02:43 volumio-topping50 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 19 17:02:43 volumio-topping50 ifplugd(eth0)[793]: ifplugd 0.28 initializing. Jun 19 17:02:43 volumio-topping50 ifplugd(eth0)[793]: Using interface eth0/E4:5F:01:5E:94:A4 with driver (version: 6.6.62-v7l+) Jun 19 17:02:43 volumio-topping50 ifplugd(eth0)[793]: Using detection mode: SIOCETHTOOL Jun 19 17:02:43 volumio-topping50 ifplugd(eth0)[793]: Initialization complete, link beat not detected. Jun 19 17:02:43 volumio-topping50 ntpd[763]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 19 17:02:43 volumio-topping50 ntpd[763]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jun 19 17:02:43 volumio-topping50 ntpd[796]: proto: precision = 0.778 usec (-20) Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Network Time Service. Jun 19 17:02:43 volumio-topping50 ntpd[796]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 19 17:02:43 volumio-topping50 ntpd[796]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 19 17:02:43 volumio-topping50 ntpd[796]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 539 days ago Jun 19 17:02:43 volumio-topping50 ntpd[796]: Listen and drop on 0 v6wildcard [::]:123 Jun 19 17:02:43 volumio-topping50 ntpd[796]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 19 17:02:43 volumio-topping50 ntpd[796]: Listen normally on 2 lo 127.0.0.1:123 Jun 19 17:02:43 volumio-topping50 ntpd[796]: Listening on routing socket on fd #19 for interface updates Jun 19 17:02:43 volumio-topping50 ntpd[796]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 19 17:02:43 volumio-topping50 ntpd[796]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 19 17:02:43 volumio-topping50 ifplugd[747]: Network Interface Plugging Daemon...start eth0...done. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started LSB: Brings up/down network automatically. Jun 19 17:02:43 volumio-topping50 cpufrequtils[780]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 19 17:02:43 volumio-topping50 systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 19 17:02:43 volumio-topping50 bluetoothd[777]: Bluetooth daemon 5.83 Jun 19 17:02:43 volumio-topping50 bluetoothd[777]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 19 17:02:43 volumio-topping50 systemd[1]: Started Bluetooth service. Jun 19 17:02:43 volumio-topping50 systemd[1]: Reached target Bluetooth. Jun 19 17:02:43 volumio-topping50 bluetoothd[777]: Starting SDP server Jun 19 17:02:43 volumio-topping50 bluetoothd[777]: Excluding (cli) hostname Jun 19 17:02:43 volumio-topping50 bluetoothd[777]: Excluding (cli) scanparam Jun 19 17:02:43 volumio-topping50 bluetoothd[777]: Excluding (cli) battery Jun 19 17:02:43 volumio-topping50 bluetoothd[777]: Bluetooth management interface 1.22 initialized Jun 19 17:02:43 volumio-topping50 bluetoothd[777]: Battery Provider Manager created Jun 19 17:02:43 volumio-topping50 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 19 17:02:43 volumio-topping50 kernel: Bluetooth: BNEP filters: protocol multicast Jun 19 17:02:43 volumio-topping50 kernel: Bluetooth: BNEP socket layer initialized Jun 19 17:02:43 volumio-topping50 kernel: Bluetooth: MGMT ver 1.22 Jun 19 17:02:43 volumio-topping50 haveged[578]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jun 19 17:02:43 volumio-topping50 haveged[578]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jun 19 17:02:43 volumio-topping50 haveged[578]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00184 Jun 19 17:02:43 volumio-topping50 haveged[578]: haveged: fills: 0, generated: 0 Jun 19 17:02:43 volumio-topping50 kernel: NET: Registered PF_ALG protocol family Jun 19 17:02:43 volumio-topping50 kernel: cryptd: max_cpu_qlen set to 1000 Jun 19 17:02:44 volumio-topping50 bluetoothd[777]: Failed to set privacy: Rejected (0x0b) Jun 19 17:02:44 volumio-topping50 nmbd[767]: [2026/06/19 17:02:44.259728, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 19 17:02:44 volumio-topping50 nmbd[767]: started asyncdns process 829 Jun 19 17:02:44 volumio-topping50 nmbd[767]: [2026/06/19 17:02:44.262124, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 19 17:02:44 volumio-topping50 nmbd[767]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 19 17:02:44 volumio-topping50 nmbd[767]: [2026/06/19 17:02:44.262238, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 19 17:02:44 volumio-topping50 nmbd[767]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 19 17:02:44 volumio-topping50 wireless.js[636]: Cleaning previous... Jun 19 17:02:44 volumio-topping50 ntpd[796]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 19 17:02:44 volumio-topping50 sudo[837]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 19 17:02:44 volumio-topping50 sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:44 volumio-topping50 sudo[837]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:44 volumio-topping50 sudo[839]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 19 17:02:44 volumio-topping50 sudo[839]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:45 volumio-topping50 sudo[839]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:45 volumio-topping50 sh[543]: eth0: carrier acquired Jun 19 17:02:45 volumio-topping50 dhcpcd[593]: eth0: carrier acquired Jun 19 17:02:45 volumio-topping50 sh[543]: DUID 00:01:00:01:2b:1f:b6:4a:e4:5f:01:5e:94:a5 Jun 19 17:02:45 volumio-topping50 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Jun 19 17:02:45 volumio-topping50 sh[543]: eth0: IAID 01:5e:94:a4 Jun 19 17:02:45 volumio-topping50 sh[543]: eth0: adding address fe80::e65f:1ff:fe5e:94a4 Jun 19 17:02:45 volumio-topping50 sh[543]: ipv6_addaddr1: Permission denied Jun 19 17:02:45 volumio-topping50 dhcpcd[593]: DUID 00:01:00:01:2b:1f:b6:4a:e4:5f:01:5e:94:a5 Jun 19 17:02:45 volumio-topping50 dhcpcd[593]: eth0: IAID 01:5e:94:a4 Jun 19 17:02:45 volumio-topping50 dhcpcd[593]: eth0: adding address fe80::e65f:1ff:fe5e:94a4 Jun 19 17:02:45 volumio-topping50 dhcpcd[593]: ipv6_addaddr1: Permission denied Jun 19 17:02:45 volumio-topping50 wireless.js[636]: Stopped aP Jun 19 17:02:45 volumio-topping50 wireless.js[636]: Wireless Networking DISABLED, not starting wireless flow Jun 19 17:02:45 volumio-topping50 systemd[1]: wireless.service: Succeeded. Jun 19 17:02:45 volumio-topping50 systemd[1]: Started Wireless Services. Jun 19 17:02:45 volumio-topping50 systemd[1]: Started Volumio Backend Module. Jun 19 17:02:45 volumio-topping50 systemd[1]: Started Volumio Cpu Tweaker. Jun 19 17:02:45 volumio-topping50 volumio-cpu-tweak[849]: Setting RT Priority for mpd Jun 19 17:02:45 volumio-topping50 volumio-cpu-tweak[849]: pid 35's current scheduling policy: SCHED_OTHER Jun 19 17:02:45 volumio-topping50 volumio-cpu-tweak[849]: pid 35's current scheduling priority: 0 Jun 19 17:02:45 volumio-topping50 volumio-cpu-tweak[849]: Setting MPD Affinity Jun 19 17:02:45 volumio-topping50 volumio-cpu-tweak[849]: pid 3's current affinity mask: f Jun 19 17:02:45 volumio-topping50 volumio-cpu-tweak[849]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 19 17:02:45 volumio-topping50 volumio-cpu-tweak[849]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 19 17:02:45 volumio-topping50 systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 19 17:02:45 volumio-topping50 ntpd[796]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 19 17:02:45 volumio-topping50 ifplugd(eth0)[793]: Link beat detected. Jun 19 17:02:45 volumio-topping50 sh[543]: eth0: rebinding lease of 192.168.213.155 Jun 19 17:02:45 volumio-topping50 dhcpcd[593]: eth0: rebinding lease of 192.168.213.155 Jun 19 17:02:45 volumio-topping50 ifplugd(eth0)[793]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jun 19 17:02:45 volumio-topping50 ifplugd(eth0)[793]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jun 19 17:02:45 volumio-topping50 sh[543]: eth0: probing address 192.168.213.155/24 Jun 19 17:02:45 volumio-topping50 dhcpcd[593]: eth0: probing address 192.168.213.155/24 Jun 19 17:02:45 volumio-topping50 sh[543]: eth0: soliciting an IPv6 router Jun 19 17:02:45 volumio-topping50 dhcpcd[593]: eth0: soliciting an IPv6 router Jun 19 17:02:46 volumio-topping50 ntpd[796]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 19 17:02:46 volumio-topping50 volumio[848]: info: ------------------------------------------- Jun 19 17:02:46 volumio-topping50 volumio[848]: info: ----- Volumio3 ---- Jun 19 17:02:46 volumio-topping50 volumio[848]: info: ------------------------------------------- Jun 19 17:02:46 volumio-topping50 volumio[848]: info: ----- System startup ---- Jun 19 17:02:46 volumio-topping50 volumio[848]: info: ------------------------------------------- Jun 19 17:02:47 volumio-topping50 systemd[1]: systemd-rfkill.service: Succeeded. Jun 19 17:02:47 volumio-topping50 ntpd[796]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 19 17:02:47 volumio-topping50 volumio[848]: info: MYVOLUMIO Environment detected Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Plugin folders cleanup Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning into folder /volumio/app/plugins/ Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning category audio_interface Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning category miscellanea Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning category music_service Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning category plugins.json Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning category system_controller Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning category user_interface Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning into folder /data/plugins/ Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning category music_service Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Scanning category user_interface Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Plugin folders cleanup completed Jun 19 17:02:47 volumio-topping50 volumio[848]: info: ------------------------------------------- Jun 19 17:02:47 volumio-topping50 volumio[848]: info: ----- Core plugins startup ---- Jun 19 17:02:47 volumio-topping50 volumio[848]: info: ------------------------------------------- Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Loading plugins from folder /volumio/app/plugins/ Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Adding plugin upnp to MyMusic Plugins Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Loading plugins from folder /data/plugins/ Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Loading plugin "system"... Jun 19 17:02:47 volumio-topping50 volumio[848]: info: Loading plugin "appearance"... Jun 19 17:02:47 volumio-topping50 volumio-remote-updater[633]: [2026-06-19 17:02:47] [connect] Successful connection Jun 19 17:02:47 volumio-topping50 volumio-time-update[611]: volumio-time-update-util: Fetching time from Volumio... Jun 19 17:02:47 volumio-topping50 volumio-time-update[611]: volumio-time-update-util: Date not found in response Jun 19 17:02:47 volumio-topping50 volumio-time-update[611]: volumio-time-update-util: Retrying in 5 seconds... Jun 19 17:02:48 volumio-topping50 bluetoothd[777]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Jun 19 17:02:48 volumio-topping50 bthelper[698]: [NEW] Media /org/bluez/hci0 Jun 19 17:02:48 volumio-topping50 bthelper[698]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jun 19 17:02:48 volumio-topping50 bthelper[698]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jun 19 17:02:48 volumio-topping50 bthelper[698]: [CHG] Controller E4:5F:01:5E:94:A6 PowerState: on-disabling Jun 19 17:02:48 volumio-topping50 bthelper[698]: hci0 class of device changed: 0x000000 Jun 19 17:02:48 volumio-topping50 bthelper[698]: hci0 new_settings: connectable discoverable bondable ssp br/edr le secure-conn Jun 19 17:02:48 volumio-topping50 bthelper[698]: Changing power off succeeded Jun 19 17:02:48 volumio-topping50 bthelper[698]: [NEW] Media /org/bluez/hci0 Jun 19 17:02:48 volumio-topping50 bthelper[698]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jun 19 17:02:48 volumio-topping50 bthelper[698]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jun 19 17:02:48 volumio-topping50 bthelper[698]: [CHG] Controller E4:5F:01:5E:94:A6 PowerState: off-enabling Jun 19 17:02:48 volumio-topping50 bthelper[698]: hci0 class of device changed: 0x00041c Jun 19 17:02:48 volumio-topping50 bthelper[698]: [CHG] Controller E4:5F:01:5E:94:A6 Class: 0x0000041c (1052) Jun 19 17:02:48 volumio-topping50 bthelper[698]: hci0 new_settings: powered connectable discoverable bondable ssp br/edr le secure-conn Jun 19 17:02:48 volumio-topping50 bthelper[698]: Changing power on succeeded Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Loading plugin "network"... Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Refreshing Cached IP Addresses Jun 19 17:02:48 volumio-topping50 sudo[890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 19 17:02:48 volumio-topping50 sudo[890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:48 volumio-topping50 sudo[890]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:48 volumio-topping50 sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 19 17:02:48 volumio-topping50 sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Loading plugin "services"... Jun 19 17:02:48 volumio-topping50 sudo[892]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Loading plugin "volumio5onboarding"... Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Loading plugin "alsa_controller"... Jun 19 17:02:48 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Loading plugin "wizard"... Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Loading plugin "networkfs"... Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Starting Udev Watcher for removable devices Jun 19 17:02:48 volumio-topping50 sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.213.73/USB /mnt/NAS/USB Jun 19 17:02:48 volumio-topping50 sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Ignoring mount for partition: boot Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Ignoring mount for partition: volumio Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Ignoring mount for partition: volumio_data Jun 19 17:02:48 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Loading plugin "volumio_command_line_client"... Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Loading plugin "upnp"... Jun 19 17:02:48 volumio-topping50 volumio[848]: info: [1781906568986] Starting Upmpd Daemon Jun 19 17:02:48 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 17:02:48 volumio-topping50 volumio[848]: info: Loading plugin "my_music"... Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Loading plugin "mpd"... Jun 19 17:02:49 volumio-topping50 sudo[915]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:49 volumio-topping50 kernel: Key type cifs.spnego registered Jun 19 17:02:49 volumio-topping50 kernel: Key type cifs.idmap registered Jun 19 17:02:49 volumio-topping50 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jun 19 17:02:49 volumio-topping50 kernel: CIFS: Attempting to mount //192.168.213.73/USB Jun 19 17:02:49 volumio-topping50 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jun 19 17:02:49 volumio-topping50 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Loading plugin "upnp_browser"... Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Starting UPNP Browser Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Loading plugin "alarm-clock"... Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Loading plugin "airplay_emulation"... Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Starting Shairport Sync Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Loading plugin "last_100"... Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Loading plugin "webradio"... Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Loading plugin "i2s_dacs"... Jun 19 17:02:49 volumio-topping50 volumio[848]: info: I2S DAC not set, start Auto-detection Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Loading plugin "volumiodiscovery"... Jun 19 17:02:49 volumio-topping50 volumio[848]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 17:02:49 volumio-topping50 volumio[848]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 17:02:49 volumio-topping50 node[848]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 19 17:02:49 volumio-topping50 volumio[848]: *** WARNING *** For more information see Jun 19 17:02:49 volumio-topping50 volumio[848]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 17:02:49 volumio-topping50 volumio[848]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 17:02:49 volumio-topping50 volumio[848]: *** WARNING *** For more information see Jun 19 17:02:49 volumio-topping50 node[848]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 17:02:49 volumio-topping50 node[848]: *** WARNING *** For more information see Jun 19 17:02:49 volumio-topping50 node[848]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 19 17:02:49 volumio-topping50 node[848]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 19 17:02:49 volumio-topping50 node[848]: *** WARNING *** For more information see Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Discovery: Started advertising with name: Volumio Topping50 Jun 19 17:02:49 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 17:02:49 volumio-topping50 volumio[848]: info: Loading plugin "spop"... Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Loading plugin "outputs"... Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Loading plugin "albumart"... Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Plugin example_plugin is not enabled Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Loading plugin "inputs"... Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Loading plugin "updater_comm"... Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Plugin mpdemulation is not enabled Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Loading plugin "rest_api"... Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Loading plugin "websocket"... Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Starting Socket.io Server version 2.3.0 Jun 19 17:02:50 volumio-topping50 volumio[848]: info: Loading plugin "lastfm"... Jun 19 17:02:51 volumio-topping50 volumio[848]: Forking 3 albumart workers Jun 19 17:02:51 volumio-topping50 volumio[848]: Starting albumart workers Jun 19 17:02:51 volumio-topping50 volumio[848]: Starting albumart workers Jun 19 17:02:51 volumio-topping50 volumio[848]: Starting albumart workers Jun 19 17:02:51 volumio-topping50 volumio[848]: info: Loading i18n strings for locale en Jun 19 17:02:51 volumio-topping50 volumio[848]: Updating browse sources language Jun 19 17:02:51 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 17:02:51 volumio-topping50 sh[543]: eth0: leased 192.168.213.155 for 86400 seconds Jun 19 17:02:51 volumio-topping50 sh[543]: eth0: adding route to 192.168.213.0/24 Jun 19 17:02:51 volumio-topping50 sh[543]: eth0: adding default route via 192.168.213.1 Jun 19 17:02:51 volumio-topping50 dhcpcd[593]: eth0: leased 192.168.213.155 for 86400 seconds Jun 19 17:02:51 volumio-topping50 dhcpcd[593]: eth0: adding route to 192.168.213.0/24 Jun 19 17:02:51 volumio-topping50 dhcpcd[593]: eth0: adding default route via 192.168.213.1 Jun 19 17:02:51 volumio-topping50 avahi-daemon[635]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.213.155. Jun 19 17:02:51 volumio-topping50 avahi-daemon[635]: New relevant interface eth0.IPv4 for mDNS. Jun 19 17:02:51 volumio-topping50 avahi-daemon[635]: Registering new address record for 192.168.213.155 on eth0.IPv4. Jun 19 17:02:51 volumio-topping50 sh[543]: forked to background, child pid 980 Jun 19 17:02:51 volumio-topping50 dhcpcd[593]: forked to background, child pid 980 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::initPlayerControls Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 ifplugd(eth0)[793]: client: ifup: interface eth0 already configured Jun 19 17:02:52 volumio-topping50 sh[543]: eth0=eth0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: Express server listening on port 3000 Jun 19 17:02:52 volumio-topping50 volumio[848]: [Metrics] WebUI: 5s 734.70ms Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreStateMachine::resetVolumioState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreStateMachine::getcurrentVolume Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Volumio Network Manager: Network status updated: 0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Cannot mount NAS USB at system boot, trial number 1 ,retrying in 5 seconds Jun 19 17:02:52 volumio-topping50 ifplugd(eth0)[793]: Program executed successfully. Jun 19 17:02:52 volumio-topping50 volumio-remote-updater[633]: [2026-06-19 17:02:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1781906567 101 Jun 19 17:02:52 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 19 17:02:52 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to 192.168.213.155:3000 from 192.168.213.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 19 17:02:52 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to 192.168.213.155:3000 from 192.168.213.73 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 19 17:02:52 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to 192.168.213.155:3000 from 192.168.213.101 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 19 17:02:52 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Reloading queue from file Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Setting Device type: Raspberry PI Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreStateMachine::setRepeat false single undefined Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreStateMachine::pushState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrack 0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioPushState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreStateMachine::setRandom false Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreStateMachine::pushState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrack 0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioPushState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: VolumeController:: Volume=100 Mute =false Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreStateMachine::pushState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrack 0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioPushState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreStateMachine::updateTrackBlock Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrackBlock Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioRetrievevolume Jun 19 17:02:52 volumio-topping50 volumio[848]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 19 17:02:52 volumio-topping50 volumio[848]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioGetState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrack 0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioGetState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrack 0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: VolumeController:: Volume=100 Mute =false Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreStateMachine::pushState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrack 0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioPushState Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Completed loading Core Plugins Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Preparing to generate the ALSA configuration file Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Asound.conf file written Jun 19 17:02:52 volumio-topping50 sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 19 17:02:52 volumio-topping50 sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:52 volumio-topping50 sudo[1045]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Output device has changed, restarting MPD Jun 19 17:02:52 volumio-topping50 sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 17:02:52 volumio-topping50 sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Output device has changed, restarting Shairport Sync Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:02:52 volumio-topping50 sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 17:02:52 volumio-topping50 sudo[1050]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:52 volumio-topping50 sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: ___________ START PLUGINS ___________ Jun 19 17:02:52 volumio-topping50 volumio[848]: info: ControllerMpd::onStart: Initializing MPD Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Creating MPD Configuration file Jun 19 17:02:52 volumio-topping50 sudo[1060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Jun 19 17:02:52 volumio-topping50 sudo[1060]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:52 volumio-topping50 systemd[1]: Listening on mpd.socket. Jun 19 17:02:52 volumio-topping50 systemd[1]: Starting Music Player Daemon... Jun 19 17:02:52 volumio-topping50 sudo[1063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 17:02:52 volumio-topping50 sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:52 volumio-topping50 sudo[1063]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 17:02:52 volumio-topping50 volumio[848]: info: [1781906572641] CoreMusicLibrary::Adding element Media Servers Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 17:02:52 volumio-topping50 systemd[1]: Started Volumio5 Onboarding Server. Jun 19 17:02:52 volumio-topping50 sudo[1067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 17:02:52 volumio-topping50 sudo[1067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:52 volumio-topping50 sudo[1060]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:52 volumio-topping50 volumio[848]: info: UPNP Browser: Client initialized successfully Jun 19 17:02:52 volumio-topping50 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 19 17:02:52 volumio-topping50 systemd[1]: mpd.service: Succeeded. Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:02:52 volumio-topping50 systemd[1]: Stopped Music Player Daemon. Jun 19 17:02:52 volumio-topping50 systemd[1]: Starting Music Player Daemon... Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:02:52 volumio-topping50 sudo[1070]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 19 17:02:52 volumio-topping50 sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:52 volumio-topping50 sudo[1070]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 19 17:02:52 volumio-topping50 sudo[1070]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 17:02:52 volumio-topping50 volumio[848]: info: [1781906572767] CoreMusicLibrary::Adding element Last_100 Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 17:02:52 volumio-topping50 volumio[848]: info: [1781906572770] CoreMusicLibrary::Adding element Webradio Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Initializing BBC Radios Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Checking for old spotify connect plugin installed Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 17:02:52 volumio-topping50 volumio[848]: info: [1781906572839] CoreMusicLibrary::Adding element LastFM Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 17:02:52 volumio-topping50 volumio[848]: Cannot find translation for source LastFM Jun 19 17:02:52 volumio-topping50 volumio[848]: info: [LastFM] scrobbler initiated! Jun 19 17:02:52 volumio-topping50 volumio[848]: info: [LastFM] extended logging: false Jun 19 17:02:52 volumio-topping50 volumio[848]: info: [LastFM] try scrobble stream/radio plays: true Jun 19 17:02:52 volumio-topping50 volumio[848]: info: [LastFM] Left init routine Jun 19 17:02:52 volumio-topping50 volumio[848]: info: [LastFM] Socket already connected: true Jun 19 17:02:52 volumio-topping50 volumio[848]: info: Volumio Calling Home Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 19 17:02:52 volumio-topping50 volumio[848]: [SpotifyConnect] Creating VLS config file Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:52 volumio-topping50 volumio[848]: [SpotifyConnect] Starting metadata listener Jun 19 17:02:52 volumio-topping50 sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jun 19 17:02:52 volumio-topping50 sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:52 volumio-topping50 volumio-time-update[611]: volumio-time-update-util: Fetching time from Volumio... Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: adding b0b2f847-2d79-47cc-919e-5787f5ff8b3a Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: Found device Gustard X26pro Volumio Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: Connecting to remote: 192.168.213.73 Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: adding 4cde0c53-afc1-43b9-951d-2f2c83694816 Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: Found device Volumio Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: Connecting to remote: 192.168.213.101 Jun 19 17:02:53 volumio-topping50 volumio[848]: info: MPD Permissions set Jun 19 17:02:53 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 19 17:02:53 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 19 17:02:53 volumio-topping50 systemd[1]: Started Volspotconnect2 Daemon. Jun 19 17:02:53 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to 192.168.213.155:3000 from 192.168.213.73 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 19 17:02:53 volumio-topping50 sudo[1098]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: Connected to remote: 192.168.213.101 Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: Connected to remote: 192.168.213.73 Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 17:02:53 volumio-topping50 volumio[848]: [SpotifyConnect] Vollibrespot Daemon service started! Jun 19 17:02:53 volumio-topping50 volumio[848]: [Metrics] SpotifyConnect: 0s 255.46ms Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Starting Shairport Sync Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Starting Shairport Sync Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Starting Shairport Sync Jun 19 17:02:53 volumio-topping50 sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 17:02:53 volumio-topping50 sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:53 volumio-topping50 sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 17:02:53 volumio-topping50 sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:53 volumio-topping50 sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 19 17:02:53 volumio-topping50 sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:53 volumio-topping50 volumio[1104]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jun 19 17:02:53 volumio-topping50 volumio[1104]: Reading Config from "/tmp/volspotify.toml" Jun 19 17:02:53 volumio-topping50 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 19 17:02:53 volumio-topping50 systemd[1]: shairport-sync.service: Succeeded. Jun 19 17:02:53 volumio-topping50 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 19 17:02:53 volumio-topping50 volumio[1104]: [Vollibrespot] : Using Alsa backend with device: volumio Jun 19 17:02:53 volumio-topping50 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 19 17:02:53 volumio-topping50 sudo[1115]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:53 volumio-topping50 sudo[1117]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:53 volumio-topping50 sudo[1120]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Shairport-Sync Started Jun 19 17:02:53 volumio-topping50 volumio[848]: Error adding Membership: Error: addMembership EINVAL Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Shairport-Sync Started Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Shairport-Sync Started Jun 19 17:02:53 volumio-topping50 volumio5-onboarding[1068]: time=2026-06-19T17:02:53.378-05:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Jun 19 17:02:53 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Jun 19 17:02:53 volumio-topping50 volumio[1104]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Received Get System Info Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: Getting this device information Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioGetState Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrack 0 Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 17:02:53 volumio-topping50 volumio5-onboarding[1068]: time=2026-06-19T17:02:53.433-05:00 level=INFO msg="system info for 5564d50c9ff424dc781b6ebcb2df9c1e" deviceName="Volumio Topping50" deviceVariant=volumio deviceModel= softwareVersion=3.912 Jun 19 17:02:53 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Received Get System Info Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: Getting this device information Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioGetState Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrack 0 Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 19 17:02:53 volumio-topping50 volumio5-onboarding[1068]: time=2026-06-19T17:02:53.444-05:00 level=INFO msg="enabling local network discovery" Jun 19 17:02:53 volumio-topping50 volumio5-onboarding[1068]: time=2026-06-19T17:02:53.454-05:00 level=INFO msg="enabling BLE discovery" Jun 19 17:02:53 volumio-topping50 volumio5-onboarding[1068]: time=2026-06-19T17:02:53.503-05:00 level=INFO msg="bootstrapping state" hasInternet=true Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Received Get System Info Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Discovery: Getting this device information Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::volumioGetState Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CorePlayQueue::getTrack 0 Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 19 17:02:53 volumio-topping50 volumio-remote-updater[633]: No test mode Jun 19 17:02:53 volumio-topping50 volumio-remote-updater[633]: No alpha test mode Jun 19 17:02:53 volumio-topping50 ntpd[796]: Listen normally on 3 eth0 192.168.213.155:123 Jun 19 17:02:53 volumio-topping50 ntpd[796]: new interface(s) found: waking up resolver Jun 19 17:02:53 volumio-topping50 volumio[848]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 19 17:02:53 volumio-topping50 volumio[848]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 19 17:02:54 volumio-topping50 nmbd[767]: [2026/06/19 17:02:54.264822, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 19 17:02:54 volumio-topping50 nmbd[767]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 19 17:02:54 volumio-topping50 systemd[1]: Started Samba NMB Daemon. Jun 19 17:02:54 volumio-topping50 systemd[1]: Starting Samba Winbind Daemon... Jun 19 17:02:54 volumio-topping50 volumio[848]: verbose: New Socket.io Connection to 192.168.213.155:3000 from 192.168.213.73 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 19 17:02:54 volumio-topping50 winbindd[1133]: [2026/06/19 17:02:54.557079, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 19 17:02:54 volumio-topping50 winbindd[1133]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 19 17:02:54 volumio-topping50 winbindd[1133]: [2026/06/19 17:02:54.579168, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 19 17:02:54 volumio-topping50 systemd[1]: Started Samba Winbind Daemon. Jun 19 17:02:54 volumio-topping50 winbindd[1133]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 19 17:02:54 volumio-topping50 systemd[1]: Starting Samba SMB Daemon... Jun 19 17:02:54 volumio-topping50 volumio5-onboarding[1068]: time=2026-06-19T17:02:54.672-05:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 19 17:02:55 volumio-topping50 winbindd[1133]: [2026/06/19 17:02:55.056561, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jun 19 17:02:55 volumio-topping50 winbindd[1133]: res_names->count = 3, expected 4 Jun 19 17:02:55 volumio-topping50 mpd[1078]: Jun 19 17:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 19 17:02:55 volumio-topping50 smbd[1138]: [2026/06/19 17:02:55.080074, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 19 17:02:55 volumio-topping50 systemd[1]: Started Samba SMB Daemon. Jun 19 17:02:55 volumio-topping50 smbd[1138]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 19 17:02:55 volumio-topping50 systemd[1]: Started Music Player Daemon. Jun 19 17:02:55 volumio-topping50 sudo[1067]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:55 volumio-topping50 sudo[1052]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:55 volumio-topping50 volumio[848]: info: Completed starting Core Plugins Jun 19 17:02:55 volumio-topping50 volumio[848]: info: ------------------------------------------- Jun 19 17:02:55 volumio-topping50 volumio[848]: info: ----- MyVolumio plugins startup ---- Jun 19 17:02:55 volumio-topping50 volumio[848]: info: ------------------------------------------- Jun 19 17:02:55 volumio-topping50 volumio[848]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 19 17:02:55 volumio-topping50 volumio[848]: error: MPD error: The expression evaluated to a falsy value: Jun 19 17:02:55 volumio-topping50 volumio[848]: assert.ok(self.idling) Jun 19 17:02:55 volumio-topping50 volumio[848]: error: The expression evaluated to a falsy value: Jun 19 17:02:55 volumio-topping50 volumio[848]: assert.ok(self.idling) Jun 19 17:02:55 volumio-topping50 volumio[848]: info: MPD running with PID1078 Jun 19 17:02:55 volumio-topping50 volumio[848]: ,establishing connection Jun 19 17:02:55 volumio-topping50 volumio[848]: error: MPD error: The expression evaluated to a falsy value: Jun 19 17:02:55 volumio-topping50 volumio[848]: assert.ok(self.idling) Jun 19 17:02:55 volumio-topping50 volumio[848]: error: The expression evaluated to a falsy value: Jun 19 17:02:55 volumio-topping50 volumio[848]: assert.ok(self.idling) Jun 19 17:02:55 volumio-topping50 volumio[848]: error: updateQueue error: null Jun 19 17:02:57 volumio-topping50 sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.213.73/USB /mnt/NAS/USB Jun 19 17:02:57 volumio-topping50 sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 17:02:57 volumio-topping50 kernel: CIFS: Attempting to mount //192.168.213.73/USB Jun 19 17:02:57 volumio-topping50 volumio[848]: info: Checking Spotify Web API Jun 19 17:02:57 volumio-topping50 sudo[1151]: pam_unix(sudo:session): session closed for user root Jun 19 17:02:57 volumio-topping50 volumio[848]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 17:02:57 volumio-topping50 volumio[848]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com Jun 19 17:02:57 volumio-topping50 volumio[848]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jun 19 17:02:57 volumio-topping50 volumio[848]: errno: -3007, Jun 19 17:02:57 volumio-topping50 volumio[848]: code: 'ENOTFOUND', Jun 19 17:02:57 volumio-topping50 volumio[848]: syscall: 'getaddrinfo', Jun 19 17:02:57 volumio-topping50 volumio[848]: hostname: 'ws.audioscrobbler.com' Jun 19 17:02:57 volumio-topping50 volumio[848]: } Jun 19 17:02:57 volumio-topping50 volumio[848]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 17:02:58 volumio-topping50 sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-19 17:01 Jun 19 17:02:58 volumio-topping50 sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"