Jan 09 05:00:01 pi2aes kernel: PCI: CLS 0 bytes, default 64 Jan 09 05:00:01 pi2aes kernel: Trying to unpack rootfs image as initramfs... Jan 09 05:00:01 pi2aes kernel: Initialise system trusted keyrings Jan 09 05:00:01 pi2aes kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Jan 09 05:00:01 pi2aes kernel: NFS: Registering the id_resolver key type Jan 09 05:00:01 pi2aes kernel: Key type id_resolver registered Jan 09 05:00:01 pi2aes kernel: Key type id_legacy registered Jan 09 05:00:01 pi2aes kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jan 09 05:00:01 pi2aes kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Jan 09 05:00:01 pi2aes kernel: Key type asymmetric registered Jan 09 05:00:01 pi2aes kernel: Asymmetric key parser 'x509' registered Jan 09 05:00:01 pi2aes kernel: bounce: pool size: 64 pages Jan 09 05:00:01 pi2aes kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Jan 09 05:00:01 pi2aes kernel: io scheduler mq-deadline registered Jan 09 05:00:01 pi2aes kernel: io scheduler kyber registered Jan 09 05:00:01 pi2aes kernel: io scheduler bfq registered Jan 09 05:00:01 pi2aes kernel: pinctrl-bcm2835 fe200000.gpio: GPIO_OUT persistence: yes Jan 09 05:00:01 pi2aes kernel: Freeing initrd memory: 35752K Jan 09 05:00:01 pi2aes kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 09 05:00:01 pi2aes kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Jan 09 05:00:01 pi2aes kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Jan 09 05:00:01 pi2aes kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Jan 09 05:00:01 pi2aes kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Jan 09 05:00:01 pi2aes kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Jan 09 05:00:01 pi2aes kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jan 09 05:00:01 pi2aes kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Jan 09 05:00:01 pi2aes kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 PCIe Root Port Jan 09 05:00:01 pi2aes kernel: pci 0000:00:00.0: PCI bridge to [bus 00] Jan 09 05:00:01 pi2aes kernel: pci 0000:00:00.0: bridge window [mem 0x80000000-0xbfffffff] Jan 09 05:00:01 pi2aes kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Jan 09 05:00:01 pi2aes kernel: PCI: bus0: Fast back to back transfers disabled Jan 09 05:00:01 pi2aes kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jan 09 05:00:01 pi2aes kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Jan 09 05:00:01 pi2aes kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Jan 09 05:00:01 pi2aes kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Jan 09 05:00:01 pi2aes kernel: brcm-pcie fd500000.pcie: clkreq-mode set to default Jan 09 05:00:01 pi2aes kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Jan 09 05:00:01 pi2aes kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 PCIe Endpoint Jan 09 05:00:01 pi2aes kernel: pci 0000:01:00.0: BAR 0 [mem 0x00000000-0x00000fff 64bit] Jan 09 05:00:01 pi2aes kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Jan 09 05:00:01 pi2aes kernel: PCI: bus1: Fast back to back transfers disabled Jan 09 05:00:01 pi2aes kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Jan 09 05:00:01 pi2aes kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]: assigned Jan 09 05:00:01 pi2aes kernel: pci 0000:01:00.0: BAR 0 [mem 0x600000000-0x600000fff 64bit]: assigned Jan 09 05:00:01 pi2aes kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Jan 09 05:00:01 pi2aes kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Jan 09 05:00:01 pi2aes kernel: pci_bus 0000:00: resource 4 [mem 0x600000000-0x63fffffff] Jan 09 05:00:01 pi2aes kernel: pci_bus 0000:01: resource 1 [mem 0x600000000-0x6000fffff] Jan 09 05:00:01 pi2aes kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Jan 09 05:00:01 pi2aes kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 41 Jan 09 05:00:01 pi2aes kernel: bcm2708_fb soc:fb: FB found 1 display(s) Jan 09 05:00:01 pi2aes kernel: Console: switching to colour frame buffer device 80x30 Jan 09 05:00:01 pi2aes kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Jan 09 05:00:01 pi2aes kernel: iproc-rng200 fe104000.rng: hwrng registered Jan 09 05:00:01 pi2aes kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Jan 09 05:00:01 pi2aes kernel: brd: module loaded Jan 09 05:00:01 pi2aes kernel: loop: module loaded Jan 09 05:00:01 pi2aes kernel: Loading iSCSI transport class v2.0-870. Jan 09 05:00:01 pi2aes kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Jan 09 05:00:01 pi2aes kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Jan 09 05:00:01 pi2aes kernel: usbcore: registered new interface driver lan78xx Jan 09 05:00:01 pi2aes kernel: usbcore: registered new interface driver smsc95xx Jan 09 05:00:01 pi2aes kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Jan 09 05:00:01 pi2aes kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 09 05:00:01 pi2aes kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Jan 09 05:00:01 pi2aes kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0300240000000890 Jan 09 05:00:01 pi2aes kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 09 05:00:01 pi2aes kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Jan 09 05:00:01 pi2aes kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Jan 09 05:00:01 pi2aes kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 Jan 09 05:00:01 pi2aes kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 09 05:00:01 pi2aes kernel: usb usb1: Product: xHCI Host Controller Jan 09 05:00:01 pi2aes kernel: usb usb1: Manufacturer: Linux 6.12.47-v7l+ xhci-hcd Jan 09 05:00:01 pi2aes kernel: usb usb1: SerialNumber: 0000:01:00.0 Jan 09 05:00:01 pi2aes kernel: hub 1-0:1.0: USB hub found Jan 09 05:00:01 pi2aes kernel: hub 1-0:1.0: 1 port detected Jan 09 05:00:01 pi2aes kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 Jan 09 05:00:01 pi2aes kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 09 05:00:01 pi2aes kernel: usb usb2: Product: xHCI Host Controller Jan 09 05:00:01 pi2aes kernel: usb usb2: Manufacturer: Linux 6.12.47-v7l+ xhci-hcd Jan 09 05:00:01 pi2aes kernel: usb usb2: SerialNumber: 0000:01:00.0 Jan 09 05:00:01 pi2aes kernel: hub 2-0:1.0: USB hub found Jan 09 05:00:01 pi2aes kernel: hub 2-0:1.0: 4 ports detected Jan 09 05:00:01 pi2aes kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jan 09 05:00:01 pi2aes kernel: dwc_otg: FIQ enabled Jan 09 05:00:01 pi2aes kernel: dwc_otg: NAK holdoff enabled Jan 09 05:00:01 pi2aes kernel: dwc_otg: FIQ split-transaction FSM enabled Jan 09 05:00:01 pi2aes kernel: Module dwc_common_port init Jan 09 05:00:01 pi2aes kernel: usbcore: registered new interface driver uas Jan 09 05:00:01 pi2aes kernel: usbcore: registered new interface driver usb-storage Jan 09 05:00:01 pi2aes kernel: mousedev: PS/2 mouse device common for all mice Jan 09 05:00:01 pi2aes kernel: sdhci: Secure Digital Host Controller Interface driver Jan 09 05:00:01 pi2aes kernel: sdhci: Copyright(c) Pierre Ossman Jan 09 05:00:01 pi2aes kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jan 09 05:00:01 pi2aes kernel: hid: raw HID events driver (C) Jiri Kosina Jan 09 05:00:01 pi2aes kernel: usbcore: registered new interface driver usbhid Jan 09 05:00:01 pi2aes kernel: usbhid: USB HID core driver Jan 09 05:00:01 pi2aes kernel: Initializing XFRM netlink socket Jan 09 05:00:01 pi2aes kernel: NET: Registered PF_PACKET protocol family Jan 09 05:00:01 pi2aes kernel: Key type dns_resolver registered Jan 09 05:00:01 pi2aes kernel: Registering SWP/SWPB emulation handler Jan 09 05:00:01 pi2aes kernel: registered taskstats version 1 Jan 09 05:00:01 pi2aes kernel: Loading compiled-in X.509 certificates Jan 09 05:00:01 pi2aes kernel: Key type .fscrypt registered Jan 09 05:00:01 pi2aes kernel: Key type fscrypt-provisioning registered Jan 09 05:00:01 pi2aes kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Jan 09 05:00:01 pi2aes kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Jan 09 05:00:01 pi2aes kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 50, base_baud = 0) is a PL011 rev3 Jan 09 05:00:01 pi2aes kernel: serial serial0: tty port ttyAMA1 registered Jan 09 05:00:01 pi2aes kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jan 09 05:00:01 pi2aes kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jan 09 05:00:01 pi2aes kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jan 09 05:00:01 pi2aes kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Jan 09 05:00:01 pi2aes kernel: of_cfs_init Jan 09 05:00:01 pi2aes kernel: of_cfs_init: OK Jan 09 05:00:01 pi2aes kernel: clk: Disabling unused clocks Jan 09 05:00:01 pi2aes kernel: PM: genpd: Disabling unused power domains Jan 09 05:00:01 pi2aes kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Jan 09 05:00:01 pi2aes kernel: Freeing unused kernel image (initmem) memory: 2048K Jan 09 05:00:01 pi2aes kernel: Run /init as init process Jan 09 05:00:01 pi2aes kernel: with arguments: Jan 09 05:00:01 pi2aes kernel: /init Jan 09 05:00:01 pi2aes kernel: splash Jan 09 05:00:01 pi2aes kernel: nodebug Jan 09 05:00:01 pi2aes kernel: with environment: Jan 09 05:00:01 pi2aes kernel: HOME=/ Jan 09 05:00:01 pi2aes kernel: TERM=linux Jan 09 05:00:01 pi2aes kernel: numa_policy=interleave Jan 09 05:00:01 pi2aes kernel: numa=fake=2 Jan 09 05:00:01 pi2aes kernel: imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 Jan 09 05:00:01 pi2aes kernel: imgfile=/volumio_current.sqsh Jan 09 05:00:01 pi2aes kernel: bootpart=UUID=3B89-0B23 Jan 09 05:00:01 pi2aes kernel: datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 Jan 09 05:00:01 pi2aes kernel: uuidconfig=cmdline.txt Jan 09 05:00:01 pi2aes kernel: bootdelay=7 Jan 09 05:00:01 pi2aes kernel: use_kmsg=no Jan 09 05:00:01 pi2aes kernel: mmc1: new high speed SDIO card at address 0001 Jan 09 05:00:01 pi2aes kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Jan 09 05:00:01 pi2aes kernel: mmcblk0: mmc0:aaaa SP32G 29.7 GiB Jan 09 05:00:01 pi2aes kernel: mmcblk0: p1 p2 p3 Jan 09 05:00:01 pi2aes kernel: mmcblk0: mmc0:aaaa SP32G 29.7 GiB (quirks 0x0000c000) Jan 09 05:00:01 pi2aes kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Jan 09 05:00:01 pi2aes kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Jan 09 05:00:01 pi2aes kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Jan 09 05:00:01 pi2aes kernel: usb 1-1: Product: USB2.0 Hub Jan 09 05:00:01 pi2aes kernel: hub 1-1:1.0: USB hub found Jan 09 05:00:01 pi2aes kernel: hub 1-1:1.0: 4 ports detected Jan 09 05:00:01 pi2aes kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: bcm2835-audio bcm2835-audio: card created with 4 channels Jan 09 05:00:01 pi2aes kernel: bcm2835-audio bcm2835-audio: card created with 4 channels Jan 09 05:00:01 pi2aes kernel: fuse: init (API version 7.41) Jan 09 05:00:01 pi2aes kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 09 05:00:01 pi2aes kernel: fbtft: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: fb_ili9340: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: SPI driver fb_ili9340 has no spi_device_id for ilitek,ili9340 Jan 09 05:00:01 pi2aes kernel: fb_ili9341: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: fb_st7735r: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: SPI driver fb_st7735r has no spi_device_id for sitronix,st7735r Jan 09 05:00:01 pi2aes kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,sainsmart18 Jan 09 05:00:01 pi2aes kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18 Jan 09 05:00:01 pi2aes kernel: SPI driver fb_st7735r has no spi_device_id for fbtft,adafruit18_green Jan 09 05:00:01 pi2aes kernel: fb_st7789v: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: SPI driver fb_st7789v has no spi_device_id for sitronix,st7789v Jan 09 05:00:01 pi2aes kernel: SPI driver fb_st7789v has no spi_device_id for fbtft,minipitft13 Jan 09 05:00:01 pi2aes kernel: fb_hx8357d: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: SPI driver fb_hx8357d has no spi_device_id for himax,hx8357d Jan 09 05:00:01 pi2aes kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 09 05:00:01 pi2aes kernel: EXT4-fs (mmcblk0p2): mounted filesystem dafa3844-b779-48cd-9b4c-01ecfd09e0f4 r/w with ordered data mode. Quota mode: none. Jan 09 05:00:01 pi2aes kernel: loop0: detected capacity change from 0 to 1423840 Jan 09 05:00:01 pi2aes kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 09 05:00:01 pi2aes kernel: EXT4-fs (mmcblk0p3): mounted filesystem 752d19ad-b702-471d-847a-f79ae83515d0 r/w with ordered data mode. Quota mode: none. Jan 09 05:00:01 pi2aes systemd[1]: System time before build time, advancing clock. Jan 09 05:00:01 pi2aes kernel: NET: Registered PF_INET6 protocol family Jan 09 05:00:01 pi2aes kernel: Segment Routing with IPv6 Jan 09 05:00:01 pi2aes kernel: In-situ OAM (IOAM) with IPv6 Jan 09 05:00:01 pi2aes systemd[1]: systemd 252.39-1~deb12u1+rpi1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Jan 09 05:00:01 pi2aes systemd[1]: Detected architecture arm. Jan 09 05:00:01 pi2aes systemd[1]: Hostname set to . Jan 09 05:00:01 pi2aes systemd[1]: Queued start job for default target graphical.target. Jan 09 05:00:01 pi2aes systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 09 05:00:01 pi2aes systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jan 09 05:00:01 pi2aes systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jan 09 05:00:01 pi2aes systemd[1]: Created slice user.slice - User and Session Slice. Jan 09 05:00:01 pi2aes systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jan 09 05:00:01 pi2aes systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jan 09 05:00:01 pi2aes systemd[1]: Expecting device dev-disk-by\x2duuid-3B89\x2d0B23.device - /dev/disk/by-uuid/3B89-0B23... Jan 09 05:00:01 pi2aes systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jan 09 05:00:01 pi2aes systemd[1]: Reached target slices.target - Slice Units. Jan 09 05:00:01 pi2aes systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jan 09 05:00:01 pi2aes systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. Jan 09 05:00:01 pi2aes systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. Jan 09 05:00:01 pi2aes systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. Jan 09 05:00:01 pi2aes systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Jan 09 05:00:01 pi2aes systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jan 09 05:00:01 pi2aes systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jan 09 05:00:01 pi2aes systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 09 05:00:01 pi2aes systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 09 05:00:01 pi2aes systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Jan 09 05:00:01 pi2aes systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jan 09 05:00:01 pi2aes systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jan 09 05:00:01 pi2aes systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jan 09 05:00:01 pi2aes systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jan 09 05:00:01 pi2aes systemd[1]: Started dynamicswap.service - dynamicswap service. Jan 09 05:00:01 pi2aes systemd[1]: Reached target swap.target - Swaps. Jan 09 05:00:01 pi2aes systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... Jan 09 05:00:01 pi2aes systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 09 05:00:01 pi2aes systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jan 09 05:00:01 pi2aes systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 09 05:00:01 pi2aes systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 09 05:00:01 pi2aes systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 09 05:00:01 pi2aes systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jan 09 05:00:01 pi2aes systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-journald.service - Journal Service... Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jan 09 05:00:01 pi2aes kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 09 05:00:01 pi2aes systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jan 09 05:00:01 pi2aes systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jan 09 05:00:01 pi2aes systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jan 09 05:00:01 pi2aes systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 09 05:00:01 pi2aes systemd[1]: modprobe@configfs.service: Deactivated successfully. Jan 09 05:00:01 pi2aes systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jan 09 05:00:01 pi2aes systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 09 05:00:01 pi2aes systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 09 05:00:01 pi2aes systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 09 05:00:01 pi2aes systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 09 05:00:01 pi2aes systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 09 05:00:01 pi2aes systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 09 05:00:01 pi2aes systemd[1]: modprobe@fuse.service: Deactivated successfully. Jan 09 05:00:01 pi2aes systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jan 09 05:00:01 pi2aes systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 09 05:00:01 pi2aes systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 09 05:00:01 pi2aes systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jan 09 05:00:01 pi2aes systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jan 09 05:00:01 pi2aes systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 09 05:00:01 pi2aes systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. Jan 09 05:00:01 pi2aes systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jan 09 05:00:01 pi2aes systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jan 09 05:00:01 pi2aes kernel: i2c_dev: i2c /dev entries driver Jan 09 05:00:01 pi2aes systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jan 09 05:00:01 pi2aes systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 09 05:00:01 pi2aes systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-sysusers.service - Create System Users... Jan 09 05:00:01 pi2aes systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 09 05:00:01 pi2aes systemd-journald[432]: Journal started Jan 09 05:00:01 pi2aes systemd-journald[432]: Runtime Journal (/run/log/journal/35c4ab2cb79e0973fdebde8c693341bc) is 3.9M, max 30.0M, 26.0M free. Jan 09 05:00:01 pi2aes systemd[1]: dynamicswap.service: Deactivated successfully. Jan 09 05:00:01 pi2aes systemd-modules-load[437]: Inserted module 'i2c_dev' Jan 09 05:00:01 pi2aes fake-hwclock[443]: Fri Jan 9 04:00:01 UTC 2026 Jan 09 05:00:01 pi2aes systemd[1]: Started systemd-journald.service - Journal Service. Jan 09 05:00:01 pi2aes systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. Jan 09 05:00:01 pi2aes systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 09 05:00:01 pi2aes systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 09 05:00:01 pi2aes systemd[1]: Finished systemd-sysusers.service - Create System Users. Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 09 05:00:01 pi2aes systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 09 05:00:01 pi2aes systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 09 05:00:01 pi2aes systemd-udevd[457]: Using default interface naming scheme 'v252'. Jan 09 05:00:01 pi2aes systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 09 05:00:01 pi2aes systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... Jan 09 05:00:01 pi2aes systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 09 05:00:01 pi2aes (udev-worker)[472]: Network interface NamePolicy= disabled on kernel command line. Jan 09 05:00:01 pi2aes kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jan 09 05:00:01 pi2aes kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jan 09 05:00:01 pi2aes kernel: mc: Linux media interface: v0.10 Jan 09 05:00:01 pi2aes systemd[1]: Found device dev-disk-by\x2duuid-3B89\x2d0B23.device - /dev/disk/by-uuid/3B89-0B23. Jan 09 05:00:01 pi2aes systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-3B89\x2d0B23.service - File System Check on /dev/disk/by-uuid/3B89-0B23... Jan 09 05:00:01 pi2aes systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. Jan 09 05:00:01 pi2aes kernel: videodev: Linux video capture interface: v2.00 Jan 09 05:00:01 pi2aes kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 09 05:00:01 pi2aes kernel: [vc_sm_connected_init]: start Jan 09 05:00:01 pi2aes kernel: [vc_sm_connected_init]: installed successfully Jan 09 05:00:01 pi2aes kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 09 05:00:01 pi2aes kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 09 05:00:01 pi2aes systemd-fsck[560]: fsck.fat 4.2 (2021-01-31) Jan 09 05:00:01 pi2aes systemd-fsck[560]: /dev/mmcblk0p1: 518 files, 23705/93500 clusters Jan 09 05:00:01 pi2aes systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-3B89\x2d0B23.service - File System Check on /dev/disk/by-uuid/3B89-0B23. Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jan 09 05:00:01 pi2aes kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jan 09 05:00:01 pi2aes systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. Jan 09 05:00:02 pi2aes kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 09 05:00:02 pi2aes systemd[1]: Mounting boot.mount - /boot... Jan 09 05:00:02 pi2aes systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 09 05:00:02 pi2aes systemd[1]: Mounting tmp.mount - /tmp... Jan 09 05:00:02 pi2aes systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 09 05:00:02 pi2aes systemd[1]: Mounting var-log.mount - /var/log... Jan 09 05:00:02 pi2aes systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... Jan 09 05:00:02 pi2aes systemd[1]: Mounted boot.mount - /boot. Jan 09 05:00:02 pi2aes systemd[1]: Mounted tmp.mount - /tmp. Jan 09 05:00:02 pi2aes systemd[1]: Mounted var-log.mount - /var/log. Jan 09 05:00:02 pi2aes systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. Jan 09 05:00:02 pi2aes systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... Jan 09 05:00:02 pi2aes kernel: rpi-hevc-dec feb00000.codec: Device registered as /dev/video19 Jan 09 05:00:02 pi2aes systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... Jan 09 05:00:02 pi2aes systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jan 09 05:00:02 pi2aes systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. Jan 09 05:00:02 pi2aes systemd[1]: Reached target local-fs.target - Local File Systems. Jan 09 05:00:02 pi2aes systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... Jan 09 05:00:02 pi2aes systemd[1]: Starting plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot... Jan 09 05:00:02 pi2aes kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jan 09 05:00:02 pi2aes kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jan 09 05:00:02 pi2aes kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 09 05:00:02 pi2aes kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jan 09 05:00:02 pi2aes systemd-journald[432]: Runtime Journal (/run/log/journal/35c4ab2cb79e0973fdebde8c693341bc) is 3.9M, max 30.0M, 26.0M free. Jan 09 05:00:02 pi2aes systemd-journald[432]: Received client request to flush runtime journal. Jan 09 05:00:02 pi2aes systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... Jan 09 05:00:02 pi2aes systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jan 09 05:00:02 pi2aes systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jan 09 05:00:02 pi2aes systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. Jan 09 05:00:02 pi2aes systemd[1]: Received SIGRTMIN+20 from PID 309 (plymouthd). Jan 09 05:00:02 pi2aes systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 09 05:00:02 pi2aes systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 612 (systemd-binfmt) Jan 09 05:00:02 pi2aes systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... Jan 09 05:00:02 pi2aes kernel: wm8804 1-003b: revision E Jan 09 05:00:02 pi2aes systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. Jan 09 05:00:02 pi2aes systemd[1]: Finished plymouth-rotation.service - Plymouth rotation detection for shutdown/reboot. Jan 09 05:00:02 pi2aes systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. Jan 09 05:00:02 pi2aes systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 09 05:00:02 pi2aes systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0. Jan 09 05:00:02 pi2aes systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. Jan 09 05:00:02 pi2aes systemd[1]: Started ifup@eth0.service - ifup for eth0. Jan 09 05:00:02 pi2aes systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 09 05:00:02 pi2aes systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 09 05:00:02 pi2aes systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 09 05:00:02 pi2aes systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... Jan 09 05:00:02 pi2aes systemd[1]: Starting rpcbind.service - RPC bind portmap service... Jan 09 05:00:02 pi2aes systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 09 05:00:02 pi2aes systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 09 05:00:02 pi2aes systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jan 09 05:00:02 pi2aes systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jan 09 05:00:02 pi2aes systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 09 05:00:02 pi2aes systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 09 05:00:02 pi2aes systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 09 05:00:02 pi2aes systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 09 05:00:02 pi2aes kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jan 09 05:00:02 pi2aes kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 09 05:00:02 pi2aes kernel: usbcore: registered new interface driver brcmfmac Jan 09 05:00:02 pi2aes systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 09 05:00:02 pi2aes systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 09 05:00:02 pi2aes systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 09 05:00:02 pi2aes systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 09 05:00:02 pi2aes systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 09 05:00:02 pi2aes kernel: Bluetooth: Core ver 2.22 Jan 09 05:00:02 pi2aes kernel: NET: Registered PF_BLUETOOTH protocol family Jan 09 05:00:02 pi2aes kernel: Bluetooth: HCI device and connection manager initialized Jan 09 05:00:02 pi2aes kernel: Bluetooth: HCI socket layer initialized Jan 09 05:00:02 pi2aes kernel: Bluetooth: L2CAP socket layer initialized Jan 09 05:00:02 pi2aes kernel: Bluetooth: SCO socket layer initialized Jan 09 05:00:02 pi2aes systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. Jan 09 05:00:02 pi2aes systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jan 09 05:00:02 pi2aes systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. Jan 09 05:00:02 pi2aes systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 09 05:00:02 pi2aes systemd[1]: Reached target paths.target - Path Units. Jan 09 05:00:02 pi2aes systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jan 09 05:00:02 pi2aes kernel: Bluetooth: HCI UART driver ver 2.3 Jan 09 05:00:02 pi2aes kernel: Bluetooth: HCI UART protocol H4 registered Jan 09 05:00:02 pi2aes kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 09 05:00:02 pi2aes kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 09 05:00:02 pi2aes kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jan 09 05:00:02 pi2aes kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jan 09 05:00:02 pi2aes haveged[629]: haveged: command socket is listening at fd 3 Jan 09 05:00:02 pi2aes systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jan 09 05:00:02 pi2aes sh[673]: command failed: No such device (-19) Jan 09 05:00:02 pi2aes systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 09 05:00:02 pi2aes systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 09 05:00:02 pi2aes systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 09 05:00:02 pi2aes systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 09 05:00:02 pi2aes systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 09 05:00:02 pi2aes systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jan 09 05:00:02 pi2aes systemd[1]: Started rpcbind.service - RPC bind portmap service. Jan 09 05:00:02 pi2aes systemd[1]: Reached target rpcbind.target - RPC Port Mapper. Jan 09 05:00:02 pi2aes systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 09 05:00:02 pi2aes systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 09 05:00:02 pi2aes sh[684]: dhcpcd-9.4.1 starting Jan 09 05:00:02 pi2aes dhcpcd[684]: dhcpcd-9.4.1 starting Jan 09 05:00:02 pi2aes systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 09 05:00:02 pi2aes systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 09 05:00:02 pi2aes systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 09 05:00:02 pi2aes sh[684]: DUID 00:01:00:01:30:c6:00:2b:dc:a6:32:47:7c:59 Jan 09 05:00:02 pi2aes dhcpcd[689]: DUID 00:01:00:01:30:c6:00:2b:dc:a6:32:47:7c:59 Jan 09 05:00:02 pi2aes systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 09 05:00:02 pi2aes systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 09 05:00:02 pi2aes systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 09 05:00:02 pi2aes kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Jan 09 05:00:02 pi2aes kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 09 05:00:02 pi2aes kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Aug 29 2023 01:47:08 version 7.45.265 (28bca26 CY) FWID 01-b677b91b Jan 09 05:00:02 pi2aes (udev-worker)[471]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Jan 09 05:00:02 pi2aes systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Jan 09 05:00:02 pi2aes systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. Jan 09 05:00:02 pi2aes (udev-worker)[471]: Network interface NamePolicy= disabled on kernel command line. Jan 09 05:00:02 pi2aes kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jan 09 05:00:02 pi2aes dhcpcd[689]: eth0: waiting for carrier Jan 09 05:00:02 pi2aes sh[684]: eth0: waiting for carrier Jan 09 05:00:02 pi2aes kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jan 09 05:00:02 pi2aes systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). Jan 09 05:00:02 pi2aes systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 09 05:00:02 pi2aes systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 09 05:00:02 pi2aes systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 09 05:00:02 pi2aes systemd[1]: Starting networking.service - Raise network interfaces... Jan 09 05:00:02 pi2aes systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 09 05:00:02 pi2aes systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). Jan 09 05:00:02 pi2aes systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). Jan 09 05:00:02 pi2aes systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Jan 09 05:00:02 pi2aes systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 09 05:00:02 pi2aes systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 09 05:00:02 pi2aes systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 09 05:00:02 pi2aes systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 09 05:00:02 pi2aes systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 09 05:00:02 pi2aes systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 09 05:00:02 pi2aes systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 09 05:00:02 pi2aes systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 09 05:00:02 pi2aes kernel: Bluetooth: hci0: BCM: chip id 107 Jan 09 05:00:02 pi2aes kernel: Bluetooth: hci0: BCM: features 0x2f Jan 09 05:00:02 pi2aes kernel: Bluetooth: hci0: BCM4345C0 Jan 09 05:00:02 pi2aes kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jan 09 05:00:02 pi2aes kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,4-model-b.hcd' Patch Jan 09 05:00:02 pi2aes systemd[1]: Condition check resulted in sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0 being skipped. Jan 09 05:00:02 pi2aes systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). Jan 09 05:00:02 pi2aes systemd[1]: Reached target sysinit.target - System Initialization. Jan 09 05:00:03 pi2aes systemd[1]: Started apt-daily.timer - Daily apt download activities. Jan 09 05:00:03 pi2aes systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Jan 09 05:00:03 pi2aes systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. Jan 09 05:00:03 pi2aes systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Jan 09 05:00:03 pi2aes systemd[1]: Started fstrim.timer - Discard unused blocks once a week. Jan 09 05:00:03 pi2aes systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Jan 09 05:00:03 pi2aes systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Jan 09 05:00:03 pi2aes systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jan 09 05:00:03 pi2aes systemd[1]: Reached target timers.target - Timer Units. Jan 09 05:00:03 pi2aes systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. Jan 09 05:00:03 pi2aes systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jan 09 05:00:03 pi2aes systemd[1]: Reached target sockets.target - Socket Units. Jan 09 05:00:03 pi2aes systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... Jan 09 05:00:03 pi2aes systemd[1]: Started ifup@wlan0.service - ifup for wlan0. Jan 09 05:00:03 pi2aes systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jan 09 05:00:03 pi2aes systemd[1]: Reached target basic.target - Basic System. Jan 09 05:00:03 pi2aes systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). Jan 09 05:00:03 pi2aes systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... Jan 09 05:00:03 pi2aes systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Jan 09 05:00:03 pi2aes systemd[1]: Starting bluetooth.service - Bluetooth service... Jan 09 05:00:03 pi2aes systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jan 09 05:00:03 pi2aes systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... Jan 09 05:00:03 pi2aes systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). Jan 09 05:00:03 pi2aes systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). Jan 09 05:00:03 pi2aes kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Jan 09 05:00:03 pi2aes systemd[1]: Started iptables.service - Volumio Iptables Module. Jan 09 05:00:03 pi2aes systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... Jan 09 05:00:03 pi2aes systemd[1]: Starting systemd-logind.service - User Login Management... Jan 09 05:00:03 pi2aes systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Jan 09 05:00:03 pi2aes systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 09 05:00:03 pi2aes systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. Jan 09 05:00:03 pi2aes systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... Jan 09 05:00:03 pi2aes systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). Jan 09 05:00:03 pi2aes systemd[1]: Starting wireless.service - Wireless Services... Jan 09 05:00:03 pi2aes systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. Jan 09 05:00:03 pi2aes volumio_rfkill_unblock.sh[784]: Volumio WiFi Soft Blocked check script Jan 09 05:00:03 pi2aes volumio_rfkill_unblock.sh[784]: Check if rfkill is available Jan 09 05:00:03 pi2aes volumio_rfkill_unblock.sh[784]: Check if rfkill listed devices are already unblocked Jan 09 05:00:03 pi2aes haveged[629]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K Jan 09 05:00:03 pi2aes haveged[629]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14748/63356 Jan 09 05:00:03 pi2aes haveged[629]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99745 Jan 09 05:00:03 pi2aes haveged[629]: haveged: fills: 0, generated: 0 Jan 09 05:00:03 pi2aes systemd[1]: Reached target rpc_pipefs.target. Jan 09 05:00:03 pi2aes systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). Jan 09 05:00:03 pi2aes systemd[1]: Reached target nfs-client.target - NFS client services. Jan 09 05:00:03 pi2aes systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jan 09 05:00:03 pi2aes systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 09 05:00:03 pi2aes thd[781]: Unable to parse trigger line: Jan 09 05:00:03 pi2aes thd[781]: Unable to parse trigger line: Jan 09 05:00:03 pi2aes kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 09 05:00:03 pi2aes systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Jan 09 05:00:03 pi2aes systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jan 09 05:00:03 pi2aes systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Jan 09 05:00:03 pi2aes avahi-daemon[763]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Jan 09 05:00:03 pi2aes avahi-daemon[763]: Successfully dropped root privileges. Jan 09 05:00:03 pi2aes avahi-daemon[763]: avahi-daemon 0.8 starting up. Jan 09 05:00:03 pi2aes volumio_rfkill_unblock.sh[784]: Wi-Fi is already unblocked. Jan 09 05:00:03 pi2aes systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. Jan 09 05:00:03 pi2aes systemd[1]: e2scrub_reap.service: Deactivated successfully. Jan 09 05:00:03 pi2aes systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. Jan 09 05:00:03 pi2aes alsactl[799]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jan 09 05:00:03 pi2aes alsactl[799]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jan 09 05:00:03 pi2aes systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... Jan 09 05:00:03 pi2aes alsactl[799]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jan 09 05:00:03 pi2aes systemd[1]: Starting th-udev-rebind.service - Late rebind of input devices to triggerhappy... Jan 09 05:00:03 pi2aes systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. Jan 09 05:00:03 pi2aes systemd[1]: Reached target sound.target - Sound Card. Jan 09 05:00:03 pi2aes systemd[1]: th-udev-rebind.service: Deactivated successfully. Jan 09 05:00:03 pi2aes systemd[1]: Finished th-udev-rebind.service - Late rebind of input devices to triggerhappy. Jan 09 05:00:03 pi2aes systemd-logind[780]: New seat seat0. Jan 09 05:00:03 pi2aes ifup[720]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jan 09 05:00:03 pi2aes sh[840]: wlan0=wlan0 Jan 09 05:00:03 pi2aes dhcpcd[815]: dhcpcd-9.4.1 starting Jan 09 05:00:03 pi2aes dhcpcd[851]: dev: loaded udev Jan 09 05:00:03 pi2aes dhcpcd[851]: DUID 00:01:00:01:30:c6:00:2b:dc:a6:32:47:7c:59 Jan 09 05:00:03 pi2aes systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. Jan 09 05:00:03 pi2aes systemd[1]: Finished networking.service - Raise network interfaces. Jan 09 05:00:03 pi2aes dhcpcd[851]: eth0: waiting for carrier Jan 09 05:00:03 pi2aes dhcpcd[851]: wlan0: waiting for carrier Jan 09 05:00:03 pi2aes bluetoothd[766]: Bluetooth daemon 5.83 Jan 09 05:00:03 pi2aes loadcpufreq[794]: Loading cpufreq kernel modules...done (none). Jan 09 05:00:03 pi2aes systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Jan 09 05:00:03 pi2aes kernel: Bluetooth: hci0: BCM: features 0x2f Jan 09 05:00:03 pi2aes kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 Jan 09 05:00:03 pi2aes kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 Jan 09 05:00:03 pi2aes systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... Jan 09 05:00:03 pi2aes systemd[1]: Started dbus.service - D-Bus System Message Bus. Jan 09 05:00:03 pi2aes avahi-daemon[763]: Successfully called chroot(). Jan 09 05:00:03 pi2aes avahi-daemon[763]: Successfully dropped remaining capabilities. Jan 09 05:00:03 pi2aes dbus-daemon[768]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 09 05:00:03 pi2aes avahi-daemon[763]: Loading service file /services/volumio.service. Jan 09 05:00:03 pi2aes bluetoothd[766]: Starting SDP server Jan 09 05:00:03 pi2aes bluetoothd[766]: Excluding (cli) sap Jan 09 05:00:03 pi2aes bluetoothd[766]: Excluding (cli) midi Jan 09 05:00:03 pi2aes bluetoothd[766]: Excluding (cli) battery Jan 09 05:00:03 pi2aes systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Jan 09 05:00:03 pi2aes systemd[1]: Started systemd-logind.service - User Login Management. Jan 09 05:00:03 pi2aes avahi-daemon[763]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jan 09 05:00:03 pi2aes avahi-daemon[763]: New relevant interface lo.IPv4 for mDNS. Jan 09 05:00:03 pi2aes systemd[1]: Started bluetooth.service - Bluetooth service. Jan 09 05:00:03 pi2aes avahi-daemon[763]: Network interface enumeration completed. Jan 09 05:00:03 pi2aes avahi-daemon[763]: Registering new address record for 127.0.0.1 on lo.IPv4. Jan 09 05:00:03 pi2aes kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 09 05:00:03 pi2aes kernel: Bluetooth: BNEP filters: protocol multicast Jan 09 05:00:03 pi2aes kernel: Bluetooth: BNEP socket layer initialized Jan 09 05:00:03 pi2aes kernel: Bluetooth: MGMT ver 1.23 Jan 09 05:00:03 pi2aes bluetoothd[766]: Bluetooth management interface 1.23 initialized Jan 09 05:00:03 pi2aes bluetoothd[766]: Battery Provider Manager created Jan 09 05:00:03 pi2aes dbus-daemon[768]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=0 pid=766 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Jan 09 05:00:03 pi2aes cpufrequtils[915]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 09 05:00:03 pi2aes systemd[1]: Starting bluealsa.service - BlueALSA service... Jan 09 05:00:03 pi2aes systemd[1]: Starting wpa_supplicant.service - WPA supplicant... Jan 09 05:00:03 pi2aes kernel: NET: Registered PF_ALG protocol family Jan 09 05:00:03 pi2aes systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. Jan 09 05:00:03 pi2aes systemd[1]: iptables.service: Deactivated successfully. Jan 09 05:00:03 pi2aes systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Jan 09 05:00:04 pi2aes kernel: cryptd: max_cpu_qlen set to 1000 Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: Skipping automatic bootloader upgrade. current 1762363706 >= min 1746717695 Jan 09 05:00:04 pi2aes dbus-daemon[768]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 09 05:00:04 pi2aes systemd[1]: Started systemd-hostnamed.service - Hostname Service. Jan 09 05:00:04 pi2aes wpa_supplicant[951]: Successfully initialized wpa_supplicant Jan 09 05:00:04 pi2aes systemd[1]: Started wpa_supplicant.service - WPA supplicant. Jan 09 05:00:04 pi2aes systemd[1]: Reached target network.target - Network. Jan 09 05:00:04 pi2aes systemd[1]: Reached target network-online.target - Network is Online. Jan 09 05:00:04 pi2aes volumio-remote-updater[783]: Volumio OTA Client: 1.7.0 Jan 09 05:00:04 pi2aes volumio-remote-updater[783]: Error: No active session Jan 09 05:00:04 pi2aes volumio-remote-updater[783]: [2026-01-09 05:00:04] [info] asio async_connect error: asio.system:111 (Connection refused) Jan 09 05:00:04 pi2aes volumio-remote-updater[783]: [2026-01-09 05:00:04] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jan 09 05:00:04 pi2aes volumio-remote-updater[783]: [2026-01-09 05:00:04] [error] handle_connect error: Connection refused Jan 09 05:00:04 pi2aes systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... Jan 09 05:00:04 pi2aes systemd[1]: Starting nmbd.service - Samba NMB Daemon... Jan 09 05:00:04 pi2aes systemd[1]: Starting ntpsec.service - Network Time Service... Jan 09 05:00:04 pi2aes systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... Jan 09 05:00:04 pi2aes systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... Jan 09 05:00:04 pi2aes systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... Jan 09 05:00:04 pi2aes bluetoothd[766]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Jan 09 05:00:04 pi2aes bluetoothd[766]: bap: Operation not supported (95) Jan 09 05:00:04 pi2aes systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 09 05:00:04 pi2aes systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 09 05:00:04 pi2aes sm-notify[986]: Version 2.6.2 starting Jan 09 05:00:04 pi2aes bluetoothd[766]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 Jan 09 05:00:04 pi2aes systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Jan 09 05:00:04 pi2aes systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jan 09 05:00:04 pi2aes upmpdcli[782]: Could not open config: /tmp/upmpdcli.conf Jan 09 05:00:04 pi2aes systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Jan 09 05:00:04 pi2aes systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 09 05:00:04 pi2aes systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Jan 09 05:00:04 pi2aes systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 09 05:00:04 pi2aes systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 09 05:00:04 pi2aes systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Jan 09 05:00:04 pi2aes ntpd[994]: INIT: ntpd ntpsec-1.2.2: Starting Jan 09 05:00:04 pi2aes ntp-systemd-wrapper[994]: 2026-01-09T05:00:04 ntpd[994]: INIT: ntpd ntpsec-1.2.2: Starting Jan 09 05:00:04 pi2aes ntp-systemd-wrapper[994]: 2026-01-09T05:00:04 ntpd[994]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jan 09 05:00:04 pi2aes ntpd[994]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Jan 09 05:00:04 pi2aes systemd[1]: Started ntpsec.service - Network Time Service. Jan 09 05:00:04 pi2aes systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jan 09 05:00:04 pi2aes welcome[1000]: Resolved ip:[0] Jan 09 05:00:04 pi2aes ifplugd(eth0)[1033]: ifplugd 0.28 initializing. Jan 09 05:00:04 pi2aes ifplugd(eth0)[1033]: Using interface eth0/DC:A6:32:47:7C:59 with driver (version: 6.12.47-v7l+) Jan 09 05:00:04 pi2aes ifplugd(eth0)[1033]: Using detection mode: SIOCETHTOOL Jan 09 05:00:04 pi2aes ifplugd(eth0)[1033]: Initialization complete, link beat not detected. Jan 09 05:00:04 pi2aes systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Jan 09 05:00:04 pi2aes ntpd[1011]: INIT: precision = 0.444 usec (-21) Jan 09 05:00:04 pi2aes ntpd[1011]: INIT: successfully locked into RAM Jan 09 05:00:04 pi2aes ntpd[1011]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Jan 09 05:00:04 pi2aes ntpd[1011]: CONFIG: restrict nopeer ignored Jan 09 05:00:04 pi2aes ifplugd[975]: Network Interface Plugging Daemon...start eth0...done. Jan 09 05:00:04 pi2aes systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Jan 09 05:00:04 pi2aes ntpd[1011]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 09 05:00:04 pi2aes ntpd[1011]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Jan 09 05:00:04 pi2aes ntpd[1011]: INIT: Using SO_TIMESTAMPNS(ns) Jan 09 05:00:04 pi2aes ntpd[1011]: IO: Listen and drop on 0 v6wildcard [::]:123 Jan 09 05:00:04 pi2aes ntpd[1011]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 09 05:00:04 pi2aes ntpd[1011]: IO: Listen normally on 2 lo 127.0.0.1:123 Jan 09 05:00:04 pi2aes ntpd[1011]: IO: Listening on routing socket on fd #19 for interface updates Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: BOOTLOADER: up to date Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: CURRENT: Wed Nov 5 17:28:26 UTC 2025 (1762363706) Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: LATEST: Mon Dec 8 19:23:42 UTC 2025 (1765221822) Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2711/default) Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: Use raspi-config to change the release. Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: VL805_FW: Dedicated VL805 EEPROM Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: VL805: up to date Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: CURRENT: 000138c0 Jan 09 05:00:04 pi2aes rpi-eeprom-update[775]: LATEST: 000138c0 Jan 09 05:00:04 pi2aes systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Jan 09 05:00:04 pi2aes avahi-daemon[763]: Server startup complete. Host name is pi2aes.local. Local service cookie is 2392518246. Jan 09 05:00:04 pi2aes systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 09 05:00:04 pi2aes ntpd[1011]: INIT: This system has a 32-bit time_t. Jan 09 05:00:04 pi2aes ntpd[1011]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. Jan 09 05:00:04 pi2aes ntpd[1011]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 09 05:00:04 pi2aes ntpd[1011]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Jan 09 05:00:04 pi2aes ntpd[1011]: INIT: Running with OpenSSL 3.0.18 30 Sep 2025, 30000120 Jan 09 05:00:04 pi2aes ntpd[1011]: NTSc: Using system default root certificates. Jan 09 05:00:04 pi2aes sshd[1046]: Server listening on 0.0.0.0 port 22. Jan 09 05:00:04 pi2aes sshd[1046]: Server listening on :: port 22. Jan 09 05:00:04 pi2aes systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Jan 09 05:00:05 pi2aes ntpd[1011]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Jan 09 05:00:05 pi2aes bluealsa[937]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Jan 09 05:00:05 pi2aes bluealsa[937]: main.c:663: Starting main dispatching loop Jan 09 05:00:05 pi2aes systemd[1]: Started bluealsa.service - BlueALSA service. Jan 09 05:00:05 pi2aes bluealsa[937]: main.c:118: Acquired D-Bus service name: org.bluealsa Jan 09 05:00:05 pi2aes bluealsa[937]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Jan 09 05:00:05 pi2aes systemd[1]: Reached target bluetooth.target - Bluetooth Support. Jan 09 05:00:05 pi2aes bluealsa[937]: bluez.c:153: Registering media application: /org/bluez/hci0 Jan 09 05:00:05 pi2aes bluealsa[937]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Jan 09 05:00:05 pi2aes bluealsa[937]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Jan 09 05:00:05 pi2aes bluealsa[937]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Jan 09 05:00:05 pi2aes bluealsa[937]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Jan 09 05:00:05 pi2aes bluetoothd[766]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Jan 09 05:00:05 pi2aes bluetoothd[766]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Jan 09 05:00:05 pi2aes bluetoothd[766]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Jan 09 05:00:05 pi2aes bluetoothd[766]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Jan 09 05:00:05 pi2aes avahi-daemon[763]: Service "Pi2AES" (/services/volumio.service) successfully established. Jan 09 05:00:05 pi2aes systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Jan 09 05:00:05 pi2aes systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Jan 09 05:00:06 pi2aes ntpd[1011]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 09 05:00:06 pi2aes ntpd[1011]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jan 09 05:00:06 pi2aes ntpd[1011]: DNS: dns_check: DNS error: -11, System error Jan 09 05:00:06 pi2aes ntpd[1011]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Jan 09 05:00:06 pi2aes nmbd[1078]: [2026/01/09 05:00:06.334424, 0] ../../source3/nmbd/nmbd.c:901(main) Jan 09 05:00:06 pi2aes nmbd[1078]: nmbd version 4.17.8-Raspbian started. Jan 09 05:00:06 pi2aes nmbd[1078]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jan 09 05:00:06 pi2aes nmbd[1078]: [2026/01/09 05:00:06.349705, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 09 05:00:06 pi2aes nmbd[1078]: started asyncdns process 1082 Jan 09 05:00:06 pi2aes nmbd[1078]: [2026/01/09 05:00:06.352105, 0] ../../lib/util/become_daemon.c:150(daemon_status) Jan 09 05:00:06 pi2aes nmbd[1078]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 09 05:00:06 pi2aes nmbd[1078]: [2026/01/09 05:00:06.352706, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Jan 09 05:00:06 pi2aes nmbd[1078]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 09 05:00:06 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless Jan 09 05:00:06 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Jan 09 05:00:07 pi2aes dhcpcd[851]: eth0: carrier acquired Jan 09 05:00:07 pi2aes sh[684]: eth0: carrier acquired Jan 09 05:00:07 pi2aes dhcpcd[689]: eth0: carrier acquired Jan 09 05:00:07 pi2aes dhcpcd[851]: eth0: IAID 32:47:7c:59 Jan 09 05:00:07 pi2aes dhcpcd[851]: eth0: adding address fe80::dea6:32ff:fe47:7c59 Jan 09 05:00:07 pi2aes dhcpcd[851]: ipv6_addaddr1: Permission denied Jan 09 05:00:07 pi2aes kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jan 09 05:00:07 pi2aes dhcpcd[689]: eth0: IAID 32:47:7c:59 Jan 09 05:00:07 pi2aes sh[684]: eth0: IAID 32:47:7c:59 Jan 09 05:00:07 pi2aes sh[684]: eth0: adding address fe80::dea6:32ff:fe47:7c59 Jan 09 05:00:07 pi2aes sh[684]: ipv6_addaddr1: Permission denied Jan 09 05:00:07 pi2aes dhcpcd[689]: eth0: adding address fe80::dea6:32ff:fe47:7c59 Jan 09 05:00:07 pi2aes dhcpcd[689]: ipv6_addaddr1: Permission denied Jan 09 05:00:07 pi2aes ntpd[1011]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 09 05:00:07 pi2aes ntpd[1011]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jan 09 05:00:07 pi2aes ntpd[1011]: DNS: dns_check: DNS error: -11, System error Jan 09 05:00:07 pi2aes ntpd[1011]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 Jan 09 05:00:07 pi2aes sudo[1101]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 09 05:00:07 pi2aes sudo[1101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:07 pi2aes sudo[1101]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:07 pi2aes sudo[1106]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 09 05:00:07 pi2aes sudo[1106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:07 pi2aes sh[684]: eth0: soliciting an IPv6 router Jan 09 05:00:07 pi2aes dhcpcd[689]: eth0: soliciting an IPv6 router Jan 09 05:00:07 pi2aes sh[684]: eth0: soliciting a DHCP lease Jan 09 05:00:07 pi2aes dhcpcd[689]: eth0: soliciting a DHCP lease Jan 09 05:00:07 pi2aes dhcpcd[851]: eth0: soliciting a DHCP lease Jan 09 05:00:07 pi2aes sudo[1106]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:07 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Cleaning previous... Jan 09 05:00:07 pi2aes sudo[1117]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 09 05:00:07 pi2aes sudo[1117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:07 pi2aes sudo[1117]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:07 pi2aes kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 09 05:00:07 pi2aes ifplugd(eth0)[1033]: Link beat detected. Jan 09 05:00:07 pi2aes wireless.js[785]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Jan 09 05:00:07 pi2aes wireless.js[785]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms Jan 09 05:00:07 pi2aes wireless.js[785]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: dc:a6:32:47:7c:5b) Jan 09 05:00:07 pi2aes dhcpcd[851]: eth0: soliciting an IPv6 router Jan 09 05:00:07 pi2aes sudo[1124]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 09 05:00:07 pi2aes sudo[1124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:00:07 pi2aes sudo[1124]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:07 pi2aes sudo[1132]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 09 05:00:07 pi2aes sudo[1132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:00:07 pi2aes ifplugd(eth0)[1033]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 09 05:00:07 pi2aes ifplugd(eth0)[1033]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 09 05:00:07 pi2aes systemd[1]: systemd-rfkill.service: Deactivated successfully. Jan 09 05:00:08 pi2aes ntpd[1011]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 09 05:00:08 pi2aes ntpd[1011]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jan 09 05:00:08 pi2aes ntpd[1011]: DNS: dns_check: DNS error: -11, System error Jan 09 05:00:08 pi2aes ntpd[1011]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 Jan 09 05:00:09 pi2aes ntpd[1011]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 09 05:00:09 pi2aes ntpd[1011]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jan 09 05:00:09 pi2aes ntpd[1011]: DNS: dns_check: DNS error: -11, System error Jan 09 05:00:09 pi2aes ntpd[1011]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 Jan 09 05:00:09 pi2aes volumio-remote-updater[783]: [2026-01-09 05:00:09] [info] asio async_connect error: asio.system:111 (Connection refused) Jan 09 05:00:09 pi2aes volumio-remote-updater[783]: [2026-01-09 05:00:09] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jan 09 05:00:09 pi2aes volumio-remote-updater[783]: [2026-01-09 05:00:09] [error] handle_connect error: Connection refused Jan 09 05:00:10 pi2aes sudo[1132]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:10 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SETTING APPROPRIATE REG DOMAIN: DE Jan 09 05:00:10 pi2aes sudo[1164]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Jan 09 05:00:10 pi2aes sudo[1164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:00:10 pi2aes sudo[1164]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:10 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SUCCESSFULLY SET NEW REGDOMAIN: DE Jan 09 05:00:10 pi2aes wireless.js[785]: WIRELESS.JS - INFO: refreshEthernetState: Corrected ethernet state: connected Jan 09 05:00:10 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Jan 09 05:00:10 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Jan 09 05:00:10 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Jan 09 05:00:10 pi2aes sudo[1174]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 09 05:00:10 pi2aes sudo[1174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:10 pi2aes sudo[1174]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:10 pi2aes sudo[1177]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 09 05:00:10 pi2aes sudo[1177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:10 pi2aes sudo[1177]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:10 pi2aes wpa_supplicant[1180]: Successfully initialized wpa_supplicant Jan 09 05:00:10 pi2aes dhcpcd[851]: eth0: offered 192.168.50.107 from 192.168.50.1 Jan 09 05:00:10 pi2aes dhcpcd[689]: eth0: offered 192.168.50.107 from 192.168.50.1 Jan 09 05:00:10 pi2aes sh[684]: eth0: offered 192.168.50.107 from 192.168.50.1 Jan 09 05:00:10 pi2aes dhcpcd[851]: eth0: ignoring offer of 192.168.50.107 from 192.168.50.1 Jan 09 05:00:10 pi2aes dhcpcd[851]: eth0: probing address 192.168.50.107/24 Jan 09 05:00:10 pi2aes sh[684]: eth0: probing address 192.168.50.107/24 Jan 09 05:00:10 pi2aes dhcpcd[689]: eth0: probing address 192.168.50.107/24 Jan 09 05:00:11 pi2aes wpa_supplicant[1180]: nl80211: kernel reports: Registration to specific type not supported Jan 09 05:00:11 pi2aes wpa_supplicant[1185]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Notified systemd about wireless ready Jan 09 05:00:11 pi2aes systemd[1]: Started wireless.service - Wireless Services. Jan 09 05:00:11 pi2aes kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 09 05:00:11 pi2aes systemd[1]: Started volumio.service - Volumio Backend Module. Jan 09 05:00:11 pi2aes systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Jan 09 05:00:11 pi2aes systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Jan 09 05:00:11 pi2aes systemd[1]: Received SIGRTMIN+21 from PID 309 (plymouthd). Jan 09 05:00:11 pi2aes systemd[1]: Received SIGRTMIN+21 from PID 309 (plymouthd). Jan 09 05:00:11 pi2aes systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Jan 09 05:00:11 pi2aes systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Jan 09 05:00:11 pi2aes systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 09 05:00:11 pi2aes systemd[1]: Reached target getty.target - Login Prompts. Jan 09 05:00:11 pi2aes volumio-cpu-tweak[1206]: Setting RT Priority for mpd Jan 09 05:00:11 pi2aes volumio-cpu-tweak[1221]: pid 35's current scheduling policy: SCHED_OTHER Jan 09 05:00:11 pi2aes volumio-cpu-tweak[1221]: pid 35's current scheduling priority: 0 Jan 09 05:00:11 pi2aes volumio-cpu-tweak[1206]: Setting MPD Affinity Jan 09 05:00:11 pi2aes volumio-cpu-tweak[1222]: pid 3's current affinity mask: f Jan 09 05:00:11 pi2aes volumio-cpu-tweak[1206]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 09 05:00:11 pi2aes volumio-cpu-tweak[1206]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 09 05:00:11 pi2aes systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Jan 09 05:00:11 pi2aes bash[993]: setdatetime-helper: all HTTPS Date fallbacks failed Jan 09 05:00:11 pi2aes systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 09 05:00:11 pi2aes systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: === SNM TRANSITION === Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: New ethernet state: connected Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Single Network Mode: enabled Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: First start: no Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: === END TRANSITION === Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Jan 09 05:00:11 pi2aes sudo[1229]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Jan 09 05:00:11 pi2aes sudo[1229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:11 pi2aes dhcpcd[1230]: dhcpcd not running Jan 09 05:00:11 pi2aes sudo[1229]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:11 pi2aes wireless.js[785]: dhcpcd not running Jan 09 05:00:11 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Jan 09 05:00:11 pi2aes wpa_supplicant[1185]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 09 05:00:11 pi2aes wpa_supplicant[1185]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 09 05:00:11 pi2aes wpa_supplicant[1185]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Jan 09 05:00:12 pi2aes sudo[1238]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 09 05:00:12 pi2aes sudo[1238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:12 pi2aes sudo[1238]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:12 pi2aes sudo[1240]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 09 05:00:12 pi2aes wpa_supplicant[1185]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Jan 09 05:00:12 pi2aes sudo[1240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:12 pi2aes wpa_supplicant[1185]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 09 05:00:12 pi2aes sudo[1240]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:12 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Cleaning previous... Jan 09 05:00:12 pi2aes wpa_supplicant[1185]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 09 05:00:12 pi2aes wpa_supplicant[1185]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jan 09 05:00:12 pi2aes sudo[1243]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 09 05:00:12 pi2aes sudo[1243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:12 pi2aes kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 09 05:00:12 pi2aes sudo[1243]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:12 pi2aes wpa_supplicant[1185]: wlan0: CTRL-EVENT-TERMINATING Jan 09 05:00:12 pi2aes wireless.js[785]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations Jan 09 05:00:12 pi2aes wireless.js[785]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 2ms Jan 09 05:00:12 pi2aes wireless.js[785]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: dc:a6:32:47:7c:5b) Jan 09 05:00:12 pi2aes sudo[1250]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 09 05:00:12 pi2aes sudo[1250]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:00:12 pi2aes sudo[1250]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:12 pi2aes sudo[1258]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 09 05:00:12 pi2aes sudo[1258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:00:14 pi2aes volumio[1204]: info: ------------------------------------------- Jan 09 05:00:14 pi2aes volumio[1204]: info: ----- Volumio3 ---- Jan 09 05:00:14 pi2aes volumio[1204]: info: ------------------------------------------- Jan 09 05:00:14 pi2aes volumio[1204]: info: ----- System startup ---- Jan 09 05:00:14 pi2aes volumio[1204]: info: ------------------------------------------- Jan 09 05:00:15 pi2aes volumio[1204]: info: MYVOLUMIO Environment detected Jan 09 05:00:15 pi2aes sudo[1258]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:15 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Regdomain already correct: DE Jan 09 05:00:15 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Jan 09 05:00:15 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Jan 09 05:00:15 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Jan 09 05:00:15 pi2aes volumio[1204]: info: Plugin folders cleanup Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning into folder /volumio/app/plugins/ Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning category audio_interface Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning category miscellanea Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning category music_service Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning category plugins.json Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning category system_controller Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning category user_interface Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning into folder /data/plugins/ Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning category audio_interface Jan 09 05:00:15 pi2aes volumio[1204]: info: Scanning category system_controller Jan 09 05:00:15 pi2aes volumio[1204]: info: Plugin folders cleanup completed Jan 09 05:00:15 pi2aes volumio[1204]: info: ------------------------------------------- Jan 09 05:00:15 pi2aes volumio[1204]: info: ----- Core plugins startup ---- Jan 09 05:00:15 pi2aes volumio[1204]: info: ------------------------------------------- Jan 09 05:00:15 pi2aes volumio[1204]: info: Loading plugins from folder /volumio/app/plugins/ Jan 09 05:00:15 pi2aes volumio[1204]: info: Adding plugin upnp to MyMusic Plugins Jan 09 05:00:15 pi2aes volumio[1204]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 09 05:00:15 pi2aes volumio[1204]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 09 05:00:15 pi2aes sudo[1277]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 09 05:00:15 pi2aes sudo[1277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:15 pi2aes sudo[1277]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:15 pi2aes volumio[1204]: info: Loading plugins from folder /data/plugins/ Jan 09 05:00:15 pi2aes volumio[1204]: info: Loading plugin "system"... Jan 09 05:00:15 pi2aes sudo[1280]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 09 05:00:15 pi2aes sudo[1280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:00:15 pi2aes sudo[1280]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:15 pi2aes wpa_supplicant[1283]: Successfully initialized wpa_supplicant Jan 09 05:00:15 pi2aes volumio[1204]: info: Loading plugin "appearance"... Jan 09 05:00:15 pi2aes dhcpcd[851]: eth0: leased 192.168.50.107 for 86400 seconds Jan 09 05:00:15 pi2aes avahi-daemon[763]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.50.107. Jan 09 05:00:15 pi2aes avahi-daemon[763]: New relevant interface eth0.IPv4 for mDNS. Jan 09 05:00:15 pi2aes avahi-daemon[763]: Registering new address record for 192.168.50.107 on eth0.IPv4. Jan 09 05:00:15 pi2aes dhcpcd[851]: eth0: adding route to 192.168.50.0/24 Jan 09 05:00:15 pi2aes systemd[1]: welcome.service: Deactivated successfully. Jan 09 05:00:15 pi2aes systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 09 05:00:15 pi2aes systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 09 05:00:15 pi2aes dhcpcd[851]: eth0: adding default route via 192.168.50.1 Jan 09 05:00:15 pi2aes wpa_supplicant[1283]: nl80211: kernel reports: Registration to specific type not supported Jan 09 05:00:15 pi2aes wpa_supplicant[1309]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Jan 09 05:00:15 pi2aes systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 09 05:00:16 pi2aes welcome[1303]: Resolved ip:[1] 192.168.50.107 Jan 09 05:00:16 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 4367ms Jan 09 05:00:16 pi2aes wireless.js[785]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Jan 09 05:00:16 pi2aes systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 09 05:00:16 pi2aes systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 09 05:00:16 pi2aes systemd[1]: Started nmbd.service - Samba NMB Daemon. Jan 09 05:00:16 pi2aes nmbd[1078]: [2026/01/09 05:00:16.098931, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 09 05:00:16 pi2aes nmbd[1078]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.50.107 for name WORKGROUP<1d>. Jan 09 05:00:16 pi2aes nmbd[1078]: This response was from IP 192.168.50.108, reporting an IP address of 192.168.50.108. Jan 09 05:00:16 pi2aes systemd[1]: Starting winbind.service - Samba Winbind Daemon... Jan 09 05:00:16 pi2aes wireless.js[785]: WIRELESS.JS - INFO: Notified systemd about wireless ready Jan 09 05:00:16 pi2aes sh[684]: eth0: leased 192.168.50.107 for 86400 seconds Jan 09 05:00:16 pi2aes sh[684]: eth0: adding route to 192.168.50.0/24 Jan 09 05:00:16 pi2aes sh[684]: eth0: adding default route via 192.168.50.1 Jan 09 05:00:16 pi2aes dhcpcd[689]: eth0: leased 192.168.50.107 for 86400 seconds Jan 09 05:00:16 pi2aes dhcpcd[689]: eth0: adding route to 192.168.50.0/24 Jan 09 05:00:16 pi2aes dhcpcd[689]: eth0: adding default route via 192.168.50.1 Jan 09 05:00:16 pi2aes systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 09 05:00:16 pi2aes systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 09 05:00:16 pi2aes systemd[1]: welcome.service: Deactivated successfully. Jan 09 05:00:16 pi2aes systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 09 05:00:16 pi2aes systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 09 05:00:16 pi2aes systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 09 05:00:16 pi2aes welcome[1349]: Resolved ip:[1] 192.168.50.107 Jan 09 05:00:16 pi2aes sh[684]: forked to background, child pid 688 Jan 09 05:00:16 pi2aes systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 09 05:00:16 pi2aes systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 09 05:00:16 pi2aes ifplugd(eth0)[1033]: client: ifup: interface eth0 already configured Jan 09 05:00:16 pi2aes sh[1384]: eth0=eth0 Jan 09 05:00:16 pi2aes winbindd[1333]: [2026/01/09 05:00:16.708705, 0] ../../source3/winbindd/winbindd.c:1440(main) Jan 09 05:00:16 pi2aes winbindd[1333]: winbindd version 4.17.8-Raspbian started. Jan 09 05:00:16 pi2aes winbindd[1333]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jan 09 05:00:16 pi2aes winbindd[1333]: [2026/01/09 05:00:16.720480, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Jan 09 05:00:16 pi2aes winbindd[1333]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 09 05:00:16 pi2aes systemd[1]: Started winbind.service - Samba Winbind Daemon. Jan 09 05:00:16 pi2aes ifplugd(eth0)[1033]: Program executed successfully. Jan 09 05:00:16 pi2aes systemd[1]: Starting smbd.service - Samba SMB Daemon... Jan 09 05:00:16 pi2aes volumio-remote-updater[783]: [2026-01-09 05:00:16] [connect] Successful connection Jan 09 05:00:17 pi2aes smbd[1399]: [2026/01/09 05:00:17.448969, 0] ../../source3/smbd/server.c:1741(main) Jan 09 05:00:17 pi2aes smbd[1399]: smbd version 4.17.8-Raspbian started. Jan 09 05:00:17 pi2aes smbd[1399]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jan 09 05:00:18 pi2aes ntpd[1011]: IO: Listen normally on 3 eth0 192.168.50.107:123 Jan 09 05:00:18 pi2aes ntpd[1011]: IO: new interface(s) found: waking up resolver Jan 09 05:00:18 pi2aes ntpd[1011]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 09 05:00:18 pi2aes ntpd[1011]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jan 09 05:00:18 pi2aes ntpd[1011]: DNS: Pool taking: 194.58.204.20 Jan 09 05:00:18 pi2aes ntpd[1011]: DNS: Pool taking: 194.58.204.148 Jan 09 05:00:18 pi2aes ntpd[1011]: DNS: Pool taking: 45.83.221.52 Jan 09 05:00:18 pi2aes ntpd[1011]: DNS: Pool taking: 172.232.157.27 Jan 09 05:00:18 pi2aes ntpd[1011]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jan 09 05:00:18 pi2aes volumio[1204]: info: Loading plugin "network"... Jan 09 05:00:18 pi2aes volumio[1204]: info: Refreshing Cached IP Addresses Jan 09 05:00:18 pi2aes systemd[1]: Started smbd.service - Samba SMB Daemon. Jan 09 05:00:18 pi2aes systemd[1]: Reached target multi-user.target - Multi-User System. Jan 09 05:00:18 pi2aes systemd[1]: Reached target graphical.target - Graphical Interface. Jan 09 05:00:18 pi2aes sudo[1413]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 09 05:00:18 pi2aes sudo[1413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:00:18 pi2aes sudo[1412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 09 05:00:18 pi2aes sudo[1413]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:18 pi2aes sudo[1412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:00:18 pi2aes systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Jan 09 05:00:18 pi2aes sudo[1412]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:18 pi2aes volumio[1204]: info: Loading plugin "services"... Jan 09 05:00:18 pi2aes volumio[1204]: info: Loading plugin "alsa_controller"... Jan 09 05:00:18 pi2aes sudo[1420]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 09 05:00:18 pi2aes sudo[1420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:00:18 pi2aes systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jan 09 05:00:18 pi2aes systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Jan 09 05:00:18 pi2aes systemd[1]: Startup finished in 13.018s (kernel) + 18.590s (userspace) = 31.609s. Jan 09 05:00:18 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 09 05:00:18 pi2aes volumio[1204]: info: Loading plugin "wizard"... Jan 09 05:00:18 pi2aes volumio[1204]: info: Loading plugin "networkfs"... Jan 09 05:00:18 pi2aes volumio[1204]: info: Starting Udev Watcher for removable devices Jan 09 05:00:18 pi2aes sudo[1444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Pseudonym,password=1NASVERid,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.108/sonic /mnt/NAS/SONIC Jan 09 05:00:18 pi2aes sudo[1444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:00:18 pi2aes volumio[1204]: info: Ignoring mount for partition: boot Jan 09 05:00:18 pi2aes volumio[1204]: info: Ignoring mount for partition: volumio Jan 09 05:00:18 pi2aes volumio[1204]: info: Ignoring mount for partition: volumio_data Jan 09 05:00:18 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 09 05:00:18 pi2aes volumio[1204]: info: Loading plugin "volumio_command_line_client"... Jan 09 05:00:18 pi2aes volumio[1204]: info: Loading plugin "upnp"... Jan 09 05:00:18 pi2aes volumio[1204]: info: [1767931218793] Starting Upmpd Daemon Jan 09 05:00:18 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 09 05:00:18 pi2aes volumio[1204]: info: Loading plugin "my_music"... Jan 09 05:00:18 pi2aes kernel: netfs: FS-Cache loaded Jan 09 05:00:18 pi2aes volumio[1204]: info: Loading plugin "mpd"... Jan 09 05:00:18 pi2aes kernel: Key type cifs.spnego registered Jan 09 05:00:18 pi2aes kernel: Key type cifs.idmap registered Jan 09 05:00:18 pi2aes 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. Jan 09 05:00:18 pi2aes kernel: CIFS: Attempting to mount //192.168.50.108/sonic Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: Pool taking: 192.36.143.130 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: Pool taking: 192.36.143.134 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: Pool taking: 185.96.163.92 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: Pool taking: 172.232.146.46 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: Pool taking: 2001:440:1880:7373::2 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: Pool taking: 2a05:f480:2000:2c23:5400:5ff:fe3b:6d64 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: Pool taking: 2a01:3f7:2:1::1 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: Pool taking: 2a01:3f7:4:2::1 Jan 09 05:00:19 pi2aes ntpd[1011]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jan 09 05:00:19 pi2aes volumio[1204]: info: Loading plugin "upnp_browser"... Jan 09 05:00:19 pi2aes systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Jan 09 05:00:19 pi2aes systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 09 05:00:19 pi2aes systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 09 05:00:19 pi2aes upmpdcli[1482]: Could not open config: /tmp/upmpdcli.conf Jan 09 05:00:19 pi2aes systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 09 05:00:19 pi2aes systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 09 05:00:20 pi2aes kernel: CIFS: VFS: Autodisabling the use of server inode numbers on new server Jan 09 05:00:20 pi2aes kernel: CIFS: VFS: The server doesn't seem to support them properly or the files might be on different servers (DFS) Jan 09 05:00:20 pi2aes kernel: CIFS: VFS: Hardlinks will not be recognized on this mount. Consider mounting with the "noserverino" option to silence this message. Jan 09 05:00:20 pi2aes sudo[1444]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:20 pi2aes ntpd[1011]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 09 05:00:20 pi2aes ntpd[1011]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jan 09 05:00:20 pi2aes ntpd[1011]: DNS: Pool taking: 193.182.111.13 Jan 09 05:00:20 pi2aes ntpd[1011]: DNS: Pool taking: 194.58.207.148 Jan 09 05:00:20 pi2aes ntpd[1011]: DNS: Pool taking: 98.128.175.45 Jan 09 05:00:20 pi2aes ntpd[1011]: DNS: Pool taking: 176.126.86.247 Jan 09 05:00:20 pi2aes ntpd[1011]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jan 09 05:00:20 pi2aes volumio[1204]: info: Starting UPNP Browser Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "alarm-clock"... Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "airplay_emulation"... Jan 09 05:00:20 pi2aes volumio[1204]: info: Starting Shairport Sync Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "last_100"... Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "webradio"... Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "i2s_dacs"... Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "volumiodiscovery"... Jan 09 05:00:20 pi2aes volumio[1204]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 09 05:00:20 pi2aes volumio[1204]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 09 05:00:20 pi2aes volumio[1204]: *** WARNING *** For more information see Jan 09 05:00:20 pi2aes volumio[1204]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 09 05:00:20 pi2aes volumio[1204]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 09 05:00:20 pi2aes volumio[1204]: *** WARNING *** For more information see Jan 09 05:00:20 pi2aes node[1204]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 09 05:00:20 pi2aes node[1204]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 09 05:00:20 pi2aes node[1204]: *** WARNING *** For more information see Jan 09 05:00:20 pi2aes node[1204]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 09 05:00:20 pi2aes node[1204]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 09 05:00:20 pi2aes node[1204]: *** WARNING *** For more information see Jan 09 05:00:20 pi2aes volumio[1204]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 09 05:00:20 pi2aes volumio[1204]: info: Discovery: Started advertising with name: Pi2AES Jan 09 05:00:20 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "outputs"... Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "albumart"... Jan 09 05:00:20 pi2aes volumio[1204]: info: Plugin example_plugin is not enabled Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "inputs"... Jan 09 05:00:20 pi2aes volumio[1204]: info: Loading plugin "updater_comm"... Jan 09 05:00:20 pi2aes sudo[1420]: pam_unix(sudo:session): session closed for user root Jan 09 05:00:21 pi2aes ntpd[1011]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jan 09 05:00:21 pi2aes volumio[1204]: info: Plugin mpdemulation is not enabled Jan 09 05:00:21 pi2aes volumio[1204]: info: Loading plugin "rest_api"... Jan 09 05:00:21 pi2aes ntpd[1011]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jan 09 05:00:21 pi2aes ntpd[1011]: DNS: Pool taking: 192.121.108.99 Jan 09 05:00:21 pi2aes ntpd[1011]: DNS: Pool taking: 194.58.202.148 Jan 09 05:00:21 pi2aes ntpd[1011]: DNS: Pool taking: 194.58.207.20 Jan 09 05:00:21 pi2aes ntpd[1011]: DNS: Pool taking: 84.217.23.201 Jan 09 05:00:21 pi2aes ntpd[1011]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jan 09 05:00:21 pi2aes volumio[1204]: info: Loading plugin "websocket"... Jan 09 05:00:21 pi2aes volumio[1204]: info: Starting Socket.io Server version 1.7.4 Jan 09 05:00:21 pi2aes volumio[1204]: info: Loading plugin "fusiondsp"... Jan 09 05:00:21 pi2aes volumio[1488]: Forking 3 albumart workers Jan 09 05:00:22 pi2aes volumio[1204]: info: Applying required configuration parameters for plugin fusiondsp Jan 09 05:00:23 pi2aes volumio[1204]: info: Loading plugin "scheduledrestart"... Jan 09 05:01:00 pi2aes ntpd[1011]: CLOCK: time stepped by 36.454085 Jan 09 05:01:00 pi2aes ntpd[1011]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 09 05:01:01 pi2aes volumio[1204]: info: Applying required configuration parameters for plugin scheduledrestart Jan 09 05:01:01 pi2aes volumio[1204]: info: Loading i18n strings for locale sv Jan 09 05:01:01 pi2aes volumio[1204]: Updating browse sources language Jan 09 05:01:01 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::initPlayerControls Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 09 05:01:02 pi2aes volumio[1204]: Express server listening on port 3000 Jan 09 05:01:02 pi2aes volumio[1204]: [Metrics] WebUI: 12s 329.84ms Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreStateMachine::resetVolumioState Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreStateMachine::getcurrentVolume Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::volumioRetrievevolume Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreStateMachine::pushState Jan 09 05:01:02 pi2aes volumio[1204]: info: CorePlayQueue::getTrack 0 Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 05:01:02 pi2aes volumio[1204]: info: CoreCommandRouter::volumioPushState Jan 09 05:01:02 pi2aes volumio[1204]: info: Volumio Network Manager: Network status updated: 1 Jan 09 05:01:03 pi2aes volumio[1204]: verbose: New Socket.io Connection to 192.168.50.107:3000 from 192.168.50.9 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 05:01:03 pi2aes volumio[1204]: info: Reloading queue from file Jan 09 05:01:03 pi2aes volumio[1204]: info: Setting Device type: Raspberry PI Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreStateMachine::setRepeat false single undefined Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreStateMachine::pushState Jan 09 05:01:03 pi2aes volumio[1204]: info: CorePlayQueue::getTrack 0 Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreCommandRouter::volumioPushState Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreStateMachine::setRandom null Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreStateMachine::pushState Jan 09 05:01:03 pi2aes volumio[1204]: info: CorePlayQueue::getTrack 0 Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreCommandRouter::volumioPushState Jan 09 05:01:03 pi2aes volumio[1204]: info: Completed loading Core Plugins Jan 09 05:01:03 pi2aes volumio[1204]: info: Preparing to generate the ALSA configuration file Jan 09 05:01:03 pi2aes volumio[1500]: Starting albumart workers Jan 09 05:01:03 pi2aes volumio[1499]: Starting albumart workers Jan 09 05:01:03 pi2aes volumio[1204]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 09 05:01:03 pi2aes volumio[1204]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Jan 09 05:01:03 pi2aes volumio[1204]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 09 05:01:03 pi2aes volumio[1204]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 09 05:01:03 pi2aes volumio[1204]: info: Reading ALSA contributions from plugins. Jan 09 05:01:03 pi2aes volumio[1501]: Starting albumart workers Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 09 05:01:03 pi2aes volumio[1204]: info: CoreCommandRouter::volumioGetState Jan 09 05:01:03 pi2aes volumio[1204]: info: CorePlayQueue::getTrack 0 Jan 09 05:01:03 pi2aes volumio[1204]: info: Discovery: adding 4d31b383-105d-4113-a81e-da6ff9c16703 Jan 09 05:01:03 pi2aes volumio[1204]: info: Discovery: Found device Pi2IIS Jan 09 05:01:04 pi2aes volumio[1204]: info: Discovery: Connecting to remote: 192.168.50.9 Jan 09 05:01:04 pi2aes volumio[1204]: info: Discovery: adding 6b823da1-ff7a-4a9c-8efe-582264ea08a1 Jan 09 05:01:04 pi2aes volumio[1204]: info: Discovery: Found device Pi2AES Jan 09 05:01:04 pi2aes volumio[1204]: info: CoreCommandRouter::volumioGetState Jan 09 05:01:04 pi2aes volumio[1204]: info: CorePlayQueue::getTrack 0 Jan 09 05:01:04 pi2aes volumio[1204]: info: Discovery: this is already registered, 6b823da1-ff7a-4a9c-8efe-582264ea08a1 Jan 09 05:01:04 pi2aes volumio[1204]: info: Discovery: Found device Pi2AES Jan 09 05:01:04 pi2aes volumio[1204]: info: CoreCommandRouter::volumioGetState Jan 09 05:01:04 pi2aes volumio[1204]: info: CorePlayQueue::getTrack 0 Jan 09 05:01:04 pi2aes volumio[1204]: info: Asound.conf file unchanged, so no further update is needed Jan 09 05:01:04 pi2aes volumio[1204]: info: Output device has changed, restarting MPD Jan 09 05:01:04 pi2aes volumio[1204]: info: Output device has changed, restarting Shairport Sync Jan 09 05:01:04 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:04 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 05:01:04 pi2aes sudo[1544]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 09 05:01:04 pi2aes sudo[1544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:04 pi2aes sudo[1544]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:04 pi2aes volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 09 05:01:04 pi2aes volumio[1204]: info: ___________ START PLUGINS ___________ Jan 09 05:01:04 pi2aes volumio[1204]: info: ControllerMpd::onStart: Initializing MPD Jan 09 05:01:04 pi2aes volumio[1204]: info: Creating MPD Configuration file Jan 09 05:01:04 pi2aes sudo[1549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 09 05:01:04 pi2aes sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:04 pi2aes sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 09 05:01:04 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 09 05:01:04 pi2aes volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 09 05:01:04 pi2aes volumio[1204]: info: [1767931264833] CoreMusicLibrary::Adding element Mediaservrar Jan 09 05:01:04 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 09 05:01:04 pi2aes sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:04 pi2aes volumio[1204]: info: UPNP Browser: Client initialized successfully Jan 09 05:01:04 pi2aes sudo[1553]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:04 pi2aes systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 09 05:01:04 pi2aes systemd[1]: Starting mpd.service - Music Player Daemon... Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 05:01:05 pi2aes sudo[1556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 09 05:01:05 pi2aes sudo[1556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:05 pi2aes sudo[1559]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 09 05:01:05 pi2aes sudo[1559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:01:05 pi2aes sudo[1562]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 09 05:01:05 pi2aes sudo[1559]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:05 pi2aes systemd[1]: mpd.service: Deactivated successfully. Jan 09 05:01:05 pi2aes systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 09 05:01:05 pi2aes systemd[1]: mpd.socket: Deactivated successfully. Jan 09 05:01:05 pi2aes systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 09 05:01:05 pi2aes systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 09 05:01:05 pi2aes systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 09 05:01:05 pi2aes volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 05:01:05 pi2aes systemd[1]: Starting mpd.service - Music Player Daemon... Jan 09 05:01:05 pi2aes sudo[1574]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 09 05:01:05 pi2aes sudo[1574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:01:05 pi2aes sudo[1586]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 09 05:01:05 pi2aes sudo[1574]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:05 pi2aes volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 09 05:01:05 pi2aes volumio[1204]: info: [1767931265369] CoreMusicLibrary::Adding element Last_100 Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 09 05:01:05 pi2aes volumio[1204]: info: [1767931265424] CoreMusicLibrary::Adding element Webradio Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 09 05:01:05 pi2aes volumio[1204]: info: Initializing BBC Radios Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 09 05:01:05 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 05:01:06 pi2aes volumio[1204]: info: FusionDsp - CamillaDSP binary found. Jan 09 05:01:06 pi2aes volumio[1204]: info: Loading i18n strings for locale sv Jan 09 05:01:06 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 09 05:01:06 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:06 pi2aes volumio[1204]: info: FusionDsp - mixtype--------------------- None Jan 09 05:01:06 pi2aes volumio[1204]: info: Scheduling reboots with hours: 5, minutes: 0 in timezone: Europe/Stockholm Jan 09 05:01:06 pi2aes volumio[1204]: info: Scheduling job with cron expression: 0 0 5 * * 0 Jan 09 05:01:06 pi2aes volumio[1204]: info: Scheduling job with cron expression: 0 0 5 * * 1 Jan 09 05:01:06 pi2aes volumio[1204]: info: Scheduling job with cron expression: 0 0 5 * * 2 Jan 09 05:01:06 pi2aes volumio[1204]: info: Scheduling job with cron expression: 0 0 5 * * 3 Jan 09 05:01:06 pi2aes volumio[1204]: info: Scheduling job with cron expression: 0 0 5 * * 4 Jan 09 05:01:06 pi2aes volumio[1204]: info: Scheduling job with cron expression: 0 0 5 * * 5 Jan 09 05:01:06 pi2aes volumio[1204]: info: Scheduling job with cron expression: 0 0 5 * * 6 Jan 09 05:01:06 pi2aes volumio[1204]: info: Volumio Calling Home Jan 09 05:01:07 pi2aes volumio[1204]: info: Preparing to generate the ALSA configuration file Jan 09 05:01:08 pi2aes volumio-remote-updater[783]: [2026-01-09 05:01:08] [connect] Successful connection Jan 09 05:01:08 pi2aes systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 09 05:01:09 pi2aes sudo[1624]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 09 05:01:09 pi2aes sudo[1624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:09 pi2aes sudo[1624]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:09 pi2aes sudo[1627]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 09 05:01:09 pi2aes sudo[1627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:09 pi2aes sudo[1627]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:09 pi2aes volumio[1204]: info: camilladsp service started and running in background, instance 1 Jan 09 05:01:09 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:09 pi2aes volumio[1204]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jan 09 05:01:09 pi2aes volumio[1204]: info: FusionDsp loaded Jan 09 05:01:09 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 09 05:01:09 pi2aes volumio[1204]: error: FusionDsp - Socket connection not established Jan 09 05:01:09 pi2aes volumio[1204]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 09 05:01:09 pi2aes volumio[1204]: info: Adding Signal Path Element [object Object] Jan 09 05:01:09 pi2aes volumio[1204]: info: Adding fusiondspeq DSP Signal Path Element Jan 09 05:01:09 pi2aes volumio[1204]: info: FusionDsp - ---- installed callbackRead Jan 09 05:01:09 pi2aes volumio[1204]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 09 05:01:09 pi2aes volumio[1204]: info: FusionDsp - else 1 -2.5 Jan 09 05:01:09 pi2aes mpd[1587]: 2026-01-09T05:01:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 09 05:01:09 pi2aes sudo[1641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 09 05:01:09 pi2aes sudo[1641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:10 pi2aes volumio[1204]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 09 05:01:10 pi2aes volumio[1204]: info: Reading ALSA contributions from plugins. Jan 09 05:01:10 pi2aes systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jan 09 05:01:10 pi2aes sudo[1641]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:10 pi2aes volumio[1204]: info: Discovery: Connected to remote: 192.168.50.9 Jan 09 05:01:10 pi2aes volumio[1204]: info: MPD Permissions set Jan 09 05:01:10 pi2aes volumio[1204]: info: MPD Permissions set Jan 09 05:01:10 pi2aes volumio[1204]: error: FusionDsp - Reload WebSocket error: [object Object] Jan 09 05:01:10 pi2aes volumio-remote-updater[783]: [2026-01-09 05:01:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1767931268 101 Jan 09 05:01:10 pi2aes volumio[1204]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Jan 09 05:01:10 pi2aes systemd[1]: Started mpd.service - Music Player Daemon. Jan 09 05:01:10 pi2aes sudo[1556]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:10 pi2aes volumio[1204]: info: Volumio called home Jan 09 05:01:10 pi2aes sudo[1549]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:10 pi2aes systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 09 05:01:11 pi2aes systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 09 05:01:11 pi2aes systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 09 05:01:11 pi2aes volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 09 05:01:11 pi2aes systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 09 05:01:11 pi2aes volumio[1204]: info: Completed starting Core Plugins Jan 09 05:01:11 pi2aes volumio[1204]: info: ------------------------------------------- Jan 09 05:01:11 pi2aes volumio[1204]: info: ----- MyVolumio plugins startup ---- Jan 09 05:01:11 pi2aes volumio[1204]: info: ------------------------------------------- Jan 09 05:01:11 pi2aes volumio[1204]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 09 05:01:11 pi2aes upmpdcli[1653]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.50.107 port 49152 IPV6 port 49152 Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes upmpdcli[1667]: writing RSA key Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 05:01:11 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:12 pi2aes volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 09 05:01:13 pi2aes volumio[1204]: error: MPD error: The expression evaluated to a falsy value: Jan 09 05:01:13 pi2aes volumio[1204]: assert.ok(self.idling) Jan 09 05:01:13 pi2aes volumio[1204]: error: The expression evaluated to a falsy value: Jan 09 05:01:13 pi2aes volumio[1204]: assert.ok(self.idling) Jan 09 05:01:13 pi2aes volumio[1204]: info: MPD running with PID1587 Jan 09 05:01:13 pi2aes volumio[1204]: ,establishing connection Jan 09 05:01:13 pi2aes volumio[1204]: error: updateQueue error: null Jan 09 05:01:13 pi2aes volumio[1204]: info: Starting Shairport Sync Jan 09 05:01:13 pi2aes volumio[1204]: info: Starting Shairport Sync Jan 09 05:01:13 pi2aes sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 09 05:01:13 pi2aes volumio[1204]: info: Starting Shairport Sync Jan 09 05:01:13 pi2aes sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:13 pi2aes sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 09 05:01:13 pi2aes sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:13 pi2aes sudo[1693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 09 05:01:13 pi2aes sudo[1693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:13 pi2aes sudo[1688]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:13 pi2aes volumio[1204]: error: updateQueue error: null Jan 09 05:01:13 pi2aes systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 09 05:01:13 pi2aes systemd[1]: shairport-sync.service: Deactivated successfully. Jan 09 05:01:13 pi2aes systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 09 05:01:13 pi2aes systemd[1]: shairport-sync.service: Consumed 1.625s CPU time. Jan 09 05:01:13 pi2aes sudo[1696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 09 05:01:13 pi2aes sudo[1696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:13 pi2aes systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 09 05:01:13 pi2aes sudo[1690]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:13 pi2aes systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 09 05:01:13 pi2aes systemd[1]: shairport-sync.service: Deactivated successfully. Jan 09 05:01:13 pi2aes systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 09 05:01:13 pi2aes systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 09 05:01:13 pi2aes sudo[1693]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:13 pi2aes volumio[1204]: info: Upmpdcli Daemon Started Jan 09 05:01:13 pi2aes volumio[1204]: info: Asound.conf file unchanged, so no further update is needed Jan 09 05:01:13 pi2aes volumio[1204]: info: Output device has changed, restarting MPD Jan 09 05:01:13 pi2aes systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 09 05:01:13 pi2aes systemd[1]: shairport-sync.service: Deactivated successfully. Jan 09 05:01:13 pi2aes systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 09 05:01:13 pi2aes systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 09 05:01:13 pi2aes sudo[1696]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:13 pi2aes volumio[1204]: info: Output device has changed, restarting Shairport Sync Jan 09 05:01:13 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:13 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 05:01:13 pi2aes sudo[1718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 09 05:01:13 pi2aes sudo[1716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 09 05:01:13 pi2aes sudo[1716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:13 pi2aes sudo[1718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:13 pi2aes volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 09 05:01:13 pi2aes systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 09 05:01:13 pi2aes sudo[1716]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:13 pi2aes volumio[1204]: info: Shairport-Sync Started Jan 09 05:01:13 pi2aes volumio[1204]: Error adding Membership: Error: addMembership EINVAL Jan 09 05:01:13 pi2aes upmpdcli[1653]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 09 05:01:14 pi2aes volumio[1204]: info: MPD Permissions set Jan 09 05:01:14 pi2aes volumio[1204]: info: Shairport-Sync Started Jan 09 05:01:14 pi2aes volumio[1204]: info: Shairport-Sync Started Jan 09 05:01:14 pi2aes systemd[1]: mpd.service: Deactivated successfully. Jan 09 05:01:14 pi2aes systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 09 05:01:14 pi2aes systemd[1]: mpd.service: Consumed 4.069s CPU time. Jan 09 05:01:14 pi2aes systemd[1]: mpd.socket: Deactivated successfully. Jan 09 05:01:14 pi2aes systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 09 05:01:14 pi2aes systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::volumioGetState Jan 09 05:01:14 pi2aes volumio[1204]: info: CorePlayQueue::getTrack 0 Jan 09 05:01:14 pi2aes systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 09 05:01:14 pi2aes systemd[1]: Starting mpd.service - Music Player Daemon... Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:14 pi2aes sudo[1740]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 09 05:01:14 pi2aes sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 09 05:01:14 pi2aes sudo[1740]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 09 05:01:14 pi2aes volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 09 05:01:14 pi2aes volumio[1204]: info: Starting Shairport Sync Jan 09 05:01:15 pi2aes sudo[1749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 09 05:01:15 pi2aes sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 09 05:01:15 pi2aes systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 09 05:01:15 pi2aes systemd[1]: shairport-sync.service: Deactivated successfully. Jan 09 05:01:15 pi2aes systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 09 05:01:15 pi2aes systemd[1]: shairport-sync.service: Consumed 1.355s CPU time. Jan 09 05:01:15 pi2aes systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 09 05:01:15 pi2aes sudo[1749]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:15 pi2aes volumio[1204]: info: Shairport-Sync Started Jan 09 05:01:16 pi2aes mpd[1743]: 2026-01-09T05:01:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 09 05:01:16 pi2aes systemd[1]: Started mpd.service - Music Player Daemon. Jan 09 05:01:16 pi2aes upmpdcli[1653]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Jan 09 05:01:16 pi2aes sudo[1718]: pam_unix(sudo:session): session closed for user root Jan 09 05:01:16 pi2aes volumio[1204]: error: updateQueue error: null Jan 09 05:01:18 pi2aes volumio[1204]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 09 05:01:26 pi2aes systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 09 05:01:28 pi2aes volumio[1204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 05:01:28 pi2aes volumio[1204]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Jan 09 05:01:28 pi2aes volumio[1204]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Jan 09 05:01:28 pi2aes volumio[1204]: at new NodeError (node:internal/errors:405:5) Jan 09 05:01:28 pi2aes volumio[1204]: at assert (node:internal/assert:14:11) Jan 09 05:01:28 pi2aes volumio[1204]: at internalConnectMultiple (node:net:1118:3) Jan 09 05:01:28 pi2aes volumio[1204]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Jan 09 05:01:28 pi2aes volumio[1204]: at listOnTimeout (node:internal/timers:575:11) Jan 09 05:01:28 pi2aes volumio[1204]: at process.processTimers (node:internal/timers:514:7) { Jan 09 05:01:28 pi2aes volumio[1204]: code: 'ERR_INTERNAL_ASSERTION' Jan 09 05:01:28 pi2aes volumio[1204]: } Jan 09 05:01:28 pi2aes volumio[1204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 05:01:29 pi2aes systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 09 05:01:29 pi2aes systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 09 05:01:32 pi2aes sudo[1838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-09 05:00' Jan 09 05:01:32 pi2aes sudo[1838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="5250573efb99f2852fa27ea7e92def8586093039" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="d9e2b6784b9276d5a676bf564ce7524ad160509b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 19 15:53:15 UTC 2025" VOLUMIO_VERSION="4.082" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="47d7740e8c55792fe06a847eb7ad6b29"