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