-- Logs begin at Thu 2019-02-14 04:11:58 CST, end at Fri 2026-01-09 15:22:22 CST. -- Jan 09 15:22:09 volumio-topping50 fake-hwclock[325]: Fri Jan 9 21:22:09 UTC 2026 Jan 09 15:22:09 volumio-topping50 systemd[1]: Starting Create System Users... Jan 09 15:22:09 volumio-topping50 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 09 15:22:09 volumio-topping50 systemd[1]: Starting Load/Save Random Seed... Jan 09 15:22:09 volumio-topping50 systemd[1]: Started Restore / save the current clock. Jan 09 15:22:09 volumio-topping50 systemd[1]: Started Load/Save Random Seed. Jan 09 15:22:09 volumio-topping50 systemd[1]: dynamicswap.service: Succeeded. Jan 09 15:22:09 volumio-topping50 systemd[1]: Started Create System Users. Jan 09 15:22:09 volumio-topping50 systemd[1]: Starting Create Static Device Nodes in /dev... Jan 09 15:22:09 volumio-topping50 systemd[1]: Started udev Coldplug all Devices. Jan 09 15:22:09 volumio-topping50 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 09 15:22:09 volumio-topping50 systemd[1]: Started Create Static Device Nodes in /dev. Jan 09 15:22:09 volumio-topping50 systemd[1]: Starting udev Kernel Device Manager... Jan 09 15:22:09 volumio-topping50 systemd[1]: Reached target Local File Systems (Pre). Jan 09 15:22:09 volumio-topping50 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 09 15:22:09 volumio-topping50 systemd[1]: Mounting /var/log... Jan 09 15:22:09 volumio-topping50 systemd[1]: Mounting /var/spool/cups... Jan 09 15:22:09 volumio-topping50 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 09 15:22:09 volumio-topping50 systemd[1]: Mounting /tmp... Jan 09 15:22:09 volumio-topping50 systemd[1]: Mounted /var/log. Jan 09 15:22:09 volumio-topping50 systemd[1]: Mounted /var/spool/cups. Jan 09 15:22:09 volumio-topping50 systemd[1]: Mounted /tmp. Jan 09 15:22:09 volumio-topping50 systemd[1]: Mounting /var/spool/cups/tmp... Jan 09 15:22:09 volumio-topping50 systemd[1]: Starting Flush Journal to Persistent Storage... Jan 09 15:22:09 volumio-topping50 systemd[1]: Mounted /var/spool/cups/tmp. Jan 09 15:22:09 volumio-topping50 systemd-udevd[354]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 09 15:22:09 volumio-topping50 systemd-journald[323]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 7.5M, max 30.0M, 22.5M free. Jan 09 15:22:09 volumio-topping50 systemd[1]: Started udev Kernel Device Manager. Jan 09 15:22:09 volumio-topping50 systemd[1]: Starting Show Plymouth Boot Screen... Jan 09 15:22:09 volumio-topping50 systemd[1]: Started Flush Journal to Persistent Storage. Jan 09 15:22:09 volumio-topping50 systemd[1]: plymouth-start.service: Succeeded. Jan 09 15:22:09 volumio-topping50 systemd[1]: Started Show Plymouth Boot Screen. Jan 09 15:22:09 volumio-topping50 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 09 15:22:09 volumio-topping50 systemd[1]: Reached target Local Encrypted Volumes. Jan 09 15:22:09 volumio-topping50 systemd[1]: Reached target Paths. Jan 09 15:22:09 volumio-topping50 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 09 15:22:09 volumio-topping50 systemd-udevd[377]: Using default interface naming scheme 'v240'. Jan 09 15:22:09 volumio-topping50 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jan 09 15:22:09 volumio-topping50 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jan 09 15:22:09 volumio-topping50 systemd-udevd[377]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 09 15:22:09 volumio-topping50 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 15:22:09 volumio-topping50 systemd-udevd[360]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 09 15:22:09 volumio-topping50 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 09 15:22:09 volumio-topping50 kernel: [vc_sm_connected_init]: start Jan 09 15:22:09 volumio-topping50 kernel: mc: Linux media interface: v0.10 Jan 09 15:22:09 volumio-topping50 kernel: [vc_sm_connected_init]: installed successfully Jan 09 15:22:09 volumio-topping50 kernel: videodev: Linux video capture interface: v2.00 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 15:22:09 volumio-topping50 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 15:22:09 volumio-topping50 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 09 15:22:09 volumio-topping50 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 15:22:09 volumio-topping50 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 15:22:09 volumio-topping50 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 15:22:09 volumio-topping50 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jan 09 15:22:09 volumio-topping50 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jan 09 15:22:09 volumio-topping50 kernel: Bluetooth: Core ver 2.22 Jan 09 15:22:09 volumio-topping50 kernel: NET: Registered PF_BLUETOOTH protocol family Jan 09 15:22:09 volumio-topping50 kernel: Bluetooth: HCI device and connection manager initialized Jan 09 15:22:09 volumio-topping50 kernel: Bluetooth: HCI socket layer initialized Jan 09 15:22:09 volumio-topping50 kernel: Bluetooth: L2CAP socket layer initialized Jan 09 15:22:09 volumio-topping50 kernel: Bluetooth: SCO socket layer initialized Jan 09 15:22:09 volumio-topping50 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 09 15:22:09 volumio-topping50 kernel: Bluetooth: HCI UART driver ver 2.3 Jan 09 15:22:09 volumio-topping50 kernel: Bluetooth: HCI UART protocol H4 registered Jan 09 15:22:09 volumio-topping50 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 09 15:22:09 volumio-topping50 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jan 09 15:22:10 volumio-topping50 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jan 09 15:22:10 volumio-topping50 kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 09 15:22:10 volumio-topping50 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jan 09 15:22:10 volumio-topping50 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 09 15:22:10 volumio-topping50 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jan 09 15:22:10 volumio-topping50 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jan 09 15:22:10 volumio-topping50 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 09 15:22:10 volumio-topping50 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jan 09 15:22:10 volumio-topping50 kernel: uart-pl011 fe201000.serial: no DMA platform data Jan 09 15:22:10 volumio-topping50 systemd[1]: Found device /dev/disk/by-uuid/7F72-C251. Jan 09 15:22:10 volumio-topping50 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Jan 09 15:22:10 volumio-topping50 systemd[1]: Starting File System Check on /dev/disk/by-uuid/7F72-C251... Jan 09 15:22:10 volumio-topping50 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 09 15:22:10 volumio-topping50 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jan 09 15:22:10 volumio-topping50 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 09 15:22:10 volumio-topping50 kernel: usbcore: registered new interface driver brcmfmac Jan 09 15:22:10 volumio-topping50 kernel: Bluetooth: hci0: BCM: chip id 107 Jan 09 15:22:10 volumio-topping50 kernel: Bluetooth: hci0: BCM: features 0x2f Jan 09 15:22:10 volumio-topping50 kernel: Bluetooth: hci0: BCM4345C0 Jan 09 15:22:10 volumio-topping50 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jan 09 15:22:10 volumio-topping50 kernel: usb 1-1.4: 1:3 : unsupported format bits 0x100000000 Jan 09 15:22:10 volumio-topping50 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jan 09 15:22:10 volumio-topping50 kernel: usbcore: registered new interface driver snd-usb-audio Jan 09 15:22:10 volumio-topping50 systemd-fsck[502]: fsck.fat 4.1 (2017-01-24) Jan 09 15:22:10 volumio-topping50 systemd-fsck[502]: /dev/mmcblk0p1: 411 files, 41753/46774 clusters Jan 09 15:22:10 volumio-topping50 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jan 09 15:22:10 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 Jan 09 15:22:10 volumio-topping50 systemd-udevd[371]: Using default interface naming scheme 'v240'. Jan 09 15:22:10 volumio-topping50 systemd-udevd[371]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 09 15:22:10 volumio-topping50 systemd[1]: Started File System Check on /dev/disk/by-uuid/7F72-C251. Jan 09 15:22:11 volumio-topping50 systemd[1]: Created slice system-bthelper.slice. Jan 09 15:22:11 volumio-topping50 kernel: Bluetooth: hci0: BCM: features 0x2f Jan 09 15:22:11 volumio-topping50 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jan 09 15:22:11 volumio-topping50 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jan 09 15:22:11 volumio-topping50 kernel: alsactl[510]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jan 09 15:22:11 volumio-topping50 kernel: alsactl[507]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Jan 09 15:22:11 volumio-topping50 systemd-udevd[377]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started File System Check Daemon to report status. Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Mounting /boot... Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Show Plymouth Boot Screen... Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 09 15:22:11 volumio-topping50 systemd[1]: Mounted /boot. Jan 09 15:22:11 volumio-topping50 systemd[1]: plymouth-start.service: Succeeded. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Show Plymouth Boot Screen. Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Reached target Local File Systems. Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Create Volatile Files and Directories... Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 09 15:22:11 volumio-topping50 systemd[1]: Started ifup for eth0. Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Preprocess NFS configuration... Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Load/Save RF Kill Switch Status. Jan 09 15:22:11 volumio-topping50 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Create Volatile Files and Directories. Jan 09 15:22:11 volumio-topping50 systemd[1]: plymouth-read-write.service: Succeeded. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 09 15:22:11 volumio-topping50 systemd[1]: nfs-config.service: Succeeded. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Preprocess NFS configuration. Jan 09 15:22:11 volumio-topping50 systemd[1]: Received SIGRTMIN+20 from PID 212 (plymouthd). Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Reached target NFS client services. Jan 09 15:22:11 volumio-topping50 kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting RPC bind portmap service... Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 09 15:22:11 volumio-topping50 systemd[1]: Started ifup for wlan0. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started RPC bind portmap service. Jan 09 15:22:11 volumio-topping50 systemd[1]: Reached target RPC Port Mapper. Jan 09 15:22:11 volumio-topping50 systemd[1]: Reached target Remote File Systems (Pre). Jan 09 15:22:11 volumio-topping50 systemd[1]: Reached target Remote File Systems. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Raise network interfaces... Jan 09 15:22:11 volumio-topping50 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 09 15:22:11 volumio-topping50 systemd[1]: Reached target System Initialization. Jan 09 15:22:11 volumio-topping50 systemd[1]: Listening on triggerhappy.socket. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Daily apt download activities. Jan 09 15:22:11 volumio-topping50 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Daily apt upgrade and clean activities. Jan 09 15:22:11 volumio-topping50 systemd[1]: Reached target Timers. Jan 09 15:22:11 volumio-topping50 systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 09 15:22:11 volumio-topping50 systemd[1]: Reached target Sockets. Jan 09 15:22:11 volumio-topping50 systemd[1]: Reached target Basic System. Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 09 15:22:11 volumio-topping50 dhcpcd[552]: eth0: waiting for carrier Jan 09 15:22:11 volumio-topping50 sh[533]: eth0: waiting for carrier Jan 09 15:22:11 volumio-topping50 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jan 09 15:22:11 volumio-topping50 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jan 09 15:22:11 volumio-topping50 systemd[1]: Started MPD Monitor Service. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Volumio Log Rotation Service. Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Wireless Services... Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Volumio Iptables Module. Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 09 15:22:11 volumio-topping50 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 09 15:22:11 volumio-topping50 mpd_monitor.sh[598]: MPD Monitor Service: Starting MPD Monitor Service Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Volumio Time Update Utility... Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting triggerhappy global hotkey daemon... Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Login Service... Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting dhcpcd on all interfaces... Jan 09 15:22:11 volumio-topping50 systemd[1]: Started UPnP Renderer front-end to MPD. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started D-Bus System Message Bus. Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting WPA supplicant... Jan 09 15:22:11 volumio-topping50 systemd[1]: Started volumio-remote-updater.service. Jan 09 15:22:11 volumio-topping50 systemd[1]: Started Manage Sound Card State (restore and store). Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Save/Restore Sound Card State... Jan 09 15:22:11 volumio-topping50 volumio-time-update[610]: volumio-time-update-util: Fetching time from Volumio... Jan 09 15:22:11 volumio-topping50 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 09 15:22:11 volumio-topping50 alsactl[630]: alsactl 1.1.8 daemon started Jan 09 15:22:11 volumio-topping50 dhcpcd[618]: Not running dhcpcd because /etc/network/interfaces Jan 09 15:22:11 volumio-topping50 dhcpcd[618]: defines some interfaces that will use a Jan 09 15:22:11 volumio-topping50 dhcpcd[618]: DHCP client or static address Jan 09 15:22:11 volumio-topping50 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 09 15:22:11 volumio-topping50 thd[613]: Unable to parse trigger line: Jan 09 15:22:11 volumio-topping50 thd[613]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 09 15:22:11 volumio-topping50 thd[613]: Unable to parse trigger line: Jan 09 15:22:11 volumio-topping50 thd[613]: Unable to parse trigger line: Jan 09 15:22:11 volumio-topping50 thd[613]: Found socket passed from systemd Jan 09 15:22:11 volumio-topping50 systemd-logind[616]: New seat seat0. Jan 09 15:22:11 volumio-topping50 mpd_monitor.sh[598]: MPD error: Connection refused Jan 09 15:22:11 volumio-topping50 mpd_monitor.sh[598]: MPD Monitor Service: MPD Appears to be inactive, restarting Jan 09 15:22:11 volumio-topping50 avahi-daemon[607]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 09 15:22:11 volumio-topping50 avahi-daemon[607]: Successfully dropped root privileges. Jan 09 15:22:11 volumio-topping50 avahi-daemon[607]: avahi-daemon 0.7 starting up. Jan 09 15:22:11 volumio-topping50 mpd_monitor.sh[598]: mpd: no process found Jan 09 15:22:11 volumio-topping50 volumio-remote-updater[626]: Error: No active session Jan 09 15:22:12 volumio-topping50 volumio-remote-updater[626]: [2026-01-09 15:22:12] [info] asio async_connect error: system:111 (Connection refused) Jan 09 15:22:12 volumio-topping50 volumio-remote-updater[626]: [2026-01-09 15:22:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 09 15:22:12 volumio-topping50 volumio-remote-updater[626]: [2026-01-09 15:22:12] [error] handle_connect error: Underlying Transport Error Jan 09 15:22:12 volumio-topping50 volumio-time-update[610]: volumio-time-update-util: Date not found in response Jan 09 15:22:12 volumio-topping50 volumio-time-update[610]: volumio-time-update-util: Retrying in 5 seconds... Jan 09 15:22:12 volumio-topping50 systemd[1]: Started triggerhappy global hotkey daemon. Jan 09 15:22:12 volumio-topping50 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jan 09 15:22:12 volumio-topping50 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jan 09 15:22:12 volumio-topping50 systemd[1]: Failed to start dhcpcd on all interfaces. Jan 09 15:22:12 volumio-topping50 avahi-daemon[607]: Successfully called chroot(). Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Save/Restore Sound Card State. Jan 09 15:22:12 volumio-topping50 avahi-daemon[607]: Successfully dropped remaining capabilities. Jan 09 15:22:12 volumio-topping50 systemd[1]: hciuart.service: Succeeded. Jan 09 15:22:12 volumio-topping50 wpa_supplicant[623]: Successfully initialized wpa_supplicant Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 09 15:22:12 volumio-topping50 volumio[619]: Could not open config: /tmp/upmpdcli.conf Jan 09 15:22:12 volumio-topping50 avahi-daemon[607]: Loading service file /services/volumio.service. Jan 09 15:22:12 volumio-topping50 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 09 15:22:12 volumio-topping50 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 09 15:22:12 volumio-topping50 avahi-daemon[607]: Network interface enumeration completed. Jan 09 15:22:12 volumio-topping50 avahi-daemon[607]: Server startup complete. Host name is volumio-topping50.local. Local service cookie is 3591950464. Jan 09 15:22:12 volumio-topping50 avahi-daemon[607]: Service "Volumio Topping50" (/services/volumio.service) successfully established. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Login Service. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started WPA supplicant. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 09 15:22:12 volumio-topping50 ifup[569]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jan 09 15:22:12 volumio-topping50 sh[560]: wlan0=wlan0 Jan 09 15:22:12 volumio-topping50 systemd[1]: Listening on mpd.socket. Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 09 15:22:12 volumio-topping50 systemd[1]: Reached target Sound Card. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Raise network interfaces. Jan 09 15:22:12 volumio-topping50 systemd[1]: Reached target Network. Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting Network Time Service... Jan 09 15:22:12 volumio-topping50 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting Permit User Sessions... Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting Music Player Daemon... Jan 09 15:22:12 volumio-topping50 systemd[1]: Reached target Network is Online. Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting LSB: Brings up/down network automatically... Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting Samba NMB Daemon... Jan 09 15:22:12 volumio-topping50 loadcpufreq[596]: Loading cpufreq kernel modules...done (none). Jan 09 15:22:12 volumio-topping50 bthelper[723]: Raspberry Pi BDADDR already set Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting /etc/rc.local Compatibility... Jan 09 15:22:12 volumio-topping50 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Raspberry Pi bluetooth helper. Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting Bluetooth service... Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Permit User Sessions. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started /etc/rc.local Compatibility. Jan 09 15:22:12 volumio-topping50 systemd[1]: iptables.service: Succeeded. Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 09 15:22:12 volumio-topping50 systemd[1]: Starting Hold until boot process finishes up... Jan 09 15:22:12 volumio-topping50 systemd[1]: Received SIGRTMIN+21 from PID 212 (plymouthd). Jan 09 15:22:12 volumio-topping50 systemd[1]: plymouth-quit.service: Succeeded. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Terminate Plymouth Boot Screen. Jan 09 15:22:12 volumio-topping50 systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Hold until boot process finishes up. Jan 09 15:22:12 volumio-topping50 systemd[1]: Received SIGRTMIN+21 from PID 212 (n/a). Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Getty on tty1. Jan 09 15:22:12 volumio-topping50 systemd[1]: Reached target Login Prompts. Jan 09 15:22:12 volumio-topping50 cpufrequtils[771]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 09 15:22:12 volumio-topping50 ntpd[744]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 09 15:22:12 volumio-topping50 ntpd[744]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 09 15:22:12 volumio-topping50 ntpd[795]: proto: precision = 0.815 usec (-20) Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Network Time Service. Jan 09 15:22:12 volumio-topping50 ifplugd(eth0)[799]: ifplugd 0.28 initializing. Jan 09 15:22:12 volumio-topping50 ifplugd(eth0)[799]: Using interface eth0/E4:5F:01:5E:94:A4 with driver (version: 6.6.62-v7l+) Jan 09 15:22:12 volumio-topping50 ifplugd(eth0)[799]: Using detection mode: SIOCETHTOOL Jan 09 15:22:12 volumio-topping50 ifplugd(eth0)[799]: Initialization complete, link beat not detected. Jan 09 15:22:12 volumio-topping50 haveged[556]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 09 15:22:12 volumio-topping50 haveged[556]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 09 15:22:12 volumio-topping50 haveged[556]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00128 Jan 09 15:22:12 volumio-topping50 haveged[556]: haveged: fills: 0, generated: 0 Jan 09 15:22:12 volumio-topping50 ntpd[795]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 09 15:22:12 volumio-topping50 ntpd[795]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 09 15:22:12 volumio-topping50 ntpd[795]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 378 days ago Jan 09 15:22:12 volumio-topping50 ntpd[795]: Listen and drop on 0 v6wildcard [::]:123 Jan 09 15:22:12 volumio-topping50 ntpd[795]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 09 15:22:12 volumio-topping50 ntpd[795]: Listen normally on 2 lo 127.0.0.1:123 Jan 09 15:22:12 volumio-topping50 ntpd[795]: Listening on routing socket on fd #19 for interface updates Jan 09 15:22:12 volumio-topping50 ntpd[795]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 09 15:22:12 volumio-topping50 ntpd[795]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 09 15:22:12 volumio-topping50 ifplugd[756]: Network Interface Plugging Daemon...start eth0...done. Jan 09 15:22:12 volumio-topping50 systemd[1]: Started LSB: Brings up/down network automatically. Jan 09 15:22:12 volumio-topping50 bluetoothd[770]: Bluetooth daemon 5.50 Jan 09 15:22:12 volumio-topping50 bluetoothd[770]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 09 15:22:12 volumio-topping50 systemd[1]: Started Bluetooth service. Jan 09 15:22:12 volumio-topping50 systemd[1]: Reached target Bluetooth. Jan 09 15:22:12 volumio-topping50 bluetoothd[770]: Starting SDP server Jan 09 15:22:12 volumio-topping50 bluetoothd[770]: Excluding (cli) sap Jan 09 15:22:12 volumio-topping50 bluetoothd[770]: Bluetooth management interface 1.22 initialized Jan 09 15:22:12 volumio-topping50 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 09 15:22:12 volumio-topping50 kernel: Bluetooth: BNEP filters: protocol multicast Jan 09 15:22:12 volumio-topping50 kernel: Bluetooth: BNEP socket layer initialized Jan 09 15:22:12 volumio-topping50 kernel: Bluetooth: MGMT ver 1.22 Jan 09 15:22:12 volumio-topping50 dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=770 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 09 15:22:13 volumio-topping50 sudo[754]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 09 15:22:13 volumio-topping50 sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:13 volumio-topping50 sudo[754]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 09 15:22:13 volumio-topping50 sudo[754]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:13 volumio-topping50 bluetoothd[770]: Failed to set privacy: Rejected (0x0b) Jan 09 15:22:13 volumio-topping50 systemd[1]: Starting Hostname Service... Jan 09 15:22:13 volumio-topping50 dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 09 15:22:13 volumio-topping50 systemd[1]: Started Hostname Service. Jan 09 15:22:13 volumio-topping50 nmbd[769]: [2026/01/09 15:22:13.480865, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 09 15:22:13 volumio-topping50 nmbd[769]: started asyncdns process 813 Jan 09 15:22:13 volumio-topping50 nmbd[769]: [2026/01/09 15:22:13.482474, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 09 15:22:13 volumio-topping50 nmbd[769]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 09 15:22:13 volumio-topping50 nmbd[769]: [2026/01/09 15:22:13.482599, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 09 15:22:13 volumio-topping50 nmbd[769]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 09 15:22:13 volumio-topping50 sh[533]: eth0: carrier acquired Jan 09 15:22:13 volumio-topping50 dhcpcd[552]: eth0: carrier acquired Jan 09 15:22:13 volumio-topping50 sh[533]: DUID 00:01:00:01:2b:1f:b6:4a:e4:5f:01:5e:94:a5 Jan 09 15:22:13 volumio-topping50 sh[533]: eth0: IAID 01:5e:94:a4 Jan 09 15:22:13 volumio-topping50 sh[533]: eth0: adding address fe80::e65f:1ff:fe5e:94a4 Jan 09 15:22:13 volumio-topping50 sh[533]: ipv6_addaddr1: Permission denied Jan 09 15:22:13 volumio-topping50 dhcpcd[552]: DUID 00:01:00:01:2b:1f:b6:4a:e4:5f:01:5e:94:a5 Jan 09 15:22:13 volumio-topping50 dhcpcd[552]: eth0: IAID 01:5e:94:a4 Jan 09 15:22:13 volumio-topping50 dhcpcd[552]: eth0: adding address fe80::e65f:1ff:fe5e:94a4 Jan 09 15:22:13 volumio-topping50 dhcpcd[552]: ipv6_addaddr1: Permission denied Jan 09 15:22:13 volumio-topping50 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Jan 09 15:22:13 volumio-topping50 ntpd[795]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 09 15:22:13 volumio-topping50 ifplugd(eth0)[799]: Link beat detected. Jan 09 15:22:13 volumio-topping50 wireless.js[603]: Cleaning previous... Jan 09 15:22:13 volumio-topping50 sudo[822]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 09 15:22:13 volumio-topping50 sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:13 volumio-topping50 ifplugd(eth0)[799]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 09 15:22:13 volumio-topping50 sudo[822]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:13 volumio-topping50 sudo[825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 09 15:22:13 volumio-topping50 sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:13 volumio-topping50 sh[533]: eth0: soliciting an IPv6 router Jan 09 15:22:13 volumio-topping50 dhcpcd[552]: eth0: soliciting an IPv6 router Jan 09 15:22:14 volumio-topping50 sh[533]: eth0: soliciting a DHCP lease Jan 09 15:22:14 volumio-topping50 dhcpcd[552]: eth0: soliciting a DHCP lease Jan 09 15:22:14 volumio-topping50 ifplugd(eth0)[799]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 09 15:22:14 volumio-topping50 sudo[825]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:14 volumio-topping50 wireless.js[603]: Stopped aP Jan 09 15:22:14 volumio-topping50 wireless.js[603]: Wireless Networking DISABLED, not starting wireless flow Jan 09 15:22:14 volumio-topping50 systemd[1]: wireless.service: Succeeded. Jan 09 15:22:14 volumio-topping50 systemd[1]: Started Wireless Services. Jan 09 15:22:14 volumio-topping50 systemd[1]: Started Volumio Backend Module. Jan 09 15:22:14 volumio-topping50 systemd[1]: Started Volumio Cpu Tweaker. Jan 09 15:22:14 volumio-topping50 volumio-cpu-tweak[838]: Setting RT Priority for mpd Jan 09 15:22:14 volumio-topping50 volumio-cpu-tweak[838]: Setting MPD Affinity Jan 09 15:22:14 volumio-topping50 volumio-cpu-tweak[838]: pid 810's current affinity mask: f Jan 09 15:22:14 volumio-topping50 volumio-cpu-tweak[838]: pid 810's new affinity mask: 3 Jan 09 15:22:14 volumio-topping50 volumio-cpu-tweak[838]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 09 15:22:14 volumio-topping50 volumio-cpu-tweak[838]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 09 15:22:14 volumio-topping50 ntpd[795]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 09 15:22:14 volumio-topping50 systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 09 15:22:15 volumio-topping50 mpd[810]: Jan 09 15:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 09 15:22:15 volumio-topping50 systemd[1]: Started Music Player Daemon. Jan 09 15:22:15 volumio-topping50 mpd_monitor.sh[598]: MPD Monitor Service: MPD restarted due to no mpc output. Jan 09 15:22:15 volumio-topping50 ntpd[795]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 09 15:22:16 volumio-topping50 systemd[1]: systemd-rfkill.service: Succeeded. Jan 09 15:22:16 volumio-topping50 volumio[837]: info: ------------------------------------------- Jan 09 15:22:16 volumio-topping50 volumio[837]: info: ----- Volumio3 ---- Jan 09 15:22:16 volumio-topping50 volumio[837]: info: ------------------------------------------- Jan 09 15:22:16 volumio-topping50 volumio[837]: info: ----- System startup ---- Jan 09 15:22:16 volumio-topping50 volumio[837]: info: ------------------------------------------- Jan 09 15:22:16 volumio-topping50 ntpd[795]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 09 15:22:17 volumio-topping50 volumio-remote-updater[626]: [2026-01-09 15:22:17] [connect] Successful connection Jan 09 15:22:17 volumio-topping50 volumio-time-update[610]: volumio-time-update-util: Fetching time from Volumio... Jan 09 15:22:17 volumio-topping50 volumio-time-update[610]: volumio-time-update-util: Date not found in response Jan 09 15:22:17 volumio-topping50 volumio-time-update[610]: volumio-time-update-util: Retrying in 5 seconds... Jan 09 15:22:17 volumio-topping50 volumio[837]: info: MYVOLUMIO Environment detected Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Plugin folders cleanup Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning into folder /volumio/app/plugins/ Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning category audio_interface Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning category miscellanea Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning category music_service Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning category plugins.json Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning category system_controller Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning category user_interface Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning into folder /data/plugins/ Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning category music_service Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Scanning category user_interface Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Plugin folders cleanup completed Jan 09 15:22:17 volumio-topping50 volumio[837]: info: ------------------------------------------- Jan 09 15:22:17 volumio-topping50 volumio[837]: info: ----- Core plugins startup ---- Jan 09 15:22:17 volumio-topping50 volumio[837]: info: ------------------------------------------- Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Loading plugins from folder /volumio/app/plugins/ Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Adding plugin upnp to MyMusic Plugins Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Loading plugins from folder /data/plugins/ Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Loading plugin "system"... Jan 09 15:22:17 volumio-topping50 volumio[837]: info: Loading plugin "appearance"... Jan 09 15:22:17 volumio-topping50 bthelper[723]: Changing power off succeeded Jan 09 15:22:17 volumio-topping50 bthelper[723]: [CHG] Controller E4:5F:01:5E:94:A6 Class: 0x0000041c Jan 09 15:22:17 volumio-topping50 bthelper[723]: Changing power on succeeded Jan 09 15:22:17 volumio-topping50 sh[533]: eth0: offered 192.168.213.155 from 192.168.213.1 Jan 09 15:22:17 volumio-topping50 dhcpcd[552]: eth0: offered 192.168.213.155 from 192.168.213.1 Jan 09 15:22:17 volumio-topping50 sh[533]: eth0: probing address 192.168.213.155/24 Jan 09 15:22:17 volumio-topping50 dhcpcd[552]: eth0: probing address 192.168.213.155/24 Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "network"... Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Refreshing Cached IP Addresses Jan 09 15:22:18 volumio-topping50 sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 09 15:22:18 volumio-topping50 sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:18 volumio-topping50 sudo[884]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:18 volumio-topping50 sudo[886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 09 15:22:18 volumio-topping50 sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "services"... Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "alsa_controller"... Jan 09 15:22:18 volumio-topping50 sudo[886]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:18 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "wizard"... Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "networkfs"... Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Starting Udev Watcher for removable devices Jan 09 15:22:18 volumio-topping50 sudo[909]: 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 Jan 09 15:22:18 volumio-topping50 sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Ignoring mount for partition: boot Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Ignoring mount for partition: volumio Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Ignoring mount for partition: volumio_data Jan 09 15:22:18 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "volumio_command_line_client"... Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "upnp"... Jan 09 15:22:18 volumio-topping50 volumio[837]: info: [1767993738656] Starting Upmpd Daemon Jan 09 15:22:18 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "my_music"... Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "mpd"... Jan 09 15:22:18 volumio-topping50 sudo[909]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:18 volumio-topping50 kernel: Key type cifs.spnego registered Jan 09 15:22:18 volumio-topping50 kernel: Key type cifs.idmap registered Jan 09 15:22:18 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. Jan 09 15:22:18 volumio-topping50 kernel: CIFS: Attempting to mount //192.168.213.73/USB Jan 09 15:22:18 volumio-topping50 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 09 15:22:18 volumio-topping50 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Jan 09 15:22:18 volumio-topping50 volumio[837]: info: Loading plugin "upnp_browser"... Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Loading plugin "alarm-clock"... Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Loading plugin "airplay_emulation"... Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Starting Shairport Sync Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Loading plugin "last_100"... Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Loading plugin "webradio"... Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Loading plugin "i2s_dacs"... Jan 09 15:22:19 volumio-topping50 volumio[837]: info: I2S DAC not set, start Auto-detection Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Loading plugin "volumiodiscovery"... Jan 09 15:22:19 volumio-topping50 volumio[837]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 09 15:22:19 volumio-topping50 volumio[837]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 09 15:22:19 volumio-topping50 node[837]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 09 15:22:19 volumio-topping50 volumio[837]: *** WARNING *** For more information see Jan 09 15:22:19 volumio-topping50 volumio[837]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 09 15:22:19 volumio-topping50 volumio[837]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 09 15:22:19 volumio-topping50 volumio[837]: *** WARNING *** For more information see Jan 09 15:22:19 volumio-topping50 node[837]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 09 15:22:19 volumio-topping50 node[837]: *** WARNING *** For more information see Jan 09 15:22:19 volumio-topping50 node[837]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 09 15:22:19 volumio-topping50 node[837]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 09 15:22:19 volumio-topping50 node[837]: *** WARNING *** For more information see Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Discovery: Started advertising with name: Volumio Topping50 Jan 09 15:22:19 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 09 15:22:19 volumio-topping50 volumio[837]: info: Loading plugin "spop"... Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Loading plugin "outputs"... Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Loading plugin "albumart"... Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Plugin example_plugin is not enabled Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Loading plugin "inputs"... Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Loading plugin "updater_comm"... Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Plugin mpdemulation is not enabled Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Loading plugin "rest_api"... Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Loading plugin "websocket"... Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Starting Socket.io Server version 2.3.0 Jan 09 15:22:20 volumio-topping50 volumio[837]: info: Loading plugin "lastfm"... Jan 09 15:22:20 volumio-topping50 volumio[837]: Forking 3 albumart workers Jan 09 15:22:21 volumio-topping50 volumio[837]: Starting albumart workers Jan 09 15:22:21 volumio-topping50 volumio[837]: Starting albumart workers Jan 09 15:22:21 volumio-topping50 volumio[837]: info: Loading i18n strings for locale en Jan 09 15:22:21 volumio-topping50 volumio[837]: Starting albumart workers Jan 09 15:22:21 volumio-topping50 volumio[837]: Updating browse sources language Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::initPlayerControls Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: Express server listening on port 3000 Jan 09 15:22:21 volumio-topping50 volumio[837]: [Metrics] WebUI: 5s 551.28ms Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreStateMachine::resetVolumioState Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreStateMachine::getcurrentVolume Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioRetrievevolume Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 15:22:21 volumio-topping50 volumio[837]: info: Volumio Network Manager: Network status updated: 0 Jan 09 15:22:21 volumio-topping50 volumio[837]: info: Cannot mount NAS USB at system boot, trial number 1 ,retrying in 5 seconds Jan 09 15:22:21 volumio-topping50 volumio-remote-updater[626]: [2026-01-09 15:22:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1767993737 101 Jan 09 15:22:21 volumio-topping50 volumio[837]: 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 Jan 09 15:22:21 volumio-topping50 volumio[837]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 09 15:22:21 volumio-topping50 volumio[837]: info: Reloading queue from file Jan 09 15:22:21 volumio-topping50 volumio[837]: info: VolumeController:: Volume=100 Mute =false Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreStateMachine::pushState Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CorePlayQueue::getTrack 0 Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioPushState Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreStateMachine::updateTrackBlock Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CorePlayQueue::getTrackBlock Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioRetrievevolume Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreStateMachine::setRepeat false single undefined Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreStateMachine::pushState Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CorePlayQueue::getTrack 0 Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioPushState Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreStateMachine::setRandom false Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreStateMachine::pushState Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CorePlayQueue::getTrack 0 Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioPushState Jan 09 15:22:21 volumio-topping50 volumio[837]: info: Setting Device type: Raspberry PI Jan 09 15:22:21 volumio-topping50 volumio[837]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 09 15:22:21 volumio-topping50 volumio[837]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jan 09 15:22:21 volumio-topping50 volumio[837]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 09 15:22:21 volumio-topping50 volumio[837]: info: VolumeController:: Volume=100 Mute =false Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreStateMachine::pushState Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CorePlayQueue::getTrack 0 Jan 09 15:22:21 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioPushState Jan 09 15:22:21 volumio-topping50 volumio[837]: info: Completed loading Core Plugins Jan 09 15:22:21 volumio-topping50 volumio[837]: info: Preparing to generate the ALSA configuration file Jan 09 15:22:21 volumio-topping50 volumio[837]: info: Asound.conf file written Jan 09 15:22:21 volumio-topping50 sudo[988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 09 15:22:21 volumio-topping50 sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:21 volumio-topping50 sudo[988]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:21 volumio-topping50 volumio[837]: info: Output device has changed, restarting MPD Jan 09 15:22:22 volumio-topping50 volumio[837]: info: Output device has changed, restarting Shairport Sync Jan 09 15:22:22 volumio-topping50 sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:22 volumio-topping50 sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 15:22:22 volumio-topping50 sudo[993]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:22 volumio-topping50 sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 09 15:22:22 volumio-topping50 sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:22 volumio-topping50 systemd[1]: Stopping Music Player Daemon... Jan 09 15:22:22 volumio-topping50 volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 09 15:22:22 volumio-topping50 volumio[837]: info: ___________ START PLUGINS ___________ Jan 09 15:22:22 volumio-topping50 volumio[837]: info: ControllerMpd::onStart: Initializing MPD Jan 09 15:22:22 volumio-topping50 volumio[837]: info: Creating MPD Configuration file Jan 09 15:22:22 volumio-topping50 sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 09 15:22:22 volumio-topping50 sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 09 15:22:22 volumio-topping50 volumio[837]: info: [1767993742089] CoreMusicLibrary::Adding element Media Servers Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 09 15:22:22 volumio-topping50 sudo[1002]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:22 volumio-topping50 sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 09 15:22:22 volumio-topping50 sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio-time-update[610]: volumio-time-update-util: Fetching time from Volumio... Jan 09 15:22:22 volumio-topping50 volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio-time-update[610]: volumio-time-update-util: Date not found in response Jan 09 15:22:22 volumio-topping50 volumio-time-update[610]: volumio-time-update-util: Retrying in 5 seconds... Jan 09 15:22:22 volumio-topping50 volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 09 15:22:22 volumio-topping50 volumio[837]: info: [1767993742179] CoreMusicLibrary::Adding element Last_100 Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 09 15:22:22 volumio-topping50 volumio[837]: info: [1767993742182] CoreMusicLibrary::Adding element Webradio Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 09 15:22:22 volumio-topping50 systemd[1]: mpd.service: Succeeded. Jan 09 15:22:22 volumio-topping50 systemd[1]: Stopped Music Player Daemon. Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 09 15:22:22 volumio-topping50 volumio[837]: info: Initializing BBC Radios Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio[837]: info: Checking for old spotify connect plugin installed Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 09 15:22:22 volumio-topping50 volumio[837]: info: [1767993742238] CoreMusicLibrary::Adding element LastFM Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 09 15:22:22 volumio-topping50 volumio[837]: Cannot find translation for source LastFM Jan 09 15:22:22 volumio-topping50 volumio[837]: info: [LastFM] scrobbler initiated! Jan 09 15:22:22 volumio-topping50 volumio[837]: info: [LastFM] extended logging: false Jan 09 15:22:22 volumio-topping50 volumio[837]: info: [LastFM] try scrobble stream/radio plays: true Jan 09 15:22:22 volumio-topping50 volumio[837]: info: [LastFM] Left init routine Jan 09 15:22:22 volumio-topping50 volumio[837]: info: [LastFM] Socket already connected: true Jan 09 15:22:22 volumio-topping50 volumio[837]: info: Volumio Calling Home Jan 09 15:22:22 volumio-topping50 sh[533]: eth0: leased 192.168.213.155 for 86400 seconds Jan 09 15:22:22 volumio-topping50 dhcpcd[552]: eth0: leased 192.168.213.155 for 86400 seconds Jan 09 15:22:22 volumio-topping50 sh[533]: eth0: adding route to 192.168.213.0/24 Jan 09 15:22:22 volumio-topping50 sh[533]: eth0: adding default route via 192.168.213.1 Jan 09 15:22:22 volumio-topping50 systemd[1]: Starting Music Player Daemon... Jan 09 15:22:22 volumio-topping50 avahi-daemon[607]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.213.155. Jan 09 15:22:22 volumio-topping50 avahi-daemon[607]: New relevant interface eth0.IPv4 for mDNS. Jan 09 15:22:22 volumio-topping50 dhcpcd[552]: eth0: adding route to 192.168.213.0/24 Jan 09 15:22:22 volumio-topping50 dhcpcd[552]: eth0: adding default route via 192.168.213.1 Jan 09 15:22:22 volumio-topping50 avahi-daemon[607]: Registering new address record for 192.168.213.155 on eth0.IPv4. Jan 09 15:22:22 volumio-topping50 sh[533]: forked to background, child pid 1053 Jan 09 15:22:22 volumio-topping50 dhcpcd[552]: forked to background, child pid 1053 Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 09 15:22:22 volumio-topping50 volumio[837]: [SpotifyConnect] Creating VLS config file Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 15:22:22 volumio-topping50 volumio[837]: [SpotifyConnect] Starting metadata listener Jan 09 15:22:22 volumio-topping50 sudo[1018]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 09 15:22:22 volumio-topping50 sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:22 volumio-topping50 sudo[1018]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:22 volumio-topping50 sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jan 09 15:22:22 volumio-topping50 sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 09 15:22:22 volumio-topping50 systemd[1]: Started Volspotconnect2 Daemon. Jan 09 15:22:22 volumio-topping50 sudo[1056]: pam_unix(sudo:session): session closed for user root Jan 09 15:22:22 volumio-topping50 volumio[837]: Unhandled rejection Error: No sockets available, cannot start. Jan 09 15:22:22 volumio-topping50 volumio[837]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jan 09 15:22:22 volumio-topping50 volumio[837]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jan 09 15:22:22 volumio-topping50 volumio[837]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jan 09 15:22:22 volumio-topping50 volumio[837]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jan 09 15:22:22 volumio-topping50 volumio[837]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jan 09 15:22:22 volumio-topping50 volumio[837]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jan 09 15:22:22 volumio-topping50 volumio[837]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jan 09 15:22:22 volumio-topping50 volumio[837]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jan 09 15:22:22 volumio-topping50 volumio[837]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jan 09 15:22:22 volumio-topping50 volumio[837]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jan 09 15:22:22 volumio-topping50 volumio[837]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jan 09 15:22:22 volumio-topping50 volumio[837]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 09 15:22:22 volumio-topping50 volumio[837]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 09 15:22:22 volumio-topping50 volumio[837]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jan 09 15:22:22 volumio-topping50 volumio[837]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jan 09 15:22:22 volumio-topping50 volumio[837]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 09 15:22:22 volumio-topping50 volumio[837]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 15:22:22 volumio-topping50 volumio[837]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Jan 09 15:22:22 volumio-topping50 volumio[837]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Jan 09 15:22:22 volumio-topping50 volumio[837]: errno: -16, Jan 09 15:22:22 volumio-topping50 volumio[837]: code: 'EBUSY', Jan 09 15:22:22 volumio-topping50 volumio[837]: syscall: 'getaddrinfo', Jan 09 15:22:22 volumio-topping50 volumio[837]: hostname: 'ws.audioscrobbler.com' Jan 09 15:22:22 volumio-topping50 volumio[837]: } Jan 09 15:22:22 volumio-topping50 volumio[837]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 15:22:22 volumio-topping50 ifplugd(eth0)[799]: client: ifup: interface eth0 already configured Jan 09 15:22:22 volumio-topping50 sh[533]: eth0=eth0 Jan 09 15:22:22 volumio-topping50 volumio[1065]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jan 09 15:22:22 volumio-topping50 volumio[1065]: Reading Config from "/tmp/volspotify.toml" Jan 09 15:22:22 volumio-topping50 volumio[1065]: Malformed config key: missing field `Authentication` Jan 09 15:22:22 volumio-topping50 systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Jan 09 15:22:22 volumio-topping50 systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Jan 09 15:22:22 volumio-topping50 ifplugd(eth0)[799]: Program executed successfully. Jan 09 15:22:22 volumio-topping50 sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-09 15:21 Jan 09 15:22:22 volumio-topping50 sudo[1095]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"