-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2026-02-11 03:42:17 CET. -- Feb 11 03:41:56 volumio1 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Feb 11 03:41:56 volumio1 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 11 03:41:56 volumio1 kernel: usb usb1: Product: xHCI Host Controller Feb 11 03:41:56 volumio1 kernel: usb usb1: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Feb 11 03:41:56 volumio1 kernel: usb usb1: SerialNumber: 0000:01:00.0 Feb 11 03:41:56 volumio1 kernel: hub 1-0:1.0: USB hub found Feb 11 03:41:56 volumio1 kernel: hub 1-0:1.0: 1 port detected Feb 11 03:41:56 volumio1 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.06 Feb 11 03:41:56 volumio1 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 11 03:41:56 volumio1 kernel: usb usb2: Product: xHCI Host Controller Feb 11 03:41:56 volumio1 kernel: usb usb2: Manufacturer: Linux 6.6.62-v7l+ xhci-hcd Feb 11 03:41:56 volumio1 kernel: usb usb2: SerialNumber: 0000:01:00.0 Feb 11 03:41:56 volumio1 kernel: hub 2-0:1.0: USB hub found Feb 11 03:41:56 volumio1 kernel: hub 2-0:1.0: 4 ports detected Feb 11 03:41:56 volumio1 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 11 03:41:56 volumio1 kernel: dwc_otg: FIQ enabled Feb 11 03:41:56 volumio1 kernel: dwc_otg: NAK holdoff enabled Feb 11 03:41:56 volumio1 kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 11 03:41:56 volumio1 kernel: Module dwc_common_port init Feb 11 03:41:56 volumio1 kernel: usbcore: registered new interface driver uas Feb 11 03:41:56 volumio1 kernel: usbcore: registered new interface driver usb-storage Feb 11 03:41:56 volumio1 kernel: mousedev: PS/2 mouse device common for all mice Feb 11 03:41:56 volumio1 kernel: sdhci: Secure Digital Host Controller Interface driver Feb 11 03:41:56 volumio1 kernel: sdhci: Copyright(c) Pierre Ossman Feb 11 03:41:56 volumio1 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 11 03:41:56 volumio1 kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 11 03:41:56 volumio1 kernel: hid: raw HID events driver (C) Jiri Kosina Feb 11 03:41:56 volumio1 kernel: usbcore: registered new interface driver usbhid Feb 11 03:41:56 volumio1 kernel: usbhid: USB HID core driver Feb 11 03:41:56 volumio1 kernel: Initializing XFRM netlink socket Feb 11 03:41:56 volumio1 kernel: NET: Registered PF_PACKET protocol family Feb 11 03:41:56 volumio1 kernel: Key type dns_resolver registered Feb 11 03:41:56 volumio1 kernel: Registering SWP/SWPB emulation handler Feb 11 03:41:56 volumio1 kernel: registered taskstats version 1 Feb 11 03:41:56 volumio1 kernel: Loading compiled-in X.509 certificates Feb 11 03:41:56 volumio1 kernel: Key type .fscrypt registered Feb 11 03:41:56 volumio1 kernel: Key type fscrypt-provisioning registered Feb 11 03:41:56 volumio1 kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Feb 11 03:41:56 volumio1 kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Feb 11 03:41:56 volumio1 kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Feb 11 03:41:56 volumio1 kernel: serial serial0: tty port ttyAMA1 registered Feb 11 03:41:56 volumio1 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 11 03:41:56 volumio1 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 11 03:41:56 volumio1 kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 11 03:41:56 volumio1 kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Feb 11 03:41:56 volumio1 kernel: of_cfs_init Feb 11 03:41:56 volumio1 kernel: of_cfs_init: OK Feb 11 03:41:56 volumio1 kernel: clk: Disabling unused clocks Feb 11 03:41:56 volumio1 kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Feb 11 03:41:56 volumio1 kernel: Freeing unused kernel image (initmem) memory: 2048K Feb 11 03:41:56 volumio1 kernel: Run /init as init process Feb 11 03:41:56 volumio1 kernel: with arguments: Feb 11 03:41:56 volumio1 kernel: /init Feb 11 03:41:56 volumio1 kernel: splash Feb 11 03:41:56 volumio1 kernel: nodebug Feb 11 03:41:56 volumio1 kernel: with environment: Feb 11 03:41:56 volumio1 kernel: HOME=/ Feb 11 03:41:56 volumio1 kernel: TERM=linux Feb 11 03:41:56 volumio1 kernel: numa_policy=interleave Feb 11 03:41:56 volumio1 kernel: imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad Feb 11 03:41:56 volumio1 kernel: imgfile=/volumio_current.sqsh Feb 11 03:41:56 volumio1 kernel: bootpart=UUID=2E0A-AE55 Feb 11 03:41:56 volumio1 kernel: datapart=UUID=660717eb-e471-4727-aac1-2c263938c365 Feb 11 03:41:56 volumio1 kernel: uuidconfig=cmdline.txt Feb 11 03:41:56 volumio1 kernel: pcie_aspm=off Feb 11 03:41:56 volumio1 kernel: bootdelay=7 Feb 11 03:41:56 volumio1 kernel: use_kmsg=no Feb 11 03:41:56 volumio1 kernel: mmc1: new high speed SDIO card at address 0001 Feb 11 03:41:56 volumio1 kernel: mmc0: new ultra high speed DDR50 SDHC card at address 0001 Feb 11 03:41:56 volumio1 kernel: mmcblk0: mmc0:0001 EB1QT 29.8 GiB Feb 11 03:41:56 volumio1 kernel: mmcblk0: p1 p2 p3 Feb 11 03:41:56 volumio1 kernel: mmcblk0: mmc0:0001 EB1QT 29.8 GiB Feb 11 03:41:56 volumio1 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Feb 11 03:41:56 volumio1 kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Feb 11 03:41:56 volumio1 kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Feb 11 03:41:56 volumio1 kernel: usb 1-1: Product: USB2.0 Hub Feb 11 03:41:56 volumio1 kernel: hub 1-1:1.0: USB hub found Feb 11 03:41:56 volumio1 kernel: hub 1-1:1.0: 4 ports detected Feb 11 03:41:56 volumio1 kernel: usb 1-1.2: new full-speed USB device number 3 using xhci_hcd Feb 11 03:41:56 volumio1 kernel: fuse: init (API version 7.39) Feb 11 03:41:56 volumio1 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 11 03:41:56 volumio1 kernel: usb 1-1.2: New USB device found, idVendor=22e8, idProduct=dac3, bcdDevice= 3.26 Feb 11 03:41:56 volumio1 kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 11 03:41:56 volumio1 kernel: usb 1-1.2: Product: Cambridge AudioDAC100 USB 1 Feb 11 03:41:56 volumio1 kernel: usb 1-1.2: Manufacturer: Cambridge Audio Feb 11 03:41:56 volumio1 kernel: usb 1-1.2: SerialNumber: 0000 Feb 11 03:41:56 volumio1 kernel: usb 1-1.4: new full-speed USB device number 4 using xhci_hcd Feb 11 03:41:56 volumio1 kernel: usb 1-1.4: New USB device found, idVendor=0c45, idProduct=5101, bcdDevice= 1.20 Feb 11 03:41:56 volumio1 kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Feb 11 03:41:56 volumio1 kernel: usb 1-1.4: Product: RF Controller Feb 11 03:41:56 volumio1 kernel: usb 1-1.4: Manufacturer: MemsArt MA144 Feb 11 03:41:56 volumio1 kernel: input: MemsArt MA144 RF Controller as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/0003:0C45:5101.0001/input/input0 Feb 11 03:41:56 volumio1 kernel: hid-generic 0003:0C45:5101.0001: input,hidraw0: USB HID v1.10 Keyboard [MemsArt MA144 RF Controller] on usb-0000:01:00.0-1.4/input0 Feb 11 03:41:56 volumio1 kernel: input: MemsArt MA144 RF Controller as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:0C45:5101.0002/input/input1 Feb 11 03:41:56 volumio1 kernel: hid-generic 0003:0C45:5101.0002: input,hidraw1: USB HID v1.10 Mouse [MemsArt MA144 RF Controller] on usb-0000:01:00.0-1.4/input1 Feb 11 03:41:56 volumio1 kernel: input: MemsArt MA144 RF Controller Consumer Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:0C45:5101.0003/input/input2 Feb 11 03:41:56 volumio1 kernel: input: MemsArt MA144 RF Controller System Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:0C45:5101.0003/input/input3 Feb 11 03:41:56 volumio1 kernel: hid-generic 0003:0C45:5101.0003: input,hidraw2: USB HID v1.10 Device [MemsArt MA144 RF Controller] on usb-0000:01:00.0-1.4/input2 Feb 11 03:41:56 volumio1 kernel: EXT4-fs (mmcblk0p2): mounted filesystem af3faec2-9654-4fec-8791-d5638a5837ad r/w with ordered data mode. Quota mode: none. Feb 11 03:41:56 volumio1 kernel: loop0: detected capacity change from 0 to 1018800 Feb 11 03:41:56 volumio1 kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 11 03:41:56 volumio1 kernel: EXT4-fs (mmcblk0p3): mounted filesystem 660717eb-e471-4727-aac1-2c263938c365 r/w with ordered data mode. Quota mode: none. Feb 11 03:41:56 volumio1 systemd[1]: System time before build time, advancing clock. Feb 11 03:41:56 volumio1 kernel: NET: Registered PF_INET6 protocol family Feb 11 03:41:56 volumio1 kernel: Segment Routing with IPv6 Feb 11 03:41:56 volumio1 kernel: In-situ OAM (IOAM) with IPv6 Feb 11 03:41:56 volumio1 systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Feb 11 03:41:56 volumio1 systemd[1]: Detected architecture arm. Feb 11 03:41:56 volumio1 systemd[1]: Set hostname to . Feb 11 03:41:56 volumio1 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 11 03:41:56 volumio1 systemd[1]: Created slice system-getty.slice. Feb 11 03:41:56 volumio1 systemd[1]: Listening on RPCbind Server Activation Socket. Feb 11 03:41:56 volumio1 systemd[1]: Listening on initctl Compatibility Named Pipe. Feb 11 03:41:56 volumio1 systemd[1]: Listening on Journal Socket. Feb 11 03:41:56 volumio1 systemd[1]: Starting Restore / save the current clock... Feb 11 03:41:56 volumio1 systemd[1]: Starting Remount Root and Kernel File Systems... Feb 11 03:41:56 volumio1 kernel: i2c_dev: i2c /dev entries driver Feb 11 03:41:56 volumio1 systemd-journald[327]: Journal started Feb 11 03:41:56 volumio1 systemd-journald[327]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Feb 11 03:41:56 volumio1 systemd-modules-load[320]: Inserted module 'i2c_dev' Feb 11 03:41:56 volumio1 fake-hwclock[315]: Wed Feb 11 02:41:56 UTC 2026 Feb 11 03:41:56 volumio1 systemd[1]: Started Apply Kernel Variables. Feb 11 03:41:56 volumio1 systemd[1]: Started Load/Save Random Seed. Feb 11 03:41:56 volumio1 systemd[1]: Started Create System Users. Feb 11 03:41:56 volumio1 systemd[1]: Starting Create Static Device Nodes in /dev... Feb 11 03:41:56 volumio1 systemd[1]: dynamicswap.service: Succeeded. Feb 11 03:41:56 volumio1 systemd[1]: Started Create Static Device Nodes in /dev. Feb 11 03:41:56 volumio1 systemd[1]: Reached target Local File Systems (Pre). Feb 11 03:41:56 volumio1 systemd[1]: Mounting /var/spool/cups... Feb 11 03:41:56 volumio1 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 11 03:41:56 volumio1 systemd[1]: Mounting /var/log... Feb 11 03:41:56 volumio1 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 11 03:41:56 volumio1 systemd[1]: Mounting /tmp... Feb 11 03:41:56 volumio1 systemd[1]: Starting udev Kernel Device Manager... Feb 11 03:41:56 volumio1 systemd[1]: Mounted /var/spool/cups. Feb 11 03:41:56 volumio1 systemd[1]: Mounted /var/log. Feb 11 03:41:56 volumio1 systemd[1]: Mounted /tmp. Feb 11 03:41:56 volumio1 systemd[1]: Starting Flush Journal to Persistent Storage... Feb 11 03:41:56 volumio1 systemd[1]: Mounting /var/spool/cups/tmp... Feb 11 03:41:56 volumio1 systemd[1]: Started udev Coldplug all Devices. Feb 11 03:41:56 volumio1 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Feb 11 03:41:56 volumio1 systemd[1]: Mounted /var/spool/cups/tmp. Feb 11 03:41:56 volumio1 systemd-udevd[347]: Network interface NamePolicy= disabled on kernel command line, ignoring. Feb 11 03:41:56 volumio1 systemd[1]: Started Helper to synchronize boot up for ifupdown. Feb 11 03:41:56 volumio1 systemd-journald[327]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 7.5M, max 30.0M, 22.5M free. Feb 11 03:41:56 volumio1 systemd[1]: Started udev Kernel Device Manager. Feb 11 03:41:56 volumio1 systemd[1]: Starting Show Plymouth Boot Screen... Feb 11 03:41:56 volumio1 systemd[1]: Started Flush Journal to Persistent Storage. Feb 11 03:41:56 volumio1 systemd[1]: plymouth-start.service: Succeeded. Feb 11 03:41:56 volumio1 systemd[1]: Started Show Plymouth Boot Screen. Feb 11 03:41:56 volumio1 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 11 03:41:56 volumio1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 11 03:41:56 volumio1 systemd[1]: Reached target Local Encrypted Volumes. Feb 11 03:41:56 volumio1 systemd[1]: Reached target Paths. Feb 11 03:41:56 volumio1 systemd-udevd[374]: Using default interface naming scheme 'v240'. Feb 11 03:41:56 volumio1 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Feb 11 03:41:56 volumio1 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Feb 11 03:41:56 volumio1 kernel: [vc_sm_connected_init]: start Feb 11 03:41:56 volumio1 kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Feb 11 03:41:56 volumio1 kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Feb 11 03:41:56 volumio1 kernel: [vc_sm_connected_init]: installed successfully Feb 11 03:41:56 volumio1 systemd-udevd[374]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 11 03:41:56 volumio1 kernel: mc: Linux media interface: v0.10 Feb 11 03:41:56 volumio1 systemd-udevd[373]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 11 03:41:56 volumio1 kernel: videodev: Linux video capture interface: v2.00 Feb 11 03:41:56 volumio1 kernel: Bluetooth: Core ver 2.22 Feb 11 03:41:56 volumio1 kernel: NET: Registered PF_BLUETOOTH protocol family Feb 11 03:41:56 volumio1 kernel: Bluetooth: HCI device and connection manager initialized Feb 11 03:41:56 volumio1 kernel: Bluetooth: HCI socket layer initialized Feb 11 03:41:56 volumio1 kernel: Bluetooth: L2CAP socket layer initialized Feb 11 03:41:56 volumio1 kernel: Bluetooth: SCO socket layer initialized Feb 11 03:41:56 volumio1 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 11 03:41:56 volumio1 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Feb 11 03:41:56 volumio1 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Feb 11 03:41:56 volumio1 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 11 03:41:56 volumio1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Feb 11 03:41:56 volumio1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Feb 11 03:41:56 volumio1 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Feb 11 03:41:56 volumio1 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Feb 11 03:41:56 volumio1 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 11 03:41:56 volumio1 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Feb 11 03:41:56 volumio1 kernel: Bluetooth: HCI UART driver ver 2.3 Feb 11 03:41:56 volumio1 kernel: Bluetooth: HCI UART protocol H4 registered Feb 11 03:41:56 volumio1 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 11 03:41:56 volumio1 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Feb 11 03:41:57 volumio1 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Feb 11 03:41:57 volumio1 kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 11 03:41:57 volumio1 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Feb 11 03:41:57 volumio1 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Feb 11 03:41:57 volumio1 kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Feb 11 03:41:57 volumio1 kernel: rpivid feb10000.codec: Device registered as /dev/video19 Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Feb 11 03:41:57 volumio1 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Feb 11 03:41:57 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 11 03:41:57 volumio1 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Feb 11 03:41:57 volumio1 kernel: uart-pl011 fe201000.serial: no DMA platform data Feb 11 03:41:57 volumio1 kernel: alsactl[461]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Feb 11 03:41:57 volumio1 systemd-udevd[377]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 11 03:41:57 volumio1 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Feb 11 03:41:57 volumio1 systemd-udevd[380]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 11 03:41:57 volumio1 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 11 03:41:57 volumio1 kernel: usbcore: registered new interface driver brcmfmac Feb 11 03:41:57 volumio1 kernel: Bluetooth: hci0: BCM: chip id 107 Feb 11 03:41:57 volumio1 kernel: Bluetooth: hci0: BCM: features 0x2f Feb 11 03:41:57 volumio1 kernel: Bluetooth: hci0: BCM4345C0 Feb 11 03:41:57 volumio1 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Feb 11 03:41:57 volumio1 systemd-udevd[354]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 11 03:41:57 volumio1 kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Feb 11 03:41:57 volumio1 kernel: usbcore: registered new interface driver snd-usb-audio Feb 11 03:41:57 volumio1 systemd-udevd[362]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 11 03:41:57 volumio1 systemd[1]: Found device /dev/disk/by-uuid/2E0A-AE55. Feb 11 03:41:57 volumio1 systemd-udevd[370]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 11 03:41:57 volumio1 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Feb 11 03:41:57 volumio1 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 11 03:41:57 volumio1 systemd-udevd[354]: Using default interface naming scheme 'v240'. Feb 11 03:41:57 volumio1 systemd-udevd[354]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 11 03:41:57 volumio1 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Feb 11 03:41:57 volumio1 systemd-udevd[368]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Feb 11 03:41:57 volumio1 systemd[1]: Created slice system-bthelper.slice. Feb 11 03:41:57 volumio1 systemd[1]: Starting File System Check on /dev/disk/by-uuid/2E0A-AE55... Feb 11 03:41:57 volumio1 systemd[1]: Starting Show Plymouth Boot Screen... Feb 11 03:41:57 volumio1 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 11 03:41:57 volumio1 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Feb 11 03:41:57 volumio1 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Feb 11 03:41:57 volumio1 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 11 03:41:57 volumio1 systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 11 03:41:57 volumio1 systemd[1]: Started File System Check Daemon to report status. Feb 11 03:41:57 volumio1 systemd[1]: plymouth-start.service: Succeeded. Feb 11 03:41:57 volumio1 systemd[1]: Started Show Plymouth Boot Screen. Feb 11 03:41:57 volumio1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 11 03:41:57 volumio1 systemd[1]: Started Load/Save RF Kill Switch Status. Feb 11 03:41:57 volumio1 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Feb 11 03:41:57 volumio1 systemd-fsck[513]: fsck.fat 4.1 (2017-01-24) Feb 11 03:41:57 volumio1 systemd-fsck[513]: /dev/mmcblk0p1: 404 files, 41766/46774 clusters Feb 11 03:41:57 volumio1 systemd[1]: Started File System Check on /dev/disk/by-uuid/2E0A-AE55. Feb 11 03:41:57 volumio1 systemd[1]: Mounting /boot... Feb 11 03:41:57 volumio1 systemd[1]: Mounted /boot. Feb 11 03:41:57 volumio1 systemd[1]: Reached target Local File Systems. Feb 11 03:41:57 volumio1 systemd[1]: Starting Create Volatile Files and Directories... Feb 11 03:41:57 volumio1 systemd[1]: Started ifup for eth0. Feb 11 03:41:57 volumio1 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Feb 11 03:41:57 volumio1 systemd[1]: Started ifup for wlan0. Feb 11 03:41:57 volumio1 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 11 03:41:57 volumio1 systemd[1]: Starting Raise network interfaces... Feb 11 03:41:57 volumio1 systemd[1]: Starting Preprocess NFS configuration... Feb 11 03:41:57 volumio1 systemd[1]: Received SIGRTMIN+20 from PID 208 (plymouthd). Feb 11 03:41:57 volumio1 systemd[1]: plymouth-read-write.service: Succeeded. Feb 11 03:41:57 volumio1 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 11 03:41:57 volumio1 systemd[1]: nfs-config.service: Succeeded. Feb 11 03:41:57 volumio1 systemd[1]: Started Preprocess NFS configuration. Feb 11 03:41:57 volumio1 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Feb 11 03:41:57 volumio1 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Feb 11 03:41:57 volumio1 systemd[1]: Reached target NFS client services. Feb 11 03:41:57 volumio1 systemd[1]: Started Create Volatile Files and Directories. Feb 11 03:41:57 volumio1 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Feb 11 03:41:57 volumio1 kernel: Bluetooth: hci0: BCM: features 0x2f Feb 11 03:41:57 volumio1 systemd[1]: Starting RPC bind portmap service... Feb 11 03:41:57 volumio1 kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Feb 11 03:41:57 volumio1 kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Feb 11 03:41:57 volumio1 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 11 03:41:57 volumio1 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 11 03:41:57 volumio1 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Feb 11 03:41:57 volumio1 systemd[1]: Started RPC bind portmap service. Feb 11 03:41:57 volumio1 systemd[1]: Reached target RPC Port Mapper. Feb 11 03:41:57 volumio1 systemd[1]: Reached target Remote File Systems (Pre). Feb 11 03:41:57 volumio1 systemd[1]: Reached target Remote File Systems. Feb 11 03:41:58 volumio1 systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 11 03:41:58 volumio1 systemd[1]: Reached target System Initialization. Feb 11 03:41:58 volumio1 systemd[1]: Listening on triggerhappy.socket. Feb 11 03:41:58 volumio1 systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 11 03:41:58 volumio1 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 11 03:41:58 volumio1 systemd[1]: Reached target Sockets. Feb 11 03:41:58 volumio1 systemd[1]: Reached target Basic System. Feb 11 03:41:58 volumio1 kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 11 03:41:58 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 11 03:41:58 volumio1 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Feb 11 03:41:58 volumio1 systemd[1]: Started Volumio Iptables Module. Feb 11 03:41:58 volumio1 systemd[1]: Starting Wireless Services... Feb 11 03:41:58 volumio1 systemd[1]: Started D-Bus System Message Bus. Feb 11 03:41:58 volumio1 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 11 03:41:58 volumio1 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Feb 11 03:41:58 volumio1 systemd[1]: Starting Volumio Time Update Utility... Feb 11 03:41:58 volumio1 systemd[1]: Started Volumio Log Rotation Service. Feb 11 03:41:58 volumio1 systemd[1]: Starting Login Service... Feb 11 03:41:58 volumio1 volumio-time-update[621]: volumio-time-update-util: Fetching time from Volumio... Feb 11 03:41:58 volumio1 systemd[1]: Started Manage Sound Card State (restore and store). Feb 11 03:41:58 volumio1 systemd[1]: Starting WPA supplicant... Feb 11 03:41:58 volumio1 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 11 03:41:58 volumio1 alsactl[631]: alsactl 1.1.8 daemon started Feb 11 03:41:58 volumio1 systemd[1]: Started volumio-remote-updater.service. Feb 11 03:41:58 volumio1 systemd[1]: Starting triggerhappy global hotkey daemon... Feb 11 03:41:58 volumio1 systemd[1]: Starting Save/Restore Sound Card State... Feb 11 03:41:58 volumio1 systemd[1]: Started Daily apt download activities. Feb 11 03:41:58 volumio1 systemd[1]: Started Daily apt upgrade and clean activities. Feb 11 03:41:58 volumio1 systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 11 03:41:58 volumio1 systemd[1]: Reached target Timers. Feb 11 03:41:58 volumio1 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 11 03:41:58 volumio1 systemd[1]: Starting dhcpcd on all interfaces... Feb 11 03:41:58 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Feb 11 03:41:58 volumio1 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Feb 11 03:41:58 volumio1 systemd[1]: hciuart.service: Succeeded. Feb 11 03:41:58 volumio1 systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 11 03:41:58 volumio1 systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 11 03:41:58 volumio1 sh[525]: wlan0=wlan0 Feb 11 03:41:58 volumio1 thd[643]: Unable to parse trigger line: Feb 11 03:41:58 volumio1 thd[643]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Feb 11 03:41:58 volumio1 thd[643]: Unable to parse trigger line: Feb 11 03:41:58 volumio1 thd[643]: Found socket passed from systemd Feb 11 03:41:58 volumio1 dhcpcd[645]: Not running dhcpcd because /etc/network/interfaces Feb 11 03:41:58 volumio1 dhcpcd[645]: defines some interfaces that will use a Feb 11 03:41:58 volumio1 dhcpcd[645]: DHCP client or static address Feb 11 03:41:58 volumio1 systemd-logind[627]: Watching system buttons on /dev/input/event0 (MemsArt MA144 RF Controller) Feb 11 03:41:58 volumio1 systemd-logind[627]: Watching system buttons on /dev/input/event2 (MemsArt MA144 RF Controller Consumer Control) Feb 11 03:41:58 volumio1 systemd-logind[627]: Watching system buttons on /dev/input/event3 (MemsArt MA144 RF Controller System Control) Feb 11 03:41:58 volumio1 systemd-logind[627]: New seat seat0. Feb 11 03:41:58 volumio1 avahi-daemon[637]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Feb 11 03:41:58 volumio1 avahi-daemon[637]: Successfully dropped root privileges. Feb 11 03:41:58 volumio1 avahi-daemon[637]: avahi-daemon 0.7 starting up. Feb 11 03:41:58 volumio1 bthelper[647]: Raspberry Pi BDADDR already set Feb 11 03:41:58 volumio1 volumio-time-update[621]: volumio-time-update-util: Date not found in response Feb 11 03:41:58 volumio1 volumio-time-update[621]: volumio-time-update-util: Retrying in 5 seconds... Feb 11 03:41:58 volumio1 volumio-remote-updater[638]: Error: No active session Feb 11 03:41:58 volumio1 volumio-remote-updater[638]: [2026-02-11 03:41:58] [info] asio async_connect error: system:111 (Connection refused) Feb 11 03:41:58 volumio1 volumio-remote-updater[638]: [2026-02-11 03:41:58] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 11 03:41:58 volumio1 volumio-remote-updater[638]: [2026-02-11 03:41:58] [error] handle_connect error: Underlying Transport Error Feb 11 03:41:58 volumio1 sh[524]: eth0: waiting for carrier Feb 11 03:41:58 volumio1 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Feb 11 03:41:58 volumio1 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Feb 11 03:41:58 volumio1 dhcpcd[574]: eth0: waiting for carrier Feb 11 03:41:58 volumio1 dbus-daemon[612]: [system] Successfully activated service 'org.freedesktop.systemd1' Feb 11 03:41:58 volumio1 systemd[1]: Started Login Service. Feb 11 03:41:58 volumio1 systemd[1]: Started triggerhappy global hotkey daemon. Feb 11 03:41:58 volumio1 avahi-daemon[637]: Successfully called chroot(). Feb 11 03:41:58 volumio1 avahi-daemon[637]: Successfully dropped remaining capabilities. Feb 11 03:41:58 volumio1 wpa_supplicant[636]: Successfully initialized wpa_supplicant Feb 11 03:41:58 volumio1 systemd[1]: Started Save/Restore Sound Card State. Feb 11 03:41:58 volumio1 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Feb 11 03:41:58 volumio1 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Feb 11 03:41:58 volumio1 systemd[1]: Failed to start dhcpcd on all interfaces. Feb 11 03:41:58 volumio1 systemd[1]: Started Raspberry Pi bluetooth helper. Feb 11 03:41:58 volumio1 volumio[646]: Could not open config: /tmp/upmpdcli.conf Feb 11 03:41:58 volumio1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 11 03:41:58 volumio1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 11 03:41:58 volumio1 avahi-daemon[637]: Loading service file /services/volumio.service. Feb 11 03:41:58 volumio1 avahi-daemon[637]: Network interface enumeration completed. Feb 11 03:41:58 volumio1 avahi-daemon[637]: Server startup complete. Host name is volumio1.local. Local service cookie is 103411302. Feb 11 03:41:58 volumio1 avahi-daemon[637]: Service "Volumio1" (/services/volumio.service) successfully established. Feb 11 03:41:58 volumio1 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 11 03:41:58 volumio1 systemd[1]: Started WPA supplicant. Feb 11 03:41:58 volumio1 systemd[1]: Starting Bluetooth service... Feb 11 03:41:58 volumio1 systemd[1]: Reached target Sound Card. Feb 11 03:41:58 volumio1 systemd[1]: Started Raise network interfaces. Feb 11 03:41:58 volumio1 systemd[1]: Reached target Network. Feb 11 03:41:58 volumio1 systemd[1]: Starting Network Time Service... Feb 11 03:41:58 volumio1 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 11 03:41:58 volumio1 systemd[1]: Starting Permit User Sessions... Feb 11 03:41:58 volumio1 systemd[1]: Reached target Network is Online. Feb 11 03:41:58 volumio1 systemd[1]: Starting /etc/rc.local Compatibility... Feb 11 03:41:58 volumio1 systemd[1]: Starting LSB: Brings up/down network automatically... Feb 11 03:41:58 volumio1 systemd[1]: Starting Samba NMB Daemon... Feb 11 03:41:58 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 11 03:41:58 volumio1 systemd[1]: Started /etc/rc.local Compatibility. Feb 11 03:41:58 volumio1 systemd[1]: Started Permit User Sessions. Feb 11 03:41:58 volumio1 systemd[1]: Starting Hold until boot process finishes up... Feb 11 03:41:58 volumio1 systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 11 03:41:58 volumio1 systemd[1]: iptables.service: Succeeded. Feb 11 03:41:58 volumio1 systemd[1]: Received SIGRTMIN+21 from PID 208 (plymouthd). Feb 11 03:41:58 volumio1 systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 11 03:41:58 volumio1 systemd[1]: Started Hold until boot process finishes up. Feb 11 03:41:58 volumio1 systemd[1]: plymouth-quit.service: Succeeded. Feb 11 03:41:58 volumio1 systemd[1]: Started Terminate Plymouth Boot Screen. Feb 11 03:41:58 volumio1 systemd[1]: Received SIGRTMIN+21 from PID 208 (n/a). Feb 11 03:41:59 volumio1 loadcpufreq[620]: Loading cpufreq kernel modules...done (none). Feb 11 03:41:59 volumio1 bluetoothd[715]: Bluetooth daemon 5.83 Feb 11 03:41:59 volumio1 systemd[1]: Started Getty on tty1. Feb 11 03:41:59 volumio1 ifplugd(eth0)[774]: ifplugd 0.28 initializing. Feb 11 03:41:59 volumio1 ifplugd(eth0)[774]: Using interface eth0/DC:A6:32:8A:EF:89 with driver (version: 6.6.62-v7l+) Feb 11 03:41:59 volumio1 systemd[1]: Reached target Login Prompts. Feb 11 03:41:59 volumio1 ifplugd(eth0)[774]: Using detection mode: SIOCETHTOOL Feb 11 03:41:59 volumio1 ifplugd(eth0)[774]: Initialization complete, link beat not detected. Feb 11 03:41:59 volumio1 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 11 03:41:59 volumio1 bluetoothd[715]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 11 03:41:59 volumio1 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 11 03:41:59 volumio1 systemd[1]: Started Bluetooth service. Feb 11 03:41:59 volumio1 systemd[1]: Reached target Bluetooth. Feb 11 03:41:59 volumio1 ntpd[727]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 11 03:41:59 volumio1 ntpd[727]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Feb 11 03:41:59 volumio1 bluetoothd[715]: Starting SDP server Feb 11 03:41:59 volumio1 bluetoothd[715]: Excluding (cli) hostname Feb 11 03:41:59 volumio1 bluetoothd[715]: Excluding (cli) scanparam Feb 11 03:41:59 volumio1 bluetoothd[715]: Excluding (cli) battery Feb 11 03:41:59 volumio1 ntpd[779]: proto: precision = 0.833 usec (-20) Feb 11 03:41:59 volumio1 systemd[1]: Started Network Time Service. Feb 11 03:41:59 volumio1 ntpd[779]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 11 03:41:59 volumio1 ntpd[779]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 11 03:41:59 volumio1 ntpd[779]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 411 days ago Feb 11 03:41:59 volumio1 ntpd[779]: Listen and drop on 0 v6wildcard [::]:123 Feb 11 03:41:59 volumio1 ntpd[779]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 11 03:41:59 volumio1 ntpd[779]: Listen normally on 2 lo 127.0.0.1:123 Feb 11 03:41:59 volumio1 ntpd[779]: Listening on routing socket on fd #19 for interface updates Feb 11 03:41:59 volumio1 ntpd[779]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 11 03:41:59 volumio1 ntpd[779]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 11 03:41:59 volumio1 ifplugd[730]: Network Interface Plugging Daemon...start eth0...done. Feb 11 03:41:59 volumio1 systemd[1]: Started LSB: Brings up/down network automatically. Feb 11 03:41:59 volumio1 bluetoothd[715]: Bluetooth management interface 1.22 initialized Feb 11 03:41:59 volumio1 bluetoothd[715]: Battery Provider Manager created Feb 11 03:41:59 volumio1 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 11 03:41:59 volumio1 kernel: Bluetooth: BNEP filters: protocol multicast Feb 11 03:41:59 volumio1 kernel: Bluetooth: BNEP socket layer initialized Feb 11 03:41:59 volumio1 kernel: Bluetooth: MGMT ver 1.22 Feb 11 03:41:59 volumio1 cpufrequtils[776]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 11 03:41:59 volumio1 systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 11 03:41:59 volumio1 kernel: NET: Registered PF_ALG protocol family Feb 11 03:41:59 volumio1 kernel: cryptd: max_cpu_qlen set to 1000 Feb 11 03:41:59 volumio1 haveged[562]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Feb 11 03:41:59 volumio1 haveged[562]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Feb 11 03:41:59 volumio1 haveged[562]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99759 Feb 11 03:41:59 volumio1 haveged[562]: haveged: fills: 0, generated: 0 Feb 11 03:41:59 volumio1 bluetoothd[715]: Failed to set privacy: Rejected (0x0b) Feb 11 03:41:59 volumio1 nmbd[736]: [2026/02/11 03:41:59.870689, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 11 03:41:59 volumio1 nmbd[736]: started asyncdns process 816 Feb 11 03:41:59 volumio1 nmbd[736]: [2026/02/11 03:41:59.872424, 0] ../lib/util/become_daemon.c:149(daemon_status) Feb 11 03:41:59 volumio1 nmbd[736]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 11 03:41:59 volumio1 nmbd[736]: [2026/02/11 03:41:59.872548, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Feb 11 03:41:59 volumio1 nmbd[736]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 11 03:42:00 volumio1 ntpd[779]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 11 03:42:00 volumio1 wireless.js[608]: Cleaning previous... Feb 11 03:42:00 volumio1 sudo[824]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 11 03:42:00 volumio1 sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:00 volumio1 sudo[824]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:00 volumio1 sudo[826]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 11 03:42:00 volumio1 sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:00 volumio1 sudo[826]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:00 volumio1 wireless.js[608]: Stopped aP Feb 11 03:42:00 volumio1 sudo[833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 11 03:42:00 volumio1 sudo[833]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:00 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 11 03:42:00 volumio1 sudo[833]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:00 volumio1 sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 11 03:42:00 volumio1 sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:00 volumio1 sudo[835]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:00 volumio1 sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 11 03:42:00 volumio1 sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:00 volumio1 sudo[842]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:00 volumio1 sudo[844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 11 03:42:00 volumio1 sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:01 volumio1 ntpd[779]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 11 03:42:02 volumio1 ntpd[779]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 11 03:42:02 volumio1 systemd[1]: systemd-rfkill.service: Succeeded. Feb 11 03:42:03 volumio1 ntpd[779]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 11 03:42:03 volumio1 sudo[844]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:03 volumio1 wireless.js[608]: SETTING APPROPRIATE REG DOMAIN: IT Feb 11 03:42:03 volumio1 bthelper[647]: [NEW] Media /org/bluez/hci0 Feb 11 03:42:03 volumio1 bthelper[647]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 11 03:42:03 volumio1 bthelper[647]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 11 03:42:03 volumio1 bluetoothd[715]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Feb 11 03:42:03 volumio1 bthelper[647]: [CHG] Controller DC:A6:32:8A:EF:8D PowerState: on-disabling Feb 11 03:42:03 volumio1 bthelper[647]: hci0 class of device changed: 0x000000 Feb 11 03:42:03 volumio1 bthelper[647]: hci0 new_settings: connectable discoverable bondable ssp br/edr le secure-conn Feb 11 03:42:03 volumio1 bthelper[647]: Changing power off succeeded Feb 11 03:42:03 volumio1 sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 11 03:42:03 volumio1 sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:03 volumio1 sudo[850]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:03 volumio1 volumio-time-update[621]: volumio-time-update-util: Fetching time from Volumio... Feb 11 03:42:03 volumio1 sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set IT Feb 11 03:42:03 volumio1 sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:03 volumio1 bthelper[647]: [NEW] Media /org/bluez/hci0 Feb 11 03:42:03 volumio1 bthelper[647]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 11 03:42:03 volumio1 bthelper[647]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 11 03:42:03 volumio1 bthelper[647]: [CHG] Controller DC:A6:32:8A:EF:8D PowerState: off-enabling Feb 11 03:42:03 volumio1 volumio-time-update[621]: volumio-time-update-util: Date not found in response Feb 11 03:42:03 volumio1 volumio-time-update[621]: volumio-time-update-util: Retrying in 5 seconds... Feb 11 03:42:03 volumio1 sudo[852]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:03 volumio1 wireless.js[608]: SUCCESSFULLY SET NEW REGDOMAIN: IT Feb 11 03:42:03 volumio1 volumio-remote-updater[638]: [2026-02-11 03:42:03] [info] asio async_connect error: system:111 (Connection refused) Feb 11 03:42:03 volumio1 volumio-remote-updater[638]: [2026-02-11 03:42:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 11 03:42:03 volumio1 volumio-remote-updater[638]: [2026-02-11 03:42:03] [error] handle_connect error: Underlying Transport Error Feb 11 03:42:03 volumio1 sudo[864]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 11 03:42:03 volumio1 sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:03 volumio1 sudo[864]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:03 volumio1 sudo[866]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 11 03:42:03 volumio1 sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:03 volumio1 bthelper[647]: hci0 class of device changed: 0x00041c Feb 11 03:42:03 volumio1 bthelper[647]: [CHG] Controller DC:A6:32:8A:EF:8D Class: 0x0000041c (1052) Feb 11 03:42:03 volumio1 bthelper[647]: Changing power on succeeded Feb 11 03:42:04 volumio1 sudo[866]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:04 volumio1 wireless.js[608]: Hotspot is disabled, not starting it Feb 11 03:42:04 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 11 03:42:04 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 11 03:42:04 volumio1 systemd[1]: wireless.service: Succeeded. Feb 11 03:42:04 volumio1 systemd[1]: Started Wireless Services. Feb 11 03:42:04 volumio1 systemd[1]: Started Volumio Backend Module. Feb 11 03:42:04 volumio1 systemd[1]: Started Volumio Cpu Tweaker. Feb 11 03:42:04 volumio1 volumio-cpu-tweak[873]: Setting RT Priority for mpd Feb 11 03:42:04 volumio1 volumio-cpu-tweak[873]: pid 35's current scheduling policy: SCHED_OTHER Feb 11 03:42:04 volumio1 volumio-cpu-tweak[873]: pid 35's current scheduling priority: 0 Feb 11 03:42:04 volumio1 volumio-cpu-tweak[873]: Setting MPD Affinity Feb 11 03:42:04 volumio1 volumio-cpu-tweak[873]: pid 3's current affinity mask: f Feb 11 03:42:04 volumio1 volumio-cpu-tweak[873]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 11 03:42:04 volumio1 volumio-cpu-tweak[873]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 11 03:42:04 volumio1 systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 11 03:42:05 volumio1 volumio[872]: info: ------------------------------------------- Feb 11 03:42:05 volumio1 volumio[872]: info: ----- Volumio3 ---- Feb 11 03:42:05 volumio1 volumio[872]: info: ------------------------------------------- Feb 11 03:42:05 volumio1 volumio[872]: info: ----- System startup ---- Feb 11 03:42:05 volumio1 volumio[872]: info: ------------------------------------------- Feb 11 03:42:06 volumio1 volumio[872]: info: MYVOLUMIO Environment detected Feb 11 03:42:06 volumio1 volumio[872]: info: Found new core plugin system_controller/volumio5onboarding. Adding it Feb 11 03:42:06 volumio1 volumio[872]: info: Plugin folders cleanup Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning into folder /volumio/app/plugins/ Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning category audio_interface Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning category miscellanea Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning category music_service Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning category plugins.json Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning category system_controller Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning category user_interface Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning into folder /data/plugins/ Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning category music_service Feb 11 03:42:06 volumio1 volumio[872]: info: Scanning category user_interface Feb 11 03:42:06 volumio1 volumio[872]: info: Plugin folders cleanup completed Feb 11 03:42:06 volumio1 volumio[872]: info: ------------------------------------------- Feb 11 03:42:06 volumio1 volumio[872]: info: ----- Core plugins startup ---- Feb 11 03:42:06 volumio1 volumio[872]: info: ------------------------------------------- Feb 11 03:42:06 volumio1 volumio[872]: info: Loading plugins from folder /volumio/app/plugins/ Feb 11 03:42:06 volumio1 volumio[872]: info: Adding plugin upnp to MyMusic Plugins Feb 11 03:42:06 volumio1 volumio[872]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 11 03:42:06 volumio1 volumio[872]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 11 03:42:06 volumio1 volumio[872]: info: Loading plugins from folder /data/plugins/ Feb 11 03:42:06 volumio1 volumio[872]: info: Loading plugin "system"... Feb 11 03:42:06 volumio1 volumio[872]: info: Loading plugin "appearance"... Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "network"... Feb 11 03:42:07 volumio1 volumio[872]: info: Refreshing Cached IP Addresses Feb 11 03:42:07 volumio1 sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 03:42:07 volumio1 sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:07 volumio1 sudo[903]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:07 volumio1 sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 03:42:07 volumio1 sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:07 volumio1 sudo[905]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "services"... Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "volumio5onboarding"... Feb 11 03:42:07 volumio1 sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 11 03:42:07 volumio1 sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "alsa_controller"... Feb 11 03:42:07 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "wizard"... Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "networkfs"... Feb 11 03:42:07 volumio1 sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=condivisione,password=Verona12,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.188.125/Elements1 /mnt/NAS/Elements1 Feb 11 03:42:07 volumio1 sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:07 volumio1 volumio[872]: info: Starting Udev Watcher for removable devices Feb 11 03:42:07 volumio1 sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=condivisione,password=Verona12,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.188.125/Elements2 /mnt/NAS/Elements2 Feb 11 03:42:07 volumio1 sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:07 volumio1 sudo[936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=condivisione,password=Verona12,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.188.125/Elements3 /mnt/NAS/Elements3 Feb 11 03:42:07 volumio1 sudo[936]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:07 volumio1 volumio[872]: info: Ignoring mount for partition: boot Feb 11 03:42:07 volumio1 volumio[872]: info: Ignoring mount for partition: volumio Feb 11 03:42:07 volumio1 volumio[872]: info: Ignoring mount for partition: volumio_data Feb 11 03:42:07 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "volumio_command_line_client"... Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "upnp"... Feb 11 03:42:07 volumio1 volumio[872]: info: [1770777727968] Starting Upmpd Daemon Feb 11 03:42:07 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "my_music"... Feb 11 03:42:07 volumio1 volumio[872]: info: Loading plugin "mpd"... Feb 11 03:42:08 volumio1 sudo[933]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:08 volumio1 sudo[936]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:08 volumio1 kernel: Key type cifs.spnego registered Feb 11 03:42:08 volumio1 kernel: Key type cifs.idmap registered Feb 11 03:42:08 volumio1 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 11 03:42:08 volumio1 kernel: CIFS: Attempting to mount //192.168.188.125/Elements2 Feb 11 03:42:08 volumio1 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 11 03:42:08 volumio1 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 11 03:42:08 volumio1 kernel: CIFS: Attempting to mount //192.168.188.125/Elements3 Feb 11 03:42:08 volumio1 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 11 03:42:08 volumio1 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 11 03:42:08 volumio1 kernel: CIFS: Attempting to mount //192.168.188.125/Elements1 Feb 11 03:42:08 volumio1 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 11 03:42:08 volumio1 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 11 03:42:08 volumio1 sudo[931]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:08 volumio1 volumio[872]: info: Loading plugin "upnp_browser"... Feb 11 03:42:08 volumio1 volumio[872]: info: Starting UPNP Browser Feb 11 03:42:08 volumio1 volumio[872]: info: Loading plugin "alarm-clock"... Feb 11 03:42:08 volumio1 volumio-time-update[621]: volumio-time-update-util: Fetching time from Volumio... Feb 11 03:42:08 volumio1 volumio-time-update[621]: volumio-time-update-util: Date not found in response Feb 11 03:42:08 volumio1 volumio-time-update[621]: volumio-time-update-util: Retrying in 5 seconds... Feb 11 03:42:08 volumio1 volumio[872]: info: Loading plugin "airplay_emulation"... Feb 11 03:42:08 volumio1 volumio[872]: info: Starting Shairport Sync Feb 11 03:42:08 volumio1 volumio[872]: info: Loading plugin "last_100"... Feb 11 03:42:08 volumio1 volumio[872]: info: Loading plugin "webradio"... Feb 11 03:42:08 volumio1 volumio[872]: info: Loading plugin "i2s_dacs"... Feb 11 03:42:08 volumio1 volumio[872]: info: I2S DAC not set, start Auto-detection Feb 11 03:42:08 volumio1 volumio[872]: info: Loading plugin "volumiodiscovery"... Feb 11 03:42:08 volumio1 volumio[872]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 03:42:08 volumio1 volumio[872]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 03:42:08 volumio1 node[872]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 03:42:08 volumio1 volumio[872]: *** WARNING *** For more information see Feb 11 03:42:08 volumio1 volumio[872]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 03:42:08 volumio1 volumio[872]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 03:42:08 volumio1 volumio[872]: *** WARNING *** For more information see Feb 11 03:42:08 volumio1 node[872]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 03:42:08 volumio1 node[872]: *** WARNING *** For more information see Feb 11 03:42:08 volumio1 node[872]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 03:42:08 volumio1 node[872]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 03:42:08 volumio1 node[872]: *** WARNING *** For more information see Feb 11 03:42:08 volumio1 volumio[872]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 11 03:42:08 volumio1 volumio[872]: info: Discovery: Started advertising with name: Volumio1 Feb 11 03:42:08 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 03:42:08 volumio1 volumio[872]: info: Loading plugin "bandcamp"... Feb 11 03:42:09 volumio1 volumio[872]: info: Loading plugin "spop"... Feb 11 03:42:10 volumio1 sudo[918]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:10 volumio1 volumio[872]: info: Loading plugin "youtube2"... Feb 11 03:42:11 volumio1 volumio-remote-updater[638]: [2026-02-11 03:42:11] [connect] Successful connection Feb 11 03:42:12 volumio1 sh[524]: eth0: carrier acquired Feb 11 03:42:12 volumio1 dhcpcd[574]: eth0: carrier acquired Feb 11 03:42:12 volumio1 sh[524]: DUID 00:01:00:01:2c:c5:40:e6:dc:a6:32:8a:ef:89 Feb 11 03:42:12 volumio1 sh[524]: eth0: IAID 32:8a:ef:89 Feb 11 03:42:12 volumio1 dhcpcd[574]: DUID 00:01:00:01:2c:c5:40:e6:dc:a6:32:8a:ef:89 Feb 11 03:42:12 volumio1 dhcpcd[574]: eth0: IAID 32:8a:ef:89 Feb 11 03:42:12 volumio1 ifplugd(eth0)[774]: Link beat detected. Feb 11 03:42:12 volumio1 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Feb 11 03:42:12 volumio1 sh[524]: eth0: adding address fe80::2fcc:5cf6:1cd7:83f Feb 11 03:42:12 volumio1 sh[524]: ipv6_addaddr1: Permission denied Feb 11 03:42:12 volumio1 dhcpcd[574]: eth0: adding address fe80::2fcc:5cf6:1cd7:83f Feb 11 03:42:12 volumio1 dhcpcd[574]: ipv6_addaddr1: Permission denied Feb 11 03:42:12 volumio1 ifplugd(eth0)[774]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 11 03:42:12 volumio1 ifplugd(eth0)[774]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Feb 11 03:42:12 volumio1 sh[524]: eth0: soliciting an IPv6 router Feb 11 03:42:12 volumio1 dhcpcd[574]: eth0: soliciting an IPv6 router Feb 11 03:42:12 volumio1 sh[524]: eth0: soliciting a DHCP lease Feb 11 03:42:12 volumio1 dhcpcd[574]: eth0: soliciting a DHCP lease Feb 11 03:42:12 volumio1 sh[524]: eth0: offered 192.168.188.43 from 192.168.188.1 Feb 11 03:42:12 volumio1 dhcpcd[574]: eth0: offered 192.168.188.43 from 192.168.188.1 Feb 11 03:42:12 volumio1 sh[524]: eth0: probing address 192.168.188.43/24 Feb 11 03:42:12 volumio1 dhcpcd[574]: eth0: probing address 192.168.188.43/24 Feb 11 03:42:13 volumio1 volumio[872]: info: Loading plugin "now_playing"... Feb 11 03:42:13 volumio1 volumio-time-update[621]: volumio-time-update-util: Fetching time from Volumio... Feb 11 03:42:13 volumio1 volumio-time-update[621]: volumio-time-update-util: Date not found in response Feb 11 03:42:13 volumio1 volumio-time-update[621]: volumio-time-update-util: Retrying in 5 seconds... Feb 11 03:42:14 volumio1 volumio[872]: info: Loading plugin "outputs"... Feb 11 03:42:14 volumio1 volumio[872]: info: Loading plugin "albumart"... Feb 11 03:42:14 volumio1 volumio[872]: info: Plugin example_plugin is not enabled Feb 11 03:42:14 volumio1 volumio[872]: info: Loading plugin "inputs"... Feb 11 03:42:14 volumio1 volumio[872]: info: Loading plugin "updater_comm"... Feb 11 03:42:14 volumio1 volumio[872]: info: Plugin mpdemulation is not enabled Feb 11 03:42:14 volumio1 volumio[872]: info: Loading plugin "rest_api"... Feb 11 03:42:14 volumio1 volumio[872]: info: Loading plugin "websocket"... Feb 11 03:42:14 volumio1 volumio[872]: info: Starting Socket.io Server version 2.3.0 Feb 11 03:42:14 volumio1 volumio[872]: info: Loading plugin "80s80s"... Feb 11 03:42:14 volumio1 volumio[872]: Forking 3 albumart workers Feb 11 03:42:14 volumio1 volumio[872]: info: Applying required configuration parameters for plugin 80s80s Feb 11 03:42:14 volumio1 volumio[872]: info: [1770777734720] [80s80s] API delay: 30 Feb 11 03:42:14 volumio1 volumio[872]: info: Loading plugin "motherearthradio"... Feb 11 03:42:14 volumio1 volumio[872]: Starting albumart workers Feb 11 03:42:14 volumio1 volumio[872]: Starting albumart workers Feb 11 03:42:15 volumio1 volumio[872]: Starting albumart workers Feb 11 03:42:15 volumio1 volumio[872]: info: Applying required configuration parameters for plugin motherearthradio Feb 11 03:42:15 volumio1 volumio[872]: info: [1770777735024] [MotherEarth] API delay: 5 Feb 11 03:42:15 volumio1 volumio[872]: info: Loading plugin "Systeminfo"... Feb 11 03:42:15 volumio1 volumio[872]: info: Loading plugin "lastfm"... Feb 11 03:42:16 volumio1 volumio[872]: info: Loading i18n strings for locale it Feb 11 03:42:16 volumio1 volumio[872]: Updating browse sources language Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::initPlayerControls Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: Express server listening on port 3000 Feb 11 03:42:16 volumio1 volumio[872]: [Metrics] WebUI: 10s 990.47ms Feb 11 03:42:16 volumio1 volumio[872]: info: CoreStateMachine::resetVolumioState Feb 11 03:42:16 volumio1 volumio[872]: info: CoreStateMachine::getcurrentVolume Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: Volumio Network Manager: Network status updated: 0 Feb 11 03:42:16 volumio1 volumio[872]: Unable to parse: Feb 11 03:42:16 volumio1 volumio[872]: Simple mixer control 'Cambridge AudioDAC100 USB 1 Audio In Playba',0 Feb 11 03:42:16 volumio1 volumio[872]: Capabilities: pswitch Feb 11 03:42:16 volumio1 volumio[872]: Playback channels: Front Left - Front Right Feb 11 03:42:16 volumio1 volumio[872]: Mono: Feb 11 03:42:16 volumio1 volumio[872]: Front Left: Playback [on] Feb 11 03:42:16 volumio1 volumio[872]: Front Right: Playback [on] Feb 11 03:42:16 volumio1 volumio[872]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 11 03:42:16 volumio1 volumio[872]: info: Cannot mount NAS Elements3 at system boot, trial number 1 ,retrying in 5 seconds Feb 11 03:42:16 volumio1 volumio[872]: info: Cannot mount NAS Elements2 at system boot, trial number 1 ,retrying in 5 seconds Feb 11 03:42:16 volumio1 volumio[872]: info: Cannot mount NAS Elements1 at system boot, trial number 1 ,retrying in 5 seconds Feb 11 03:42:16 volumio1 volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 11 03:42:16 volumio1 volumio[872]: Unable to parse: Feb 11 03:42:16 volumio1 volumio[872]: Simple mixer control 'Cambridge AudioDAC100 USB 1 Audio In Playba',0 Feb 11 03:42:16 volumio1 volumio[872]: Capabilities: pswitch Feb 11 03:42:16 volumio1 volumio[872]: Playback channels: Front Left - Front Right Feb 11 03:42:16 volumio1 volumio[872]: Mono: Feb 11 03:42:16 volumio1 volumio[872]: Front Left: Playback [on] Feb 11 03:42:16 volumio1 volumio[872]: Front Right: Playback [on] Feb 11 03:42:16 volumio1 volumio[872]: info: VolumeController:: Volume=undefined Mute =false Feb 11 03:42:16 volumio1 volumio[872]: info: CoreStateMachine::pushState Feb 11 03:42:16 volumio1 volumio[872]: info: CorePlayQueue::getTrack 0 Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::volumioPushState Feb 11 03:42:16 volumio1 volumio[872]: info: CoreStateMachine::updateTrackBlock Feb 11 03:42:16 volumio1 volumio[872]: info: CorePlayQueue::getTrackBlock Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 03:42:16 volumio1 volumio[872]: info: Reloading queue from file Feb 11 03:42:16 volumio1 volumio[872]: info: Setting Device type: Raspberry PI Feb 11 03:42:16 volumio1 volumio[872]: info: CoreStateMachine::setRepeat null single undefined Feb 11 03:42:16 volumio1 volumio[872]: info: CoreStateMachine::pushState Feb 11 03:42:16 volumio1 volumio[872]: info: CorePlayQueue::getTrack 0 Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::volumioPushState Feb 11 03:42:16 volumio1 volumio[872]: info: CoreStateMachine::setRandom null Feb 11 03:42:16 volumio1 volumio[872]: info: CoreStateMachine::pushState Feb 11 03:42:16 volumio1 volumio[872]: info: CorePlayQueue::getTrack 0 Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::volumioPushState Feb 11 03:42:16 volumio1 volumio[872]: Unable to parse: Feb 11 03:42:16 volumio1 volumio[872]: Simple mixer control 'Cambridge AudioDAC100 USB 1 Audio In Playba',0 Feb 11 03:42:16 volumio1 volumio[872]: Capabilities: pswitch Feb 11 03:42:16 volumio1 volumio[872]: Playback channels: Front Left - Front Right Feb 11 03:42:16 volumio1 volumio[872]: Mono: Feb 11 03:42:16 volumio1 volumio[872]: Front Left: Playback [on] Feb 11 03:42:16 volumio1 volumio[872]: Front Right: Playback [on] Feb 11 03:42:16 volumio1 volumio[872]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 11 03:42:16 volumio1 volumio[872]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 11 03:42:16 volumio1 volumio[872]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Feb 11 03:42:16 volumio1 volumio[872]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 11 03:42:16 volumio1 volumio[872]: Unable to parse: Feb 11 03:42:16 volumio1 volumio[872]: Simple mixer control 'Cambridge AudioDAC100 USB 1 Audio In Playba',0 Feb 11 03:42:16 volumio1 volumio[872]: Capabilities: pswitch Feb 11 03:42:16 volumio1 volumio[872]: Playback channels: Front Left - Front Right Feb 11 03:42:16 volumio1 volumio[872]: Mono: Feb 11 03:42:16 volumio1 volumio[872]: Front Left: Playback [on] Feb 11 03:42:16 volumio1 volumio[872]: Front Right: Playback [on] Feb 11 03:42:16 volumio1 volumio[872]: info: VolumeController:: Volume=undefined Mute =false Feb 11 03:42:16 volumio1 volumio[872]: info: CoreStateMachine::pushState Feb 11 03:42:16 volumio1 volumio[872]: info: CorePlayQueue::getTrack 0 Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::volumioPushState Feb 11 03:42:16 volumio1 volumio[872]: info: Completed loading Core Plugins Feb 11 03:42:16 volumio1 volumio[872]: info: Preparing to generate the ALSA configuration file Feb 11 03:42:16 volumio1 volumio[872]: info: Asound.conf file unchanged, so no further update is needed Feb 11 03:42:16 volumio1 volumio[872]: info: Output device has changed, restarting MPD Feb 11 03:42:16 volumio1 sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 03:42:16 volumio1 sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:16 volumio1 volumio[872]: info: Output device has changed, restarting Shairport Sync Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 03:42:16 volumio1 sudo[1040]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:16 volumio1 sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 03:42:16 volumio1 sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:16 volumio1 volumio[872]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 03:42:16 volumio1 volumio[872]: info: ___________ START PLUGINS ___________ Feb 11 03:42:16 volumio1 volumio[872]: info: ControllerMpd::onStart: Initializing MPD Feb 11 03:42:16 volumio1 volumio[872]: info: Creating MPD Configuration file Feb 11 03:42:16 volumio1 sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 11 03:42:16 volumio1 sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:16 volumio1 systemd[1]: Listening on mpd.socket. Feb 11 03:42:16 volumio1 systemd[1]: Starting Music Player Daemon... Feb 11 03:42:16 volumio1 sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 03:42:16 volumio1 sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:16 volumio1 sudo[1053]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 03:42:16 volumio1 systemd[1]: Started Volumio5 Onboarding Server. Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 03:42:16 volumio1 volumio[872]: info: [1770777736901] CoreMusicLibrary::Adding element Server multimediali Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 03:42:16 volumio1 sudo[1050]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:16 volumio1 volumio[872]: info: UPNP Browser: Client initialized successfully Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 03:42:16 volumio1 sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 03:42:16 volumio1 sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:16 volumio1 volumio[872]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:16 volumio1 sudo[1054]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 03:42:16 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 03:42:16 volumio1 sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:16 volumio1 sudo[1054]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:16 volumio1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 11 03:42:16 volumio1 systemd[1]: mpd.service: Succeeded. Feb 11 03:42:16 volumio1 systemd[1]: Stopped Music Player Daemon. Feb 11 03:42:16 volumio1 systemd[1]: Starting Music Player Daemon... Feb 11 03:42:16 volumio1 volumio[872]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 03:42:17 volumio1 volumio[872]: info: [1770777737001] CoreMusicLibrary::Adding element Last_100 Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 03:42:17 volumio1 volumio[872]: info: [1770777737003] CoreMusicLibrary::Adding element Webradio Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 03:42:17 volumio1 volumio[872]: info: Initializing BBC Radios Feb 11 03:42:17 volumio1 sudo[1063]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 03:42:17 volumio1 sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 11 03:42:17 volumio1 sudo[1063]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 11 03:42:17 volumio1 sudo[1063]: pam_unix(sudo:session): session closed for user root Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 03:42:17 volumio1 volumio[872]: info: [1770777737097] CoreMusicLibrary::Adding element Bandcamp Discover Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source Bandcamp Discover Feb 11 03:42:17 volumio1 volumio[872]: info: Creating Spotify config file Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 03:42:17 volumio1 volumio[872]: info: [1770777737166] CoreMusicLibrary::Adding element YouTube2 Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source Bandcamp Discover Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source YouTube2 Feb 11 03:42:17 volumio1 volumio[872]: info: [now-playing] ConfigUpdater: config is up to date. Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 03:42:17 volumio1 volumio[872]: info: [1770777737193] CoreMusicLibrary::Adding element 80s80s Radio Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source Bandcamp Discover Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source YouTube2 Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source 80s80s Radio Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 03:42:17 volumio1 volumio[872]: info: [1770777737204] CoreMusicLibrary::Adding element Mother Earth Radio Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source Bandcamp Discover Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source YouTube2 Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source 80s80s Radio Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source Mother Earth Radio Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 03:42:17 volumio1 volumio[872]: info: [1770777737207] CoreMusicLibrary::Adding element LastFM Feb 11 03:42:17 volumio1 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source Bandcamp Discover Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source YouTube2 Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source 80s80s Radio Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source Mother Earth Radio Feb 11 03:42:17 volumio1 volumio[872]: Cannot find translation for source LastFM Feb 11 03:42:17 volumio1 volumio[872]: info: [LastFM] scrobbler initiated! Feb 11 03:42:17 volumio1 volumio[872]: info: [LastFM] extended logging: false Feb 11 03:42:17 volumio1 volumio[872]: info: [LastFM] try scrobble stream/radio plays: true Feb 11 03:42:17 volumio1 volumio[872]: info: [LastFM] Left init routine Feb 11 03:42:17 volumio1 volumio[872]: info: [LastFM] Socket already connected: true Feb 11 03:42:17 volumio1 volumio[872]: info: Volumio Calling Home Feb 11 03:42:17 volumio1 volumio[872]: info: [now-playing] App is listening on port 4004. Feb 11 03:42:17 volumio1 volumio[872]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 11 03:42:17 volumio1 volumio[872]: Unhandled rejection Error: No sockets available, cannot start. Feb 11 03:42:17 volumio1 volumio[872]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 11 03:42:17 volumio1 volumio[872]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 11 03:42:17 volumio1 volumio[872]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 11 03:42:17 volumio1 volumio[872]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 11 03:42:17 volumio1 volumio[872]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 11 03:42:17 volumio1 volumio[872]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 11 03:42:17 volumio1 volumio[872]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 11 03:42:17 volumio1 volumio[872]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 11 03:42:17 volumio1 volumio[872]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Feb 11 03:42:17 volumio1 volumio[872]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 11 03:42:17 volumio1 volumio[872]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 11 03:42:17 volumio1 volumio[872]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 11 03:42:17 volumio1 volumio[872]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 11 03:42:17 volumio1 volumio[872]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 11 03:42:17 volumio1 volumio[872]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 11 03:42:17 volumio1 volumio[872]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 11 03:42:17 volumio1 volumio[872]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Feb 11 03:42:17 volumio1 volumio[872]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 03:42:17 volumio1 volumio[872]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Feb 11 03:42:17 volumio1 volumio[872]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Feb 11 03:42:17 volumio1 volumio[872]: errno: -16, Feb 11 03:42:17 volumio1 volumio[872]: code: 'EBUSY', Feb 11 03:42:17 volumio1 volumio[872]: syscall: 'getaddrinfo', Feb 11 03:42:17 volumio1 volumio[872]: hostname: 'ws.audioscrobbler.com' Feb 11 03:42:17 volumio1 volumio[872]: } Feb 11 03:42:17 volumio1 volumio[872]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 03:42:17 volumio1 volumio5-onboarding[1058]: time=2026-02-11T03:42:17.369+01:00 level=INFO msg="running volumio5-device-gateway" version=30d1950e+CHANGES buildDate=2026-01-29T13:10:08Z Feb 11 03:42:17 volumio1 sh[524]: eth0: leased 192.168.188.43 for 864000 seconds Feb 11 03:42:17 volumio1 sh[524]: eth0: adding route to 192.168.188.0/24 Feb 11 03:42:17 volumio1 sh[524]: eth0: adding default route via 192.168.188.1 Feb 11 03:42:17 volumio1 dhcpcd[574]: eth0: leased 192.168.188.43 for 864000 seconds Feb 11 03:42:17 volumio1 dhcpcd[574]: eth0: adding route to 192.168.188.0/24 Feb 11 03:42:17 volumio1 dhcpcd[574]: eth0: adding default route via 192.168.188.1 Feb 11 03:42:17 volumio1 avahi-daemon[637]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.188.43. Feb 11 03:42:17 volumio1 avahi-daemon[637]: New relevant interface eth0.IPv4 for mDNS. Feb 11 03:42:17 volumio1 avahi-daemon[637]: Registering new address record for 192.168.188.43 on eth0.IPv4. Feb 11 03:42:17 volumio1 sh[524]: forked to background, child pid 1119 Feb 11 03:42:17 volumio1 dhcpcd[574]: forked to background, child pid 1119 Feb 11 03:42:17 volumio1 sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-11 03:41 Feb 11 03:42:17 volumio1 sudo[1121]: 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"