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