-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2026-02-14 17:25:29 CET. -- Feb 14 17:25:10 amp-1 kernel: i2c_dev: i2c /dev entries driver Feb 14 17:25:10 amp-1 fake-hwclock[319]: Sat Feb 14 16:25:10 UTC 2026 Feb 14 17:25:10 amp-1 systemd[1]: Mounted POSIX Message Queue File System. Feb 14 17:25:10 amp-1 systemd[1]: Mounted Kernel Debug File System. Feb 14 17:25:10 amp-1 systemd[1]: Started Restore / save the current clock. Feb 14 17:25:10 amp-1 systemd[1]: Mounted RPC Pipe File System. Feb 14 17:25:10 amp-1 systemd[1]: Started Remount Root and Kernel File Systems. Feb 14 17:25:10 amp-1 systemd[1]: Started Load Kernel Modules. Feb 14 17:25:10 amp-1 systemd[1]: Mounting Kernel Configuration File System... Feb 14 17:25:10 amp-1 systemd[1]: Mounting FUSE Control File System... Feb 14 17:25:10 amp-1 systemd[1]: Starting Apply Kernel Variables... Feb 14 17:25:10 amp-1 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 14 17:25:10 amp-1 systemd[1]: Starting Load/Save Random Seed... Feb 14 17:25:10 amp-1 systemd[1]: Starting Create System Users... Feb 14 17:25:10 amp-1 systemd[1]: Mounted Kernel Configuration File System. Feb 14 17:25:10 amp-1 systemd[1]: Mounted FUSE Control File System. Feb 14 17:25:10 amp-1 systemd[1]: dynamicswap.service: Succeeded. Feb 14 17:25:10 amp-1 systemd[1]: Started Apply Kernel Variables. Feb 14 17:25:10 amp-1 systemd[1]: Started Load/Save Random Seed. Feb 14 17:25:10 amp-1 systemd[1]: Started udev Coldplug all Devices. Feb 14 17:25:10 amp-1 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Feb 14 17:25:10 amp-1 systemd[1]: Started Create System Users. Feb 14 17:25:10 amp-1 systemd[1]: Starting Create Static Device Nodes in /dev... Feb 14 17:25:10 amp-1 systemd[1]: Started Create Static Device Nodes in /dev. Feb 14 17:25:10 amp-1 systemd[1]: Reached target Local File Systems (Pre). Feb 14 17:25:10 amp-1 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 14 17:25:10 amp-1 systemd[1]: Mounting /tmp... Feb 14 17:25:10 amp-1 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 14 17:25:10 amp-1 systemd[1]: Mounting /var/log... Feb 14 17:25:10 amp-1 systemd[1]: Mounting /var/spool/cups... Feb 14 17:25:10 amp-1 systemd[1]: Starting udev Kernel Device Manager... Feb 14 17:25:10 amp-1 systemd[1]: Mounted /tmp. Feb 14 17:25:10 amp-1 systemd[1]: Mounted /var/log. Feb 14 17:25:10 amp-1 systemd[1]: Mounted /var/spool/cups. Feb 14 17:25:10 amp-1 systemd[1]: Mounting /var/spool/cups/tmp... Feb 14 17:25:10 amp-1 systemd[1]: Starting Flush Journal to Persistent Storage... Feb 14 17:25:10 amp-1 systemd[1]: Mounted /var/spool/cups/tmp. Feb 14 17:25:10 amp-1 systemd-udevd[354]: Network interface NamePolicy= disabled on kernel command line, ignoring. Feb 14 17:25:10 amp-1 systemd-journald[320]: Runtime journal (/run/log/journal/c1d531165394c79721acb5a765d65e88) is 11.2M, max 30.0M, 18.7M free. Feb 14 17:25:10 amp-1 systemd[1]: Started udev Kernel Device Manager. Feb 14 17:25:10 amp-1 systemd[1]: Starting Show Plymouth Boot Screen... Feb 14 17:25:10 amp-1 systemd[1]: Started Flush Journal to Persistent Storage. Feb 14 17:25:10 amp-1 systemd[1]: plymouth-start.service: Succeeded. Feb 14 17:25:10 amp-1 systemd[1]: Started Show Plymouth Boot Screen. Feb 14 17:25:10 amp-1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 14 17:25:10 amp-1 systemd[1]: Reached target Local Encrypted Volumes. Feb 14 17:25:10 amp-1 systemd[1]: Reached target Paths. Feb 14 17:25:10 amp-1 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 14 17:25:10 amp-1 systemd-udevd[362]: Using default interface naming scheme 'v240'. Feb 14 17:25:10 amp-1 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Feb 14 17:25:10 amp-1 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 17:25:10 amp-1 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Feb 14 17:25:10 amp-1 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Feb 14 17:25:10 amp-1 kernel: [vc_sm_connected_init]: start Feb 14 17:25:10 amp-1 kernel: [vc_sm_connected_init]: installed successfully Feb 14 17:25:10 amp-1 systemd-udevd[387]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 14 17:25:10 amp-1 systemd-udevd[362]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 14 17:25:10 amp-1 kernel: mc: Linux media interface: v0.10 Feb 14 17:25:11 amp-1 kernel: videodev: Linux video capture interface: v2.00 Feb 14 17:25:11 amp-1 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 17:25:11 amp-1 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 17:25:11 amp-1 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Feb 14 17:25:11 amp-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Feb 14 17:25:11 amp-1 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 17:25:11 amp-1 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 17:25:11 amp-1 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 14 17:25:11 amp-1 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Feb 14 17:25:12 amp-1 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 14 17:25:12 amp-1 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Feb 14 17:25:12 amp-1 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Feb 14 17:25:12 amp-1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Feb 14 17:25:12 amp-1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Feb 14 17:25:12 amp-1 kernel: Bluetooth: Core ver 2.22 Feb 14 17:25:12 amp-1 kernel: NET: Registered PF_BLUETOOTH protocol family Feb 14 17:25:12 amp-1 kernel: Bluetooth: HCI device and connection manager initialized Feb 14 17:25:12 amp-1 kernel: Bluetooth: HCI socket layer initialized Feb 14 17:25:12 amp-1 kernel: Bluetooth: L2CAP socket layer initialized Feb 14 17:25:12 amp-1 kernel: Bluetooth: SCO socket layer initialized Feb 14 17:25:12 amp-1 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Feb 14 17:25:12 amp-1 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Feb 14 17:25:12 amp-1 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 14 17:25:12 amp-1 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Feb 14 17:25:12 amp-1 kernel: Bluetooth: HCI UART driver ver 2.3 Feb 14 17:25:12 amp-1 kernel: Bluetooth: HCI UART protocol H4 registered Feb 14 17:25:12 amp-1 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 14 17:25:12 amp-1 kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 14 17:25:12 amp-1 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Feb 14 17:25:12 amp-1 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Feb 14 17:25:12 amp-1 kernel: uart-pl011 fe201000.serial: no DMA platform data Feb 14 17:25:12 amp-1 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Feb 14 17:25:12 amp-1 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 14 17:25:12 amp-1 kernel: usbcore: registered new interface driver brcmfmac Feb 14 17:25:12 amp-1 kernel: Bluetooth: hci0: BCM: chip id 107 Feb 14 17:25:12 amp-1 kernel: Bluetooth: hci0: BCM: features 0x2f Feb 14 17:25:12 amp-1 kernel: Bluetooth: hci0: BCM4345C0 Feb 14 17:25:12 amp-1 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Feb 14 17:25:13 amp-1 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Feb 14 17:25:13 amp-1 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Feb 14 17:25:13 amp-1 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 14 17:25:13 amp-1 systemd-udevd[361]: Using default interface naming scheme 'v240'. Feb 14 17:25:13 amp-1 systemd-udevd[361]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 14 17:25:13 amp-1 kernel: Bluetooth: hci0: BCM: features 0x2f Feb 14 17:25:13 amp-1 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Feb 14 17:25:13 amp-1 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Feb 14 17:25:14 amp-1 kernel: alsactl[484]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Feb 14 17:25:14 amp-1 systemd[1]: Found device /dev/disk/by-uuid/F102-DE29. Feb 14 17:25:14 amp-1 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Feb 14 17:25:15 amp-1 systemd[1]: Created slice system-bthelper.slice. Feb 14 17:25:15 amp-1 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Feb 14 17:25:15 amp-1 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 14 17:25:15 amp-1 systemd[1]: Starting Show Plymouth Boot Screen... Feb 14 17:25:15 amp-1 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Feb 14 17:25:15 amp-1 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 14 17:25:15 amp-1 systemd[1]: Starting File System Check on /dev/disk/by-uuid/F102-DE29... Feb 14 17:25:15 amp-1 systemd[1]: Started Helper to synchronize boot up for ifupdown. Feb 14 17:25:15 amp-1 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Feb 14 17:25:15 amp-1 systemd[1]: plymouth-start.service: Succeeded. Feb 14 17:25:15 amp-1 systemd[1]: Started Show Plymouth Boot Screen. Feb 14 17:25:15 amp-1 systemd[1]: Started File System Check Daemon to report status. Feb 14 17:25:15 amp-1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 14 17:25:15 amp-1 systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 14 17:25:15 amp-1 systemd[1]: Started Load/Save RF Kill Switch Status. Feb 14 17:25:15 amp-1 systemd-fsck[502]: fsck.fat 4.1 (2017-01-24) Feb 14 17:25:15 amp-1 systemd-fsck[502]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Feb 14 17:25:15 amp-1 systemd-fsck[502]: Automatically removing dirty bit. Feb 14 17:25:15 amp-1 systemd-fsck[502]: Performing changes. Feb 14 17:25:15 amp-1 systemd-fsck[502]: /dev/mmcblk0p1: 485 files, 42222/46774 clusters Feb 14 17:25:15 amp-1 systemd[1]: Started File System Check on /dev/disk/by-uuid/F102-DE29. Feb 14 17:25:15 amp-1 systemd[1]: Mounting /boot... Feb 14 17:25:15 amp-1 systemd[1]: Mounted /boot. Feb 14 17:25:15 amp-1 systemd[1]: Reached target Local File Systems. Feb 14 17:25:15 amp-1 systemd[1]: Starting Create Volatile Files and Directories... Feb 14 17:25:15 amp-1 systemd[1]: Started ifup for eth0. Feb 14 17:25:15 amp-1 systemd[1]: Starting Raise network interfaces... Feb 14 17:25:15 amp-1 systemd[1]: Started ifup for wlan0. Feb 14 17:25:15 amp-1 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Feb 14 17:25:15 amp-1 systemd[1]: Starting Preprocess NFS configuration... Feb 14 17:25:15 amp-1 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 14 17:25:15 amp-1 systemd[1]: Received SIGRTMIN+20 from PID 209 (plymouthd). Feb 14 17:25:15 amp-1 systemd[1]: plymouth-read-write.service: Succeeded. Feb 14 17:25:15 amp-1 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 14 17:25:15 amp-1 systemd[1]: nfs-config.service: Succeeded. Feb 14 17:25:15 amp-1 systemd[1]: Started Preprocess NFS configuration. Feb 14 17:25:15 amp-1 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Feb 14 17:25:15 amp-1 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Feb 14 17:25:15 amp-1 systemd[1]: Reached target NFS client services. Feb 14 17:25:15 amp-1 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Feb 14 17:25:15 amp-1 systemd[1]: Started Create Volatile Files and Directories. Feb 14 17:25:15 amp-1 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 14 17:25:15 amp-1 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 14 17:25:15 amp-1 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Feb 14 17:25:15 amp-1 kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 14 17:25:15 amp-1 systemd[1]: Starting RPC bind portmap service... Feb 14 17:25:15 amp-1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 14 17:25:15 amp-1 systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 14 17:25:15 amp-1 systemd[1]: Reached target System Initialization. Feb 14 17:25:15 amp-1 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 14 17:25:15 amp-1 systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2026-02-17 06:15:46 CET as it is in the future. Feb 14 17:25:15 amp-1 systemd[1]: Started Daily apt download activities. Feb 14 17:25:15 amp-1 systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 14 17:25:16 amp-1 systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 14 17:25:16 amp-1 systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2026-02-17 06:15:46 CET as it is in the future. Feb 14 17:25:16 amp-1 systemd[1]: Started Daily apt upgrade and clean activities. Feb 14 17:25:16 amp-1 systemd[1]: Reached target Timers. Feb 14 17:25:16 amp-1 systemd[1]: Listening on triggerhappy.socket. Feb 14 17:25:16 amp-1 systemd[1]: Reached target Sockets. Feb 14 17:25:16 amp-1 systemd[1]: Reached target Basic System. Feb 14 17:25:16 amp-1 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 14 17:25:16 amp-1 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Feb 14 17:25:16 amp-1 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 14 17:25:16 amp-1 systemd[1]: Starting dhcpcd on all interfaces... Feb 14 17:25:16 amp-1 systemd[1]: Started Volumio Log Rotation Service. Feb 14 17:25:16 amp-1 systemd[1]: Starting Volumio Time Update Utility... Feb 14 17:25:16 amp-1 systemd[1]: Started UPnP Renderer front-end to MPD. Feb 14 17:25:16 amp-1 systemd[1]: Started D-Bus System Message Bus. Feb 14 17:25:16 amp-1 systemd[1]: Starting WPA supplicant... Feb 14 17:25:16 amp-1 volumio-time-update[605]: volumio-time-update-util: Fetching time from Volumio... Feb 14 17:25:16 amp-1 systemd[1]: Started Volumio Iptables Module. Feb 14 17:25:16 amp-1 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Feb 14 17:25:16 amp-1 systemd[1]: Starting triggerhappy global hotkey daemon... Feb 14 17:25:16 amp-1 dhcpcd[599]: Not running dhcpcd because /etc/network/interfaces Feb 14 17:25:16 amp-1 dhcpcd[599]: defines some interfaces that will use a Feb 14 17:25:16 amp-1 dhcpcd[599]: DHCP client or static address Feb 14 17:25:16 amp-1 systemd[1]: Started volumio-remote-updater.service. Feb 14 17:25:16 amp-1 systemd[1]: Starting Login Service... Feb 14 17:25:16 amp-1 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Feb 14 17:25:16 amp-1 systemd[1]: Starting Wireless Services... Feb 14 17:25:16 amp-1 systemd[1]: Started Manage Sound Card State (restore and store). Feb 14 17:25:16 amp-1 systemd[1]: Starting Save/Restore Sound Card State... Feb 14 17:25:16 amp-1 alsactl[627]: alsactl 1.1.8 daemon started Feb 14 17:25:16 amp-1 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Feb 14 17:25:16 amp-1 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Feb 14 17:25:16 amp-1 systemd[1]: Failed to start dhcpcd on all interfaces. Feb 14 17:25:16 amp-1 ifup[512]: ifup: waiting for lock on /run/network/ifstate.wlan0 Feb 14 17:25:16 amp-1 thd[623]: Unable to parse trigger line: Feb 14 17:25:16 amp-1 thd[623]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Feb 14 17:25:16 amp-1 thd[623]: Unable to parse trigger line: Feb 14 17:25:16 amp-1 thd[623]: Found socket passed from systemd Feb 14 17:25:16 amp-1 systemd-logind[625]: New seat seat0. Feb 14 17:25:16 amp-1 avahi-daemon[595]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Feb 14 17:25:16 amp-1 avahi-daemon[595]: Successfully dropped root privileges. Feb 14 17:25:16 amp-1 avahi-daemon[595]: avahi-daemon 0.7 starting up. Feb 14 17:25:16 amp-1 sh[511]: eth0: waiting for carrier Feb 14 17:25:16 amp-1 dhcpcd[561]: eth0: waiting for carrier Feb 14 17:25:16 amp-1 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Feb 14 17:25:16 amp-1 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Feb 14 17:25:16 amp-1 systemd[1]: Started triggerhappy global hotkey daemon. Feb 14 17:25:16 amp-1 systemd[1]: Started RPC bind portmap service. Feb 14 17:25:16 amp-1 systemd[1]: hciuart.service: Succeeded. Feb 14 17:25:16 amp-1 avahi-daemon[595]: Successfully called chroot(). Feb 14 17:25:16 amp-1 systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 14 17:25:16 amp-1 avahi-daemon[595]: Successfully dropped remaining capabilities. Feb 14 17:25:16 amp-1 systemd[1]: Started Save/Restore Sound Card State. Feb 14 17:25:16 amp-1 wpa_supplicant[613]: Successfully initialized wpa_supplicant Feb 14 17:25:16 amp-1 systemd[1]: Started Login Service. Feb 14 17:25:16 amp-1 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 14 17:25:16 amp-1 systemd[1]: Started WPA supplicant. Feb 14 17:25:16 amp-1 systemd[1]: Reached target Sound Card. Feb 14 17:25:16 amp-1 avahi-daemon[595]: Loading service file /services/volumio.service. Feb 14 17:25:16 amp-1 sh[514]: wlan0=wlan0 Feb 14 17:25:16 amp-1 systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 14 17:25:16 amp-1 avahi-daemon[595]: Network interface enumeration completed. Feb 14 17:25:16 amp-1 systemd[1]: Reached target Remote File Systems (Pre). Feb 14 17:25:16 amp-1 avahi-daemon[595]: Server startup complete. Host name is amp-1.local. Local service cookie is 645428816. Feb 14 17:25:16 amp-1 systemd[1]: Reached target Remote File Systems. Feb 14 17:25:16 amp-1 avahi-daemon[595]: Service "AMP-1" (/services/volumio.service) successfully established. Feb 14 17:25:16 amp-1 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 14 17:25:16 amp-1 systemd[1]: Reached target RPC Port Mapper. Feb 14 17:25:17 amp-1 systemd[1]: Started Raise network interfaces. Feb 14 17:25:17 amp-1 systemd[1]: Reached target Network. Feb 14 17:25:17 amp-1 systemd[1]: Starting Permit User Sessions... Feb 14 17:25:17 amp-1 systemd[1]: Reached target Network is Online. Feb 14 17:25:17 amp-1 systemd[1]: Starting LSB: Brings up/down network automatically... Feb 14 17:25:17 amp-1 systemd[1]: Starting Samba NMB Daemon... Feb 14 17:25:17 amp-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 14 17:25:17 amp-1 systemd[1]: Starting Network Time Service... Feb 14 17:25:17 amp-1 systemd[1]: Starting /etc/rc.local Compatibility... Feb 14 17:25:17 amp-1 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 14 17:25:17 amp-1 volumio-remote-updater[624]: Error: No active session Feb 14 17:25:17 amp-1 volumio-remote-updater[624]: [2026-02-14 17:25:17] [info] asio async_connect error: system:111 (Connection refused) Feb 14 17:25:17 amp-1 volumio-remote-updater[624]: [2026-02-14 17:25:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 14 17:25:17 amp-1 volumio-remote-updater[624]: [2026-02-14 17:25:17] [error] handle_connect error: Underlying Transport Error Feb 14 17:25:17 amp-1 systemd[1]: Started /etc/rc.local Compatibility. Feb 14 17:25:17 amp-1 volumio[608]: Could not open config: /tmp/upmpdcli.conf Feb 14 17:25:17 amp-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 14 17:25:17 amp-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 14 17:25:17 amp-1 systemd[1]: Started Permit User Sessions. Feb 14 17:25:17 amp-1 bthelper[665]: Raspberry Pi BDADDR already set Feb 14 17:25:17 amp-1 systemd[1]: Starting Hold until boot process finishes up... Feb 14 17:25:17 amp-1 volumio-time-update[605]: volumio-time-update-util: Date not found in response Feb 14 17:25:17 amp-1 volumio-time-update[605]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 17:25:17 amp-1 systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 14 17:25:17 amp-1 systemd[1]: Started Raspberry Pi bluetooth helper. Feb 14 17:25:17 amp-1 systemd[1]: Starting Bluetooth service... Feb 14 17:25:17 amp-1 systemd[1]: Received SIGRTMIN+21 from PID 209 (plymouthd). Feb 14 17:25:17 amp-1 systemd[1]: Received SIGRTMIN+21 from PID 209 (plymouthd). Feb 14 17:25:17 amp-1 systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 14 17:25:17 amp-1 systemd[1]: Started Hold until boot process finishes up. Feb 14 17:25:17 amp-1 systemd[1]: plymouth-quit.service: Succeeded. Feb 14 17:25:17 amp-1 systemd[1]: Started Terminate Plymouth Boot Screen. Feb 14 17:25:17 amp-1 systemd[1]: Started Getty on tty1. Feb 14 17:25:17 amp-1 systemd[1]: Reached target Login Prompts. Feb 14 17:25:17 amp-1 ifplugd(eth0)[743]: ifplugd 0.28 initializing. Feb 14 17:25:17 amp-1 ifplugd(eth0)[743]: Using interface eth0/DC:A6:32:41:FB:CF with driver (version: 6.6.62-v7l+) Feb 14 17:25:17 amp-1 ifplugd(eth0)[743]: Using detection mode: SIOCETHTOOL Feb 14 17:25:17 amp-1 ifplugd(eth0)[743]: Initialization complete, link beat not detected. Feb 14 17:25:17 amp-1 haveged[580]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Feb 14 17:25:17 amp-1 haveged[580]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Feb 14 17:25:17 amp-1 haveged[580]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0012 Feb 14 17:25:17 amp-1 haveged[580]: haveged: fills: 0, generated: 0 Feb 14 17:25:17 amp-1 systemd[1]: iptables.service: Succeeded. Feb 14 17:25:17 amp-1 loadcpufreq[686]: Loading cpufreq kernel modules...done (none). Feb 14 17:25:17 amp-1 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 14 17:25:17 amp-1 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 14 17:25:17 amp-1 ifplugd[694]: Network Interface Plugging Daemon...start eth0...done. Feb 14 17:25:17 amp-1 systemd[1]: Started LSB: Brings up/down network automatically. Feb 14 17:25:17 amp-1 ntpd[698]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 14 17:25:17 amp-1 ntpd[698]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Feb 14 17:25:17 amp-1 systemd[1]: Started Network Time Service. Feb 14 17:25:17 amp-1 ntpd[769]: proto: precision = 0.833 usec (-20) Feb 14 17:25:17 amp-1 cpufrequtils[763]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 14 17:25:17 amp-1 systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 14 17:25:17 amp-1 ntpd[769]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 14 17:25:17 amp-1 ntpd[769]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 14 17:25:17 amp-1 ntpd[769]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 414 days ago Feb 14 17:25:17 amp-1 ntpd[769]: Listen and drop on 0 v6wildcard [::]:123 Feb 14 17:25:17 amp-1 ntpd[769]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 14 17:25:17 amp-1 ntpd[769]: Listen normally on 2 lo 127.0.0.1:123 Feb 14 17:25:17 amp-1 ntpd[769]: Listening on routing socket on fd #19 for interface updates Feb 14 17:25:17 amp-1 ntpd[769]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 17:25:17 amp-1 ntpd[769]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 17:25:17 amp-1 bluetoothd[725]: Bluetooth daemon 5.83 Feb 14 17:25:17 amp-1 bluetoothd[725]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 14 17:25:17 amp-1 systemd[1]: Started Bluetooth service. Feb 14 17:25:17 amp-1 systemd[1]: Reached target Bluetooth. Feb 14 17:25:17 amp-1 bluetoothd[725]: Starting SDP server Feb 14 17:25:17 amp-1 bluetoothd[725]: Excluding (cli) hostname Feb 14 17:25:17 amp-1 bluetoothd[725]: Excluding (cli) scanparam Feb 14 17:25:17 amp-1 bluetoothd[725]: Excluding (cli) battery Feb 14 17:25:17 amp-1 bluetoothd[725]: Bluetooth management interface 1.22 initialized Feb 14 17:25:17 amp-1 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 14 17:25:17 amp-1 kernel: Bluetooth: BNEP filters: protocol multicast Feb 14 17:25:17 amp-1 kernel: Bluetooth: BNEP socket layer initialized Feb 14 17:25:17 amp-1 kernel: Bluetooth: MGMT ver 1.22 Feb 14 17:25:17 amp-1 bluetoothd[725]: Battery Provider Manager created Feb 14 17:25:17 amp-1 kernel: NET: Registered PF_ALG protocol family Feb 14 17:25:17 amp-1 kernel: cryptd: max_cpu_qlen set to 1000 Feb 14 17:25:18 amp-1 bluetoothd[725]: Failed to set privacy: Rejected (0x0b) Feb 14 17:25:18 amp-1 nmbd[704]: [2026/02/14 17:25:18.453494, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 14 17:25:18 amp-1 nmbd[704]: started asyncdns process 803 Feb 14 17:25:18 amp-1 nmbd[704]: [2026/02/14 17:25:18.455096, 0] ../lib/util/become_daemon.c:149(daemon_status) Feb 14 17:25:18 amp-1 nmbd[704]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 14 17:25:18 amp-1 nmbd[704]: [2026/02/14 17:25:18.455218, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Feb 14 17:25:18 amp-1 nmbd[704]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 14 17:25:18 amp-1 ntpd[769]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 14 17:25:18 amp-1 wireless.js[626]: Cleaning previous... Feb 14 17:25:18 amp-1 sudo[811]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 14 17:25:18 amp-1 sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:18 amp-1 sudo[811]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:18 amp-1 sudo[813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 14 17:25:18 amp-1 sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:19 amp-1 sudo[813]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:19 amp-1 wireless.js[626]: Stopped aP Feb 14 17:25:19 amp-1 wireless.js[626]: Wireless Networking DISABLED, not starting wireless flow Feb 14 17:25:19 amp-1 ntpd[769]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 14 17:25:19 amp-1 systemd[1]: wireless.service: Succeeded. Feb 14 17:25:19 amp-1 systemd[1]: Started Wireless Services. Feb 14 17:25:19 amp-1 systemd[1]: Started Volumio Backend Module. Feb 14 17:25:19 amp-1 systemd[1]: Started Volumio Cpu Tweaker. Feb 14 17:25:19 amp-1 volumio-cpu-tweak[822]: Setting RT Priority for mpd Feb 14 17:25:19 amp-1 volumio-cpu-tweak[822]: pid 35's current scheduling policy: SCHED_OTHER Feb 14 17:25:19 amp-1 volumio-cpu-tweak[822]: pid 35's current scheduling priority: 0 Feb 14 17:25:19 amp-1 volumio-cpu-tweak[822]: Setting MPD Affinity Feb 14 17:25:19 amp-1 volumio-cpu-tweak[822]: pid 3's current affinity mask: f Feb 14 17:25:19 amp-1 volumio-cpu-tweak[822]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 14 17:25:19 amp-1 volumio-cpu-tweak[822]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 14 17:25:19 amp-1 systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 14 17:25:20 amp-1 systemd[1]: systemd-rfkill.service: Succeeded. Feb 14 17:25:20 amp-1 ntpd[769]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 14 17:25:21 amp-1 volumio[821]: info: ------------------------------------------- Feb 14 17:25:21 amp-1 volumio[821]: info: ----- Volumio3 ---- Feb 14 17:25:21 amp-1 volumio[821]: info: ------------------------------------------- Feb 14 17:25:21 amp-1 volumio[821]: info: ----- System startup ---- Feb 14 17:25:21 amp-1 volumio[821]: info: ------------------------------------------- Feb 14 17:25:21 amp-1 ntpd[769]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 14 17:25:21 amp-1 volumio[821]: info: MYVOLUMIO Environment detected Feb 14 17:25:22 amp-1 volumio[821]: info: Plugin folders cleanup Feb 14 17:25:22 amp-1 volumio[821]: info: Scanning into folder /volumio/app/plugins/ Feb 14 17:25:22 amp-1 volumio[821]: info: Scanning category audio_interface Feb 14 17:25:22 amp-1 volumio[821]: info: Scanning category miscellanea Feb 14 17:25:22 amp-1 volumio[821]: info: Scanning category music_service Feb 14 17:25:22 amp-1 volumio[821]: info: Scanning category plugins.json Feb 14 17:25:22 amp-1 volumio[821]: info: Scanning category system_controller Feb 14 17:25:22 amp-1 volumio[821]: info: Scanning category user_interface Feb 14 17:25:22 amp-1 volumio[821]: info: Scanning into folder /data/plugins/ Feb 14 17:25:22 amp-1 volumio[821]: info: Scanning category music_service Feb 14 17:25:22 amp-1 volumio[821]: info: Plugin folders cleanup completed Feb 14 17:25:22 amp-1 volumio[821]: info: ------------------------------------------- Feb 14 17:25:22 amp-1 volumio[821]: info: ----- Core plugins startup ---- Feb 14 17:25:22 amp-1 volumio[821]: info: ------------------------------------------- Feb 14 17:25:22 amp-1 volumio[821]: info: Loading plugins from folder /volumio/app/plugins/ Feb 14 17:25:22 amp-1 volumio[821]: info: Adding plugin upnp to MyMusic Plugins Feb 14 17:25:22 amp-1 volumio[821]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 14 17:25:22 amp-1 volumio[821]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 14 17:25:22 amp-1 volumio[821]: info: Loading plugins from folder /data/plugins/ Feb 14 17:25:22 amp-1 volumio[821]: info: Loading plugin "system"... Feb 14 17:25:22 amp-1 volumio-remote-updater[624]: [2026-02-14 17:25:22] [connect] Successful connection Feb 14 17:25:22 amp-1 volumio[821]: info: Loading plugin "appearance"... Feb 14 17:25:22 amp-1 bluetoothd[725]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Feb 14 17:25:22 amp-1 bthelper[665]: [NEW] Media /org/bluez/hci0 Feb 14 17:25:22 amp-1 bthelper[665]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 14 17:25:22 amp-1 bthelper[665]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 14 17:25:22 amp-1 bthelper[665]: hci0 class of device changed: 0x000000 Feb 14 17:25:22 amp-1 bthelper[665]: hci0 new_settings: connectable discoverable bondable ssp br/edr le secure-conn Feb 14 17:25:22 amp-1 bthelper[665]: [CHG] Controller DC:A6:32:41:FB:D1 PowerState: on-disabling Feb 14 17:25:22 amp-1 bthelper[665]: Changing power off succeeded Feb 14 17:25:22 amp-1 bthelper[665]: [CHG] Controller DC:A6:32:41:FB:D1 PowerState: off Feb 14 17:25:22 amp-1 bthelper[665]: [CHG] Controller DC:A6:32:41:FB:D1 Powered: no Feb 14 17:25:22 amp-1 bthelper[665]: [CHG] Controller DC:A6:32:41:FB:D1 Discovering: no Feb 14 17:25:22 amp-1 bthelper[665]: [CHG] Controller DC:A6:32:41:FB:D1 Class: 0x00000000 (0) Feb 14 17:25:22 amp-1 volumio-time-update[605]: volumio-time-update-util: Fetching time from Volumio... Feb 14 17:25:22 amp-1 volumio-time-update[605]: volumio-time-update-util: Date not found in response Feb 14 17:25:22 amp-1 volumio-time-update[605]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 17:25:22 amp-1 bthelper[665]: [NEW] Media /org/bluez/hci0 Feb 14 17:25:22 amp-1 bthelper[665]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 14 17:25:22 amp-1 bthelper[665]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 14 17:25:22 amp-1 bthelper[665]: [CHG] Controller DC:A6:32:41:FB:D1 PowerState: off-enabling Feb 14 17:25:22 amp-1 bthelper[665]: hci0 class of device changed: 0x00041c Feb 14 17:25:22 amp-1 bthelper[665]: [CHG] Controller DC:A6:32:41:FB:D1 Class: 0x0000041c (1052) Feb 14 17:25:22 amp-1 bthelper[665]: hci0 new_settings: powered connectable discoverable bondable ssp br/edr le secure-conn Feb 14 17:25:22 amp-1 bthelper[665]: Changing power on succeeded Feb 14 17:25:22 amp-1 sh[511]: eth0: carrier acquired Feb 14 17:25:22 amp-1 dhcpcd[561]: eth0: carrier acquired Feb 14 17:25:22 amp-1 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Feb 14 17:25:22 amp-1 sh[511]: DUID 00:01:00:01:2d:69:1c:d0:dc:a6:32:41:fb:cf Feb 14 17:25:22 amp-1 sh[511]: eth0: IAID 32:41:fb:cf Feb 14 17:25:22 amp-1 sh[511]: eth0: adding address fe80::dea6:32ff:fe41:fbcf Feb 14 17:25:22 amp-1 dhcpcd[561]: DUID 00:01:00:01:2d:69:1c:d0:dc:a6:32:41:fb:cf Feb 14 17:25:22 amp-1 sh[511]: ipv6_addaddr1: Permission denied Feb 14 17:25:22 amp-1 dhcpcd[561]: eth0: IAID 32:41:fb:cf Feb 14 17:25:22 amp-1 dhcpcd[561]: eth0: adding address fe80::dea6:32ff:fe41:fbcf Feb 14 17:25:22 amp-1 dhcpcd[561]: ipv6_addaddr1: Permission denied Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "network"... Feb 14 17:25:23 amp-1 volumio[821]: info: Refreshing Cached IP Addresses Feb 14 17:25:23 amp-1 sudo[859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 14 17:25:23 amp-1 sudo[859]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:23 amp-1 sh[511]: eth0: soliciting an IPv6 router Feb 14 17:25:23 amp-1 dhcpcd[561]: eth0: soliciting an IPv6 router Feb 14 17:25:23 amp-1 sudo[859]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:23 amp-1 sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 14 17:25:23 amp-1 sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "services"... Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "volumio5onboarding"... Feb 14 17:25:23 amp-1 sudo[861]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "alsa_controller"... Feb 14 17:25:23 amp-1 volumio[821]: warn: Unable to locate the audio output device Luckit USB Audio 2.0. Please configure a valid output device. Feb 14 17:25:23 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "wizard"... Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "networkfs"... Feb 14 17:25:23 amp-1 volumio[821]: info: Starting Udev Watcher for removable devices Feb 14 17:25:23 amp-1 sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Volumio,password=Bernstein28Ymer06Mohn70$,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.11.1/Media/Music/ /mnt/NAS/Media Feb 14 17:25:23 amp-1 sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:23 amp-1 volumio[821]: info: Ignoring mount for partition: boot Feb 14 17:25:23 amp-1 volumio[821]: info: Ignoring mount for partition: volumio Feb 14 17:25:23 amp-1 volumio[821]: info: Ignoring mount for partition: volumio_data Feb 14 17:25:23 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "volumio_command_line_client"... Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "upnp"... Feb 14 17:25:23 amp-1 volumio[821]: info: [1771086323304] Starting Upmpd Daemon Feb 14 17:25:23 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "my_music"... Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "mpd"... Feb 14 17:25:23 amp-1 sudo[882]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:23 amp-1 kernel: Key type cifs.spnego registered Feb 14 17:25:23 amp-1 kernel: Key type cifs.idmap registered Feb 14 17:25:23 amp-1 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 14 17:25:23 amp-1 kernel: CIFS: Attempting to mount //192.168.11.1/Media/Music Feb 14 17:25:23 amp-1 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 14 17:25:23 amp-1 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 14 17:25:23 amp-1 ifplugd(eth0)[743]: Link beat detected. Feb 14 17:25:23 amp-1 ifplugd(eth0)[743]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 14 17:25:23 amp-1 ifplugd(eth0)[743]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Feb 14 17:25:23 amp-1 sh[511]: eth0: rebinding lease of 192.168.11.182 Feb 14 17:25:23 amp-1 dhcpcd[561]: eth0: rebinding lease of 192.168.11.182 Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "upnp_browser"... Feb 14 17:25:23 amp-1 sh[511]: eth0: probing address 192.168.11.182/24 Feb 14 17:25:23 amp-1 dhcpcd[561]: eth0: probing address 192.168.11.182/24 Feb 14 17:25:23 amp-1 volumio[821]: info: Starting UPNP Browser Feb 14 17:25:23 amp-1 volumio[821]: info: Loading plugin "alarm-clock"... Feb 14 17:25:24 amp-1 volumio[821]: info: Loading plugin "airplay_emulation"... Feb 14 17:25:24 amp-1 volumio[821]: info: Starting Shairport Sync Feb 14 17:25:24 amp-1 volumio[821]: info: Loading plugin "last_100"... Feb 14 17:25:24 amp-1 volumio[821]: info: Loading plugin "webradio"... Feb 14 17:25:24 amp-1 volumio[821]: info: Loading plugin "i2s_dacs"... Feb 14 17:25:24 amp-1 volumio[821]: info: I2S DAC not set, start Auto-detection Feb 14 17:25:24 amp-1 volumio[821]: info: Loading plugin "volumiodiscovery"... Feb 14 17:25:24 amp-1 volumio[821]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 14 17:25:24 amp-1 volumio[821]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 17:25:24 amp-1 node[821]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 14 17:25:24 amp-1 volumio[821]: *** WARNING *** For more information see Feb 14 17:25:24 amp-1 volumio[821]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 14 17:25:24 amp-1 volumio[821]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 17:25:24 amp-1 volumio[821]: *** WARNING *** For more information see Feb 14 17:25:24 amp-1 node[821]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 17:25:24 amp-1 node[821]: *** WARNING *** For more information see Feb 14 17:25:24 amp-1 node[821]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 14 17:25:24 amp-1 node[821]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 14 17:25:24 amp-1 node[821]: *** WARNING *** For more information see Feb 14 17:25:24 amp-1 volumio[821]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 14 17:25:24 amp-1 volumio[821]: info: Discovery: Started advertising with name: AMP-1 Feb 14 17:25:24 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 14 17:25:24 amp-1 volumio[821]: info: Loading plugin "squeezelite_mc"... Feb 14 17:25:26 amp-1 volumio[821]: info: Loading plugin "outputs"... Feb 14 17:25:26 amp-1 volumio[821]: info: Loading plugin "albumart"... Feb 14 17:25:26 amp-1 volumio[821]: info: Plugin example_plugin is not enabled Feb 14 17:25:26 amp-1 volumio[821]: info: Loading plugin "inputs"... Feb 14 17:25:26 amp-1 volumio[821]: info: Loading plugin "updater_comm"... Feb 14 17:25:27 amp-1 volumio[821]: info: Plugin mpdemulation is not enabled Feb 14 17:25:27 amp-1 volumio[821]: info: Loading plugin "rest_api"... Feb 14 17:25:27 amp-1 volumio[821]: info: Loading plugin "websocket"... Feb 14 17:25:27 amp-1 volumio[821]: info: Starting Socket.io Server version 2.3.0 Feb 14 17:25:27 amp-1 volumio[821]: info: Loading plugin "80s80s"... Feb 14 17:25:27 amp-1 volumio[821]: Forking 3 albumart workers Feb 14 17:25:27 amp-1 volumio-time-update[605]: volumio-time-update-util: Fetching time from Volumio... Feb 14 17:25:27 amp-1 volumio-time-update[605]: volumio-time-update-util: Date not found in response Feb 14 17:25:27 amp-1 volumio-time-update[605]: volumio-time-update-util: Retrying in 5 seconds... Feb 14 17:25:27 amp-1 volumio[821]: Starting albumart workers Feb 14 17:25:27 amp-1 volumio[821]: Starting albumart workers Feb 14 17:25:27 amp-1 volumio[821]: Starting albumart workers Feb 14 17:25:28 amp-1 volumio[821]: info: Applying required configuration parameters for plugin 80s80s Feb 14 17:25:28 amp-1 volumio[821]: info: [1771086328008] [80s80s] API delay: 30 Feb 14 17:25:28 amp-1 volumio[821]: info: Loading i18n strings for locale de Feb 14 17:25:28 amp-1 volumio[821]: Updating browse sources language Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::initPlayerControls Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: Express server listening on port 3000 Feb 14 17:25:28 amp-1 volumio[821]: [Metrics] WebUI: 7s 632.04ms Feb 14 17:25:28 amp-1 volumio[821]: info: CoreStateMachine::resetVolumioState Feb 14 17:25:28 amp-1 volumio[821]: info: CoreStateMachine::getcurrentVolume Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: Volumio Network Manager: Network status updated: 0 Feb 14 17:25:28 amp-1 volumio[821]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 14 17:25:28 amp-1 volumio[821]: info: Cannot mount NAS Media at system boot, trial number 1 ,retrying in 5 seconds Feb 14 17:25:28 amp-1 volumio[821]: info: VolumeController:: Volume=undefined Mute =false Feb 14 17:25:28 amp-1 volumio[821]: info: CoreStateMachine::pushState Feb 14 17:25:28 amp-1 volumio[821]: info: CorePlayQueue::getTrack 0 Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioPushState Feb 14 17:25:28 amp-1 volumio[821]: info: CoreStateMachine::updateTrackBlock Feb 14 17:25:28 amp-1 volumio[821]: info: CorePlayQueue::getTrackBlock Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 17:25:28 amp-1 volumio[821]: info: Reloading queue from file Feb 14 17:25:28 amp-1 volumio[821]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 14 17:25:28 amp-1 volumio[821]: info: Setting Device type: Raspberry PI Feb 14 17:25:28 amp-1 volumio[821]: info: CoreStateMachine::setRepeat false single undefined Feb 14 17:25:28 amp-1 volumio[821]: info: CoreStateMachine::pushState Feb 14 17:25:28 amp-1 volumio[821]: info: CorePlayQueue::getTrack 0 Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioPushState Feb 14 17:25:28 amp-1 volumio[821]: info: CoreStateMachine::setRandom false Feb 14 17:25:28 amp-1 volumio[821]: info: CoreStateMachine::pushState Feb 14 17:25:28 amp-1 volumio[821]: info: CorePlayQueue::getTrack 0 Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioPushState Feb 14 17:25:28 amp-1 volumio[821]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 14 17:25:28 amp-1 volumio[821]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Feb 14 17:25:28 amp-1 volumio[821]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 14 17:25:28 amp-1 volumio[821]: info: VolumeController:: Volume=undefined Mute =false Feb 14 17:25:28 amp-1 volumio[821]: info: CoreStateMachine::pushState Feb 14 17:25:28 amp-1 volumio[821]: info: CorePlayQueue::getTrack 0 Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioPushState Feb 14 17:25:28 amp-1 volumio[821]: info: Completed loading Core Plugins Feb 14 17:25:28 amp-1 volumio[821]: info: Preparing to generate the ALSA configuration file Feb 14 17:25:28 amp-1 volumio[821]: info: Asound.conf file written Feb 14 17:25:28 amp-1 sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 14 17:25:28 amp-1 sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:28 amp-1 sudo[972]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:28 amp-1 volumio[821]: info: Output device has changed, restarting MPD Feb 14 17:25:28 amp-1 sudo[977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 14 17:25:28 amp-1 volumio[821]: info: Output device has changed, restarting Shairport Sync Feb 14 17:25:28 amp-1 sudo[977]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:25:28 amp-1 sudo[977]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:28 amp-1 sudo[979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 14 17:25:28 amp-1 sudo[979]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:28 amp-1 volumio[821]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 14 17:25:28 amp-1 volumio[821]: info: ___________ START PLUGINS ___________ Feb 14 17:25:28 amp-1 volumio[821]: info: ControllerMpd::onStart: Initializing MPD Feb 14 17:25:28 amp-1 volumio[821]: info: Creating MPD Configuration file Feb 14 17:25:28 amp-1 sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 14 17:25:28 amp-1 sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 17:25:28 amp-1 volumio[821]: info: [1771086328665] CoreMusicLibrary::Adding element Medienserver Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 17:25:28 amp-1 volumio[821]: info: UPNP Browser: Client initialized successfully Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:25:28 amp-1 sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 14 17:25:28 amp-1 sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:28 amp-1 sudo[989]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:28 amp-1 sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 14 17:25:28 amp-1 sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:28 amp-1 systemd[1]: Listening on mpd.socket. Feb 14 17:25:28 amp-1 systemd[1]: Starting Music Player Daemon... Feb 14 17:25:28 amp-1 systemd[1]: Started Volumio5 Onboarding Server. Feb 14 17:25:28 amp-1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 14 17:25:28 amp-1 systemd[1]: mpd.service: Succeeded. Feb 14 17:25:28 amp-1 systemd[1]: Stopped Music Player Daemon. Feb 14 17:25:28 amp-1 volumio[821]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:25:28 amp-1 sudo[987]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:28 amp-1 systemd[1]: Starting Music Player Daemon... Feb 14 17:25:28 amp-1 volumio[821]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 17:25:28 amp-1 volumio[821]: info: [1771086328756] CoreMusicLibrary::Adding element Last_100 Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 17:25:28 amp-1 volumio[821]: info: [1771086328758] CoreMusicLibrary::Adding element Webradio Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 17:25:28 amp-1 volumio[821]: info: Initializing BBC Radios Feb 14 17:25:28 amp-1 sudo[1000]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 14 17:25:28 amp-1 sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 14 17:25:28 amp-1 sudo[1000]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 14 17:25:28 amp-1 sudo[1000]: pam_unix(sudo:session): session closed for user root Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 14 17:25:28 amp-1 volumio[821]: info: [squeezelite_mc] Starting proxy server... Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 14 17:25:28 amp-1 volumio[821]: info: [1771086328872] CoreMusicLibrary::Adding element 80s80s Radio Feb 14 17:25:28 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 14 17:25:28 amp-1 volumio[821]: Cannot find translation for source 80s80s Radio Feb 14 17:25:28 amp-1 volumio[821]: info: Volumio Calling Home Feb 14 17:25:28 amp-1 volumio[821]: info: [squeezelite_mc] Proxy server started on port 33555 Feb 14 17:25:29 amp-1 volumio[821]: info: CoreCommandRouter::volumioRetrievevolume Feb 14 17:25:29 amp-1 volumio[821]: Unhandled rejection Error: No sockets available, cannot start. Feb 14 17:25:29 amp-1 volumio[821]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 14 17:25:29 amp-1 volumio[821]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 14 17:25:29 amp-1 volumio[821]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 14 17:25:29 amp-1 volumio[821]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 14 17:25:29 amp-1 volumio[821]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 14 17:25:29 amp-1 volumio[821]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 14 17:25:29 amp-1 volumio[821]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 14 17:25:29 amp-1 volumio[821]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 14 17:25:29 amp-1 volumio[821]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Feb 14 17:25:29 amp-1 volumio[821]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 14 17:25:29 amp-1 volumio[821]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 14 17:25:29 amp-1 volumio[821]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 14 17:25:29 amp-1 volumio[821]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 14 17:25:29 amp-1 volumio[821]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 14 17:25:29 amp-1 volumio[821]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 14 17:25:29 amp-1 volumio[821]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 14 17:25:29 amp-1 volumio[821]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 14 17:25:29 amp-1 volumio[821]: info: MPD Permissions set Feb 14 17:25:29 amp-1 volumio[821]: info: MPD Permissions set Feb 14 17:25:29 amp-1 volumio[821]: info: VolumeController:: Volume=undefined Mute =false Feb 14 17:25:29 amp-1 volumio[821]: info: CoreStateMachine::pushState Feb 14 17:25:29 amp-1 volumio[821]: info: CorePlayQueue::getTrack 0 Feb 14 17:25:29 amp-1 volumio[821]: info: CoreCommandRouter::volumioPushState Feb 14 17:25:29 amp-1 volumio[821]: info: [squeezelite_mc] Server discovery started Feb 14 17:25:29 amp-1 volumio[821]: info: [squeezelite_mc] Player finder started Feb 14 17:25:29 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:29 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 14 17:25:29 amp-1 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 14 17:25:29 amp-1 volumio[821]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 14 17:25:29 amp-1 volumio[821]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 17:25:29 amp-1 volumio[821]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 14 17:25:29 amp-1 volumio[821]: at doSend (dgram.js:692:16) Feb 14 17:25:29 amp-1 volumio[821]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 14 17:25:29 amp-1 volumio[821]: at afterDns (dgram.js:638:5) Feb 14 17:25:29 amp-1 volumio[821]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 14 17:25:29 amp-1 volumio[821]: errno: -101, Feb 14 17:25:29 amp-1 volumio[821]: code: 'ENETUNREACH', Feb 14 17:25:29 amp-1 volumio[821]: syscall: 'send', Feb 14 17:25:29 amp-1 volumio[821]: address: '255.255.255.255', Feb 14 17:25:29 amp-1 volumio[821]: port: 3483 Feb 14 17:25:29 amp-1 volumio[821]: } Feb 14 17:25:29 amp-1 volumio[821]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 14 17:25:29 amp-1 sh[511]: eth0: leased 192.168.11.182 for 172800 seconds Feb 14 17:25:29 amp-1 dhcpcd[561]: eth0: leased 192.168.11.182 for 172800 seconds Feb 14 17:25:29 amp-1 dhcpcd[561]: eth0: adding route to 192.168.11.0/24 Feb 14 17:25:29 amp-1 sh[511]: eth0: adding route to 192.168.11.0/24 Feb 14 17:25:29 amp-1 sh[511]: eth0: adding default route via 192.168.11.254 Feb 14 17:25:29 amp-1 dhcpcd[561]: eth0: adding default route via 192.168.11.254 Feb 14 17:25:29 amp-1 avahi-daemon[595]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.11.182. Feb 14 17:25:29 amp-1 avahi-daemon[595]: New relevant interface eth0.IPv4 for mDNS. Feb 14 17:25:29 amp-1 avahi-daemon[595]: Registering new address record for 192.168.11.182 on eth0.IPv4. Feb 14 17:25:29 amp-1 volumio5-onboarding[999]: time=2026-02-14T17:25:29.270+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 14 17:25:29 amp-1 ntpd[769]: ntpd exiting on signal 15 (Terminated) Feb 14 17:25:29 amp-1 systemd[1]: Stopping Network Time Service... Feb 14 17:25:29 amp-1 systemd[1]: ntp.service: Succeeded. Feb 14 17:25:29 amp-1 systemd[1]: Stopped Network Time Service. Feb 14 17:25:29 amp-1 systemd[1]: Starting Network Time Service... Feb 14 17:25:29 amp-1 ntpd[1070]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 14 17:25:29 amp-1 ntpd[1070]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Feb 14 17:25:29 amp-1 systemd[1]: Started Network Time Service. Feb 14 17:25:29 amp-1 ntpd[1076]: proto: precision = 0.833 usec (-20) Feb 14 17:25:29 amp-1 ntpd[1076]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 14 17:25:29 amp-1 ntpd[1076]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 14 17:25:29 amp-1 ntpd[1076]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 414 days ago Feb 14 17:25:29 amp-1 ntpd[1076]: Listen and drop on 0 v6wildcard [::]:123 Feb 14 17:25:29 amp-1 ntpd[1076]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 14 17:25:29 amp-1 ntpd[1076]: Listen normally on 2 lo 127.0.0.1:123 Feb 14 17:25:29 amp-1 ntpd[1076]: Listen normally on 3 eth0 192.168.11.182:123 Feb 14 17:25:29 amp-1 ntpd[1076]: Listening on routing socket on fd #20 for interface updates Feb 14 17:25:29 amp-1 ntpd[1076]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 17:25:29 amp-1 ntpd[1076]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 17:25:29 amp-1 sh[511]: forked to background, child pid 1078 Feb 14 17:25:29 amp-1 dhcpcd[561]: forked to background, child pid 1078 Feb 14 17:25:29 amp-1 sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-14 17:24 Feb 14 17:25:29 amp-1 sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"