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