Oct 28 04:45:00.368546 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 28 04:45:00.368571 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT Tue Oct 28 03:20:07 -00 2025 Oct 28 04:45:00.368580 kernel: KASLR enabled Oct 28 04:45:00.368586 kernel: efi: EFI v2.7 by EDK II Oct 28 04:45:00.368591 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Oct 28 04:45:00.368597 kernel: random: crng init done Oct 28 04:45:00.368604 kernel: secureboot: Secure boot disabled Oct 28 04:45:00.368610 kernel: ACPI: Early table checksum verification disabled Oct 28 04:45:00.368618 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 28 04:45:00.368624 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 28 04:45:00.368630 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:45:00.368636 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:45:00.368642 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:45:00.368648 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:45:00.368657 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:45:00.368663 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:45:00.368670 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:45:00.368676 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:45:00.368682 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 28 04:45:00.368689 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 28 04:45:00.368695 kernel: ACPI: Use ACPI SPCR as default console: No Oct 28 04:45:00.368702 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 04:45:00.368709 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Oct 28 04:45:00.368716 kernel: Zone ranges: Oct 28 04:45:00.368722 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 04:45:00.368728 kernel: DMA32 empty Oct 28 04:45:00.368735 kernel: Normal empty Oct 28 04:45:00.368741 kernel: Device empty Oct 28 04:45:00.368747 kernel: Movable zone start for each node Oct 28 04:45:00.368753 kernel: Early memory node ranges Oct 28 04:45:00.368760 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Oct 28 04:45:00.368766 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Oct 28 04:45:00.368773 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Oct 28 04:45:00.368779 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 28 04:45:00.368787 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 28 04:45:00.368793 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 28 04:45:00.368800 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 28 04:45:00.368806 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 28 04:45:00.368813 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 28 04:45:00.368819 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 28 04:45:00.368829 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 28 04:45:00.368836 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 28 04:45:00.368843 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 28 04:45:00.368850 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 28 04:45:00.368868 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Oct 28 04:45:00.368875 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Oct 28 04:45:00.368882 kernel: psci: probing for conduit method from ACPI. Oct 28 04:45:00.368888 kernel: psci: PSCIv1.1 detected in firmware. Oct 28 04:45:00.368897 kernel: psci: Using standard PSCI v0.2 function IDs Oct 28 04:45:00.368904 kernel: psci: Trusted OS migration not required Oct 28 04:45:00.368911 kernel: psci: SMC Calling Convention v1.1 Oct 28 04:45:00.368918 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Oct 28 04:45:00.368925 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Oct 28 04:45:00.368932 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Oct 28 04:45:00.368939 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 28 04:45:00.368946 kernel: Detected PIPT I-cache on CPU0 Oct 28 04:45:00.368953 kernel: CPU features: detected: GIC system register CPU interface Oct 28 04:45:00.368959 kernel: CPU features: detected: Spectre-v4 Oct 28 04:45:00.368966 kernel: CPU features: detected: Spectre-BHB Oct 28 04:45:00.368974 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 28 04:45:00.368981 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 28 04:45:00.368988 kernel: CPU features: detected: ARM erratum 1418040 Oct 28 04:45:00.368995 kernel: CPU features: detected: SSBS not fully self-synchronizing Oct 28 04:45:00.369001 kernel: alternatives: applying boot alternatives Oct 28 04:45:00.369009 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=1f35b74acbf2082d1ccaf19af5ba856ed6811e7fe1caa7e6e44e2aaa205180aa Oct 28 04:45:00.369017 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 28 04:45:00.369024 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 28 04:45:00.369030 kernel: Fallback order for Node 0: 0 Oct 28 04:45:00.369037 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Oct 28 04:45:00.369045 kernel: Policy zone: DMA Oct 28 04:45:00.369052 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 28 04:45:00.369059 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Oct 28 04:45:00.369065 kernel: software IO TLB: area num 4. Oct 28 04:45:00.369072 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Oct 28 04:45:00.369079 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Oct 28 04:45:00.369086 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 28 04:45:00.369093 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 28 04:45:00.369100 kernel: rcu: RCU event tracing is enabled. Oct 28 04:45:00.369108 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 28 04:45:00.369115 kernel: Trampoline variant of Tasks RCU enabled. Oct 28 04:45:00.369123 kernel: Tracing variant of Tasks RCU enabled. Oct 28 04:45:00.369130 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 28 04:45:00.369137 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 28 04:45:00.369144 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 28 04:45:00.369151 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Oct 28 04:45:00.369158 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 28 04:45:00.369165 kernel: GICv3: 256 SPIs implemented Oct 28 04:45:00.369171 kernel: GICv3: 0 Extended SPIs implemented Oct 28 04:45:00.369178 kernel: Root IRQ handler: gic_handle_irq Oct 28 04:45:00.369185 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Oct 28 04:45:00.369192 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Oct 28 04:45:00.369200 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 28 04:45:00.369206 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 28 04:45:00.369213 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Oct 28 04:45:00.369220 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Oct 28 04:45:00.369227 kernel: GICv3: using LPI property table @0x0000000040130000 Oct 28 04:45:00.369234 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Oct 28 04:45:00.369241 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 28 04:45:00.369248 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:45:00.369255 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 28 04:45:00.369261 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 28 04:45:00.369268 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 28 04:45:00.369277 kernel: arm-pv: using stolen time PV Oct 28 04:45:00.369284 kernel: Console: colour dummy device 80x25 Oct 28 04:45:00.369291 kernel: ACPI: Core revision 20240827 Oct 28 04:45:00.369298 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 28 04:45:00.369306 kernel: pid_max: default: 32768 minimum: 301 Oct 28 04:45:00.369313 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 28 04:45:00.369320 kernel: landlock: Up and running. Oct 28 04:45:00.369327 kernel: SELinux: Initializing. Oct 28 04:45:00.369335 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 04:45:00.369342 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 28 04:45:00.369349 kernel: rcu: Hierarchical SRCU implementation. Oct 28 04:45:00.369357 kernel: rcu: Max phase no-delay instances is 400. Oct 28 04:45:00.369364 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 28 04:45:00.369371 kernel: Remapping and enabling EFI services. Oct 28 04:45:00.369447 kernel: smp: Bringing up secondary CPUs ... Oct 28 04:45:00.369459 kernel: Detected PIPT I-cache on CPU1 Oct 28 04:45:00.369472 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 28 04:45:00.369480 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Oct 28 04:45:00.369488 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:45:00.369496 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 28 04:45:00.369503 kernel: Detected PIPT I-cache on CPU2 Oct 28 04:45:00.369511 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 28 04:45:00.369520 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Oct 28 04:45:00.369528 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:45:00.369535 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 28 04:45:00.369543 kernel: Detected PIPT I-cache on CPU3 Oct 28 04:45:00.369550 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 28 04:45:00.369558 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Oct 28 04:45:00.369566 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 28 04:45:00.369574 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 28 04:45:00.369582 kernel: smp: Brought up 1 node, 4 CPUs Oct 28 04:45:00.369723 kernel: SMP: Total of 4 processors activated. Oct 28 04:45:00.369731 kernel: CPU: All CPU(s) started at EL1 Oct 28 04:45:00.369739 kernel: CPU features: detected: 32-bit EL0 Support Oct 28 04:45:00.369747 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 28 04:45:00.369754 kernel: CPU features: detected: Common not Private translations Oct 28 04:45:00.369767 kernel: CPU features: detected: CRC32 instructions Oct 28 04:45:00.369775 kernel: CPU features: detected: Enhanced Virtualization Traps Oct 28 04:45:00.369782 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Oct 28 04:45:00.369790 kernel: CPU features: detected: LSE atomic instructions Oct 28 04:45:00.369797 kernel: CPU features: detected: Privileged Access Never Oct 28 04:45:00.369805 kernel: CPU features: detected: RAS Extension Support Oct 28 04:45:00.369812 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 28 04:45:00.369820 kernel: alternatives: applying system-wide alternatives Oct 28 04:45:00.369829 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Oct 28 04:45:00.369837 kernel: Memory: 2450400K/2572288K available (11136K kernel code, 2456K rwdata, 9084K rodata, 12992K init, 1038K bss, 99552K reserved, 16384K cma-reserved) Oct 28 04:45:00.369845 kernel: devtmpfs: initialized Oct 28 04:45:00.369860 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 28 04:45:00.369868 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 28 04:45:00.369876 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Oct 28 04:45:00.369883 kernel: 0 pages in range for non-PLT usage Oct 28 04:45:00.369893 kernel: 515056 pages in range for PLT usage Oct 28 04:45:00.369900 kernel: pinctrl core: initialized pinctrl subsystem Oct 28 04:45:00.369908 kernel: SMBIOS 3.0.0 present. Oct 28 04:45:00.369915 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 28 04:45:00.369922 kernel: DMI: Memory slots populated: 1/1 Oct 28 04:45:00.369930 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 28 04:45:00.369937 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 28 04:45:00.369947 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 28 04:45:00.369954 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 28 04:45:00.369962 kernel: audit: initializing netlink subsys (disabled) Oct 28 04:45:00.369970 kernel: audit: type=2000 audit(0.016:1): state=initialized audit_enabled=0 res=1 Oct 28 04:45:00.369983 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 28 04:45:00.369991 kernel: cpuidle: using governor menu Oct 28 04:45:00.370003 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 28 04:45:00.370016 kernel: ASID allocator initialised with 32768 entries Oct 28 04:45:00.370027 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 28 04:45:00.370035 kernel: Serial: AMBA PL011 UART driver Oct 28 04:45:00.370043 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 28 04:45:00.370052 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Oct 28 04:45:00.370059 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Oct 28 04:45:00.370067 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Oct 28 04:45:00.370074 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 28 04:45:00.370083 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Oct 28 04:45:00.370091 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Oct 28 04:45:00.370098 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Oct 28 04:45:00.370106 kernel: ACPI: Added _OSI(Module Device) Oct 28 04:45:00.370113 kernel: ACPI: Added _OSI(Processor Device) Oct 28 04:45:00.370121 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 28 04:45:00.370129 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 28 04:45:00.370137 kernel: ACPI: Interpreter enabled Oct 28 04:45:00.370145 kernel: ACPI: Using GIC for interrupt routing Oct 28 04:45:00.370152 kernel: ACPI: MCFG table detected, 1 entries Oct 28 04:45:00.370160 kernel: ACPI: CPU0 has been hot-added Oct 28 04:45:00.370167 kernel: ACPI: CPU1 has been hot-added Oct 28 04:45:00.370174 kernel: ACPI: CPU2 has been hot-added Oct 28 04:45:00.370182 kernel: ACPI: CPU3 has been hot-added Oct 28 04:45:00.370191 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 28 04:45:00.370198 kernel: printk: legacy console [ttyAMA0] enabled Oct 28 04:45:00.370206 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 28 04:45:00.370404 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 28 04:45:00.370502 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 28 04:45:00.370755 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 28 04:45:00.370866 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 28 04:45:00.370955 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 28 04:45:00.370965 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 28 04:45:00.370973 kernel: PCI host bridge to bus 0000:00 Oct 28 04:45:00.371062 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 28 04:45:00.371137 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 28 04:45:00.371213 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 28 04:45:00.371294 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 28 04:45:00.371415 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Oct 28 04:45:00.371516 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Oct 28 04:45:00.371610 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Oct 28 04:45:00.371693 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Oct 28 04:45:00.371788 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Oct 28 04:45:00.371908 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Oct 28 04:45:00.371997 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Oct 28 04:45:00.372079 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Oct 28 04:45:00.372153 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 28 04:45:00.372277 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 28 04:45:00.372362 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 28 04:45:00.372373 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Oct 28 04:45:00.372393 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Oct 28 04:45:00.372404 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Oct 28 04:45:00.372412 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Oct 28 04:45:00.372420 kernel: iommu: Default domain type: Translated Oct 28 04:45:00.372430 kernel: iommu: DMA domain TLB invalidation policy: strict mode Oct 28 04:45:00.372438 kernel: efivars: Registered efivars operations Oct 28 04:45:00.372445 kernel: vgaarb: loaded Oct 28 04:45:00.372453 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 28 04:45:00.372461 kernel: VFS: Disk quotas dquot_6.6.0 Oct 28 04:45:00.372468 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 28 04:45:00.372476 kernel: pnp: PnP ACPI init Oct 28 04:45:00.372639 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 28 04:45:00.372654 kernel: pnp: PnP ACPI: found 1 devices Oct 28 04:45:00.372681 kernel: NET: Registered PF_INET protocol family Oct 28 04:45:00.372690 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 28 04:45:00.372698 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 28 04:45:00.372706 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 28 04:45:00.372714 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 28 04:45:00.372726 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Oct 28 04:45:00.372734 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 28 04:45:00.372757 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 04:45:00.372765 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 28 04:45:00.372774 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 28 04:45:00.372781 kernel: PCI: CLS 0 bytes, default 64 Oct 28 04:45:00.372789 kernel: kvm [1]: HYP mode not available Oct 28 04:45:00.372799 kernel: Initialise system trusted keyrings Oct 28 04:45:00.372807 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 28 04:45:00.372827 kernel: Key type asymmetric registered Oct 28 04:45:00.372836 kernel: Asymmetric key parser 'x509' registered Oct 28 04:45:00.372844 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Oct 28 04:45:00.372868 kernel: io scheduler mq-deadline registered Oct 28 04:45:00.372878 kernel: io scheduler kyber registered Oct 28 04:45:00.372888 kernel: io scheduler bfq registered Oct 28 04:45:00.372896 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 28 04:45:00.372904 kernel: ACPI: button: Power Button [PWRB] Oct 28 04:45:00.372913 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Oct 28 04:45:00.373056 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 28 04:45:00.373070 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 28 04:45:00.373078 kernel: thunder_xcv, ver 1.0 Oct 28 04:45:00.373106 kernel: thunder_bgx, ver 1.0 Oct 28 04:45:00.373115 kernel: nicpf, ver 1.0 Oct 28 04:45:00.373123 kernel: nicvf, ver 1.0 Oct 28 04:45:00.373304 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 28 04:45:00.373442 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-28T04:44:59 UTC (1761626699) Oct 28 04:45:00.373455 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 28 04:45:00.373468 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Oct 28 04:45:00.373477 kernel: watchdog: NMI not fully supported Oct 28 04:45:00.373484 kernel: watchdog: Hard watchdog permanently disabled Oct 28 04:45:00.373492 kernel: NET: Registered PF_INET6 protocol family Oct 28 04:45:00.373500 kernel: Segment Routing with IPv6 Oct 28 04:45:00.373507 kernel: In-situ OAM (IOAM) with IPv6 Oct 28 04:45:00.373515 kernel: NET: Registered PF_PACKET protocol family Oct 28 04:45:00.373522 kernel: Key type dns_resolver registered Oct 28 04:45:00.373531 kernel: registered taskstats version 1 Oct 28 04:45:00.373539 kernel: Loading compiled-in X.509 certificates Oct 28 04:45:00.373546 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 26b98883ef58589f904405bf68dab5f3eec1fa06' Oct 28 04:45:00.373554 kernel: Demotion targets for Node 0: null Oct 28 04:45:00.373562 kernel: Key type .fscrypt registered Oct 28 04:45:00.373569 kernel: Key type fscrypt-provisioning registered Oct 28 04:45:00.373577 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 28 04:45:00.373586 kernel: ima: Allocated hash algorithm: sha1 Oct 28 04:45:00.373594 kernel: ima: No architecture policies found Oct 28 04:45:00.373602 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 28 04:45:00.373609 kernel: clk: Disabling unused clocks Oct 28 04:45:00.373617 kernel: PM: genpd: Disabling unused power domains Oct 28 04:45:00.373624 kernel: Freeing unused kernel memory: 12992K Oct 28 04:45:00.373632 kernel: Run /init as init process Oct 28 04:45:00.373641 kernel: with arguments: Oct 28 04:45:00.373648 kernel: /init Oct 28 04:45:00.373656 kernel: with environment: Oct 28 04:45:00.373685 kernel: HOME=/ Oct 28 04:45:00.373694 kernel: TERM=linux Oct 28 04:45:00.373802 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Oct 28 04:45:00.373973 kernel: virtio_blk virtio1: [vda] 27000832 512-byte logical blocks (13.8 GB/12.9 GiB) Oct 28 04:45:00.373993 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 28 04:45:00.374001 kernel: GPT:16515071 != 27000831 Oct 28 04:45:00.374009 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 28 04:45:00.374017 kernel: GPT:16515071 != 27000831 Oct 28 04:45:00.374024 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 28 04:45:00.374031 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 28 04:45:00.374041 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374049 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374057 kernel: SCSI subsystem initialized Oct 28 04:45:00.374064 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374071 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 28 04:45:00.374079 kernel: device-mapper: uevent: version 1.0.3 Oct 28 04:45:00.374087 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 28 04:45:00.374096 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Oct 28 04:45:00.374104 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374111 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374118 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374126 kernel: raid6: neonx8 gen() 15675 MB/s Oct 28 04:45:00.374134 kernel: raid6: neonx4 gen() 15716 MB/s Oct 28 04:45:00.374141 kernel: raid6: neonx2 gen() 13211 MB/s Oct 28 04:45:00.374149 kernel: raid6: neonx1 gen() 10419 MB/s Oct 28 04:45:00.374158 kernel: raid6: int64x8 gen() 6895 MB/s Oct 28 04:45:00.374165 kernel: raid6: int64x4 gen() 7359 MB/s Oct 28 04:45:00.374173 kernel: raid6: int64x2 gen() 6108 MB/s Oct 28 04:45:00.374180 kernel: raid6: int64x1 gen() 5043 MB/s Oct 28 04:45:00.374188 kernel: raid6: using algorithm neonx4 gen() 15716 MB/s Oct 28 04:45:00.374196 kernel: raid6: .... xor() 12350 MB/s, rmw enabled Oct 28 04:45:00.374204 kernel: raid6: using neon recovery algorithm Oct 28 04:45:00.374261 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374269 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374276 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374284 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374291 kernel: xor: measuring software checksum speed Oct 28 04:45:00.374298 kernel: 8regs : 21636 MB/sec Oct 28 04:45:00.374306 kernel: 32regs : 21693 MB/sec Oct 28 04:45:00.374314 kernel: arm64_neon : 28196 MB/sec Oct 28 04:45:00.374323 kernel: xor: using function: arm64_neon (28196 MB/sec) Oct 28 04:45:00.374330 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374338 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 28 04:45:00.374346 kernel: BTRFS: device fsid caa7e5e1-1ce1-458b-ad52-fda248cb60f9 devid 1 transid 36 /dev/mapper/usr (253:0) scanned by mount (205) Oct 28 04:45:00.374354 kernel: BTRFS info (device dm-0): first mount of filesystem caa7e5e1-1ce1-458b-ad52-fda248cb60f9 Oct 28 04:45:00.374362 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Oct 28 04:45:00.374370 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 28 04:45:00.374379 kernel: BTRFS info (device dm-0): enabling free space tree Oct 28 04:45:00.374399 kernel: Invalid ELF header magic: != \u007fELF Oct 28 04:45:00.374407 kernel: loop: module loaded Oct 28 04:45:00.374415 kernel: loop0: detected capacity change from 0 to 91480 Oct 28 04:45:00.374422 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 28 04:45:00.374431 systemd[1]: Successfully made /usr/ read-only. Oct 28 04:45:00.374442 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 28 04:45:00.374453 systemd[1]: Detected virtualization kvm. Oct 28 04:45:00.374461 systemd[1]: Detected architecture arm64. Oct 28 04:45:00.374469 systemd[1]: Running in initrd. Oct 28 04:45:00.374477 systemd[1]: No hostname configured, using default hostname. Oct 28 04:45:00.374486 systemd[1]: Hostname set to . Oct 28 04:45:00.374494 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 28 04:45:00.374503 systemd[1]: Queued start job for default target initrd.target. Oct 28 04:45:00.374511 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 28 04:45:00.374519 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 28 04:45:00.374528 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 28 04:45:00.374536 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 28 04:45:00.374545 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 28 04:45:00.374555 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 28 04:45:00.374569 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 28 04:45:00.374579 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 28 04:45:00.374588 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 28 04:45:00.374596 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 28 04:45:00.374606 systemd[1]: Reached target paths.target - Path Units. Oct 28 04:45:00.374615 systemd[1]: Reached target slices.target - Slice Units. Oct 28 04:45:00.374623 systemd[1]: Reached target swap.target - Swaps. Oct 28 04:45:00.374631 systemd[1]: Reached target timers.target - Timer Units. Oct 28 04:45:00.374640 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 28 04:45:00.374648 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 28 04:45:00.374658 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 28 04:45:00.374667 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 28 04:45:00.374676 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 28 04:45:00.374685 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 28 04:45:00.374694 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 28 04:45:00.374702 systemd[1]: Reached target sockets.target - Socket Units. Oct 28 04:45:00.374711 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 28 04:45:00.374721 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 28 04:45:00.374730 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 28 04:45:00.374738 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 28 04:45:00.374747 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Oct 28 04:45:00.374756 systemd[1]: Starting systemd-fsck-usr.service... Oct 28 04:45:00.374764 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 28 04:45:00.374773 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 28 04:45:00.374783 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 04:45:00.374792 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 28 04:45:00.374800 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 28 04:45:00.374810 systemd[1]: Finished systemd-fsck-usr.service. Oct 28 04:45:00.374819 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 28 04:45:00.374828 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 28 04:45:00.374868 systemd-journald[344]: Collecting audit messages is disabled. Oct 28 04:45:00.374892 kernel: Bridge firewalling registered Oct 28 04:45:00.374901 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 28 04:45:00.374910 systemd-journald[344]: Journal started Oct 28 04:45:00.374928 systemd-journald[344]: Runtime Journal (/run/log/journal/35a5254e36f042c7806dd3f18b537ec1) is 6M, max 48.5M, 42.4M free. Oct 28 04:45:00.370669 systemd-modules-load[345]: Inserted module 'br_netfilter' Oct 28 04:45:00.378424 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 04:45:00.380643 systemd[1]: Started systemd-journald.service - Journal Service. Oct 28 04:45:00.382467 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 28 04:45:00.387116 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 28 04:45:00.388888 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 28 04:45:00.391022 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 28 04:45:00.406174 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 28 04:45:00.415596 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 28 04:45:00.417975 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 28 04:45:00.418050 systemd-tmpfiles[369]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 28 04:45:00.421058 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 28 04:45:00.423896 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 28 04:45:00.435641 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 28 04:45:00.441934 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 28 04:45:00.466012 dracut-cmdline[390]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=1f35b74acbf2082d1ccaf19af5ba856ed6811e7fe1caa7e6e44e2aaa205180aa Oct 28 04:45:00.473566 systemd-resolved[380]: Positive Trust Anchors: Oct 28 04:45:00.473585 systemd-resolved[380]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 04:45:00.473588 systemd-resolved[380]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 28 04:45:00.473620 systemd-resolved[380]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 28 04:45:00.496425 systemd-resolved[380]: Defaulting to hostname 'linux'. Oct 28 04:45:00.497491 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 28 04:45:00.498536 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 28 04:45:00.551412 kernel: Loading iSCSI transport class v2.0-870. Oct 28 04:45:00.560421 kernel: iscsi: registered transport (tcp) Oct 28 04:45:00.573433 kernel: iscsi: registered transport (qla4xxx) Oct 28 04:45:00.573461 kernel: QLogic iSCSI HBA Driver Oct 28 04:45:00.594775 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 28 04:45:00.618075 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 28 04:45:00.623053 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 28 04:45:00.672743 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 28 04:45:00.675188 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 28 04:45:00.676897 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 28 04:45:00.711065 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 28 04:45:00.713781 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 28 04:45:00.743809 systemd-udevd[625]: Using default interface naming scheme 'v257'. Oct 28 04:45:00.751719 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 28 04:45:00.755087 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 28 04:45:00.782604 dracut-pre-trigger[693]: rd.md=0: removing MD RAID activation Oct 28 04:45:00.784685 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 28 04:45:00.787742 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 28 04:45:00.812348 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 28 04:45:00.815894 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 28 04:45:00.833147 systemd-networkd[740]: lo: Link UP Oct 28 04:45:00.833157 systemd-networkd[740]: lo: Gained carrier Oct 28 04:45:00.833717 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 28 04:45:00.835103 systemd[1]: Reached target network.target - Network. Oct 28 04:45:00.873705 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 28 04:45:00.877035 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 28 04:45:00.936914 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 28 04:45:00.947074 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 28 04:45:00.957204 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 28 04:45:00.965641 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 28 04:45:00.968117 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 28 04:45:00.990301 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 04:45:00.990450 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 04:45:00.993075 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 04:45:00.997489 disk-uuid[800]: Primary Header is updated. Oct 28 04:45:00.997489 disk-uuid[800]: Secondary Entries is updated. Oct 28 04:45:00.997489 disk-uuid[800]: Secondary Header is updated. Oct 28 04:45:00.995711 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 04:45:00.996903 systemd-networkd[740]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 04:45:00.996908 systemd-networkd[740]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 28 04:45:00.998019 systemd-networkd[740]: eth0: Link UP Oct 28 04:45:00.998174 systemd-networkd[740]: eth0: Gained carrier Oct 28 04:45:00.998184 systemd-networkd[740]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 04:45:01.017158 systemd-networkd[740]: eth0: DHCPv4 address 10.0.0.85/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 28 04:45:01.029339 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 04:45:01.065510 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 28 04:45:01.066974 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 28 04:45:01.068531 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 28 04:45:01.070455 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 28 04:45:01.073429 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 28 04:45:01.098089 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 28 04:45:02.027753 disk-uuid[803]: Warning: The kernel is still using the old partition table. Oct 28 04:45:02.027753 disk-uuid[803]: The new table will be used at the next reboot or after you Oct 28 04:45:02.027753 disk-uuid[803]: run partprobe(8) or kpartx(8) Oct 28 04:45:02.027753 disk-uuid[803]: The operation has completed successfully. Oct 28 04:45:02.036369 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 28 04:45:02.036505 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 28 04:45:02.039217 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 28 04:45:02.066377 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (833) Oct 28 04:45:02.066425 kernel: BTRFS info (device vda6): first mount of filesystem 52a6d1c0-1d9b-4b64-8a3d-40978d54767d Oct 28 04:45:02.066436 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 28 04:45:02.069733 kernel: BTRFS info (device vda6): turning on async discard Oct 28 04:45:02.069756 kernel: BTRFS info (device vda6): enabling free space tree Oct 28 04:45:02.075397 kernel: BTRFS info (device vda6): last unmount of filesystem 52a6d1c0-1d9b-4b64-8a3d-40978d54767d Oct 28 04:45:02.075640 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 28 04:45:02.076513 systemd-networkd[740]: eth0: Gained IPv6LL Oct 28 04:45:02.077766 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 28 04:45:02.181527 ignition[852]: Ignition 2.22.0 Oct 28 04:45:02.181545 ignition[852]: Stage: fetch-offline Oct 28 04:45:02.181582 ignition[852]: no configs at "/usr/lib/ignition/base.d" Oct 28 04:45:02.181591 ignition[852]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 04:45:02.181738 ignition[852]: parsed url from cmdline: "" Oct 28 04:45:02.181741 ignition[852]: no config URL provided Oct 28 04:45:02.181746 ignition[852]: reading system config file "/usr/lib/ignition/user.ign" Oct 28 04:45:02.181756 ignition[852]: no config at "/usr/lib/ignition/user.ign" Oct 28 04:45:02.181794 ignition[852]: op(1): [started] loading QEMU firmware config module Oct 28 04:45:02.181798 ignition[852]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 28 04:45:02.187362 ignition[852]: op(1): [finished] loading QEMU firmware config module Oct 28 04:45:02.196294 ignition[852]: parsing config with SHA512: 6d66decf242b63efa596d77bda6a3ce7a726bdce1332826f9f6a54bd75c311536cb104b80143d70f0bc1ff23e4a64815820a3bed001a4ed8c548e66a48c99856 Oct 28 04:45:02.200908 unknown[852]: fetched base config from "system" Oct 28 04:45:02.200920 unknown[852]: fetched user config from "qemu" Oct 28 04:45:02.201172 ignition[852]: fetch-offline: fetch-offline passed Oct 28 04:45:02.203792 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 28 04:45:02.201229 ignition[852]: Ignition finished successfully Oct 28 04:45:02.205038 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Oct 28 04:45:02.205894 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 28 04:45:02.244857 ignition[867]: Ignition 2.22.0 Oct 28 04:45:02.244872 ignition[867]: Stage: kargs Oct 28 04:45:02.245015 ignition[867]: no configs at "/usr/lib/ignition/base.d" Oct 28 04:45:02.245023 ignition[867]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 04:45:02.245596 ignition[867]: kargs: kargs passed Oct 28 04:45:02.247624 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 28 04:45:02.245639 ignition[867]: Ignition finished successfully Oct 28 04:45:02.249669 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 28 04:45:02.282792 ignition[875]: Ignition 2.22.0 Oct 28 04:45:02.282812 ignition[875]: Stage: disks Oct 28 04:45:02.282970 ignition[875]: no configs at "/usr/lib/ignition/base.d" Oct 28 04:45:02.282978 ignition[875]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 04:45:02.285362 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 28 04:45:02.283556 ignition[875]: disks: disks passed Oct 28 04:45:02.286871 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 28 04:45:02.283600 ignition[875]: Ignition finished successfully Oct 28 04:45:02.288337 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 28 04:45:02.289801 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 28 04:45:02.291357 systemd[1]: Reached target sysinit.target - System Initialization. Oct 28 04:45:02.292803 systemd[1]: Reached target basic.target - Basic System. Oct 28 04:45:02.295349 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 28 04:45:02.337463 systemd-fsck[885]: ROOT: clean, 15/456736 files, 38230/456704 blocks Oct 28 04:45:02.495864 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 28 04:45:02.498904 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 28 04:45:02.570452 kernel: EXT4-fs (vda9): mounted filesystem 8fca153a-dda8-4620-af0a-8ec5cf72e73c r/w with ordered data mode. Quota mode: none. Oct 28 04:45:02.571142 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 28 04:45:02.572341 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 28 04:45:02.574656 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 28 04:45:02.576175 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 28 04:45:02.577161 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 28 04:45:02.577192 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 28 04:45:02.577216 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 28 04:45:02.590967 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 28 04:45:02.593657 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 28 04:45:02.598414 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (893) Oct 28 04:45:02.598491 kernel: BTRFS info (device vda6): first mount of filesystem 52a6d1c0-1d9b-4b64-8a3d-40978d54767d Oct 28 04:45:02.598563 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 28 04:45:02.600803 kernel: BTRFS info (device vda6): turning on async discard Oct 28 04:45:02.600874 kernel: BTRFS info (device vda6): enabling free space tree Oct 28 04:45:02.601795 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 28 04:45:02.633018 initrd-setup-root[918]: cut: /sysroot/etc/passwd: No such file or directory Oct 28 04:45:02.637302 initrd-setup-root[925]: cut: /sysroot/etc/group: No such file or directory Oct 28 04:45:02.641376 initrd-setup-root[932]: cut: /sysroot/etc/shadow: No such file or directory Oct 28 04:45:02.644377 initrd-setup-root[939]: cut: /sysroot/etc/gshadow: No such file or directory Oct 28 04:45:02.714962 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 28 04:45:02.717304 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 28 04:45:02.718965 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 28 04:45:02.739700 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 28 04:45:02.742398 kernel: BTRFS info (device vda6): last unmount of filesystem 52a6d1c0-1d9b-4b64-8a3d-40978d54767d Oct 28 04:45:02.755548 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 28 04:45:02.771396 ignition[1009]: INFO : Ignition 2.22.0 Oct 28 04:45:02.771396 ignition[1009]: INFO : Stage: mount Oct 28 04:45:02.773007 ignition[1009]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 28 04:45:02.773007 ignition[1009]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 04:45:02.773007 ignition[1009]: INFO : mount: mount passed Oct 28 04:45:02.773007 ignition[1009]: INFO : Ignition finished successfully Oct 28 04:45:02.773901 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 28 04:45:02.775872 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 28 04:45:03.572695 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 28 04:45:03.596421 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1020) Oct 28 04:45:03.599894 kernel: BTRFS info (device vda6): first mount of filesystem 52a6d1c0-1d9b-4b64-8a3d-40978d54767d Oct 28 04:45:03.599916 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 28 04:45:03.602918 kernel: BTRFS info (device vda6): turning on async discard Oct 28 04:45:03.602940 kernel: BTRFS info (device vda6): enabling free space tree Oct 28 04:45:03.604577 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 28 04:45:03.635171 ignition[1037]: INFO : Ignition 2.22.0 Oct 28 04:45:03.635171 ignition[1037]: INFO : Stage: files Oct 28 04:45:03.636893 ignition[1037]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 28 04:45:03.636893 ignition[1037]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 04:45:03.636893 ignition[1037]: DEBUG : files: compiled without relabeling support, skipping Oct 28 04:45:03.636893 ignition[1037]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 28 04:45:03.636893 ignition[1037]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 28 04:45:03.643030 ignition[1037]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 28 04:45:03.643030 ignition[1037]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 28 04:45:03.643030 ignition[1037]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 28 04:45:03.643030 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Oct 28 04:45:03.643030 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Oct 28 04:45:03.640775 unknown[1037]: wrote ssh authorized keys file for user: core Oct 28 04:45:03.665811 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 28 04:45:03.667577 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 28 04:45:03.667577 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 28 04:45:03.716342 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 28 04:45:03.716342 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 28 04:45:03.720710 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Oct 28 04:45:04.350279 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Oct 28 04:45:04.617430 ignition[1037]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Oct 28 04:45:04.617430 ignition[1037]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Oct 28 04:45:04.621225 ignition[1037]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 28 04:45:04.621225 ignition[1037]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Oct 28 04:45:04.621225 ignition[1037]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Oct 28 04:45:04.621225 ignition[1037]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Oct 28 04:45:04.637158 ignition[1037]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Oct 28 04:45:04.640539 ignition[1037]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Oct 28 04:45:04.643297 ignition[1037]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Oct 28 04:45:04.643297 ignition[1037]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 28 04:45:04.643297 ignition[1037]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 28 04:45:04.643297 ignition[1037]: INFO : files: files passed Oct 28 04:45:04.643297 ignition[1037]: INFO : Ignition finished successfully Oct 28 04:45:04.644680 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 28 04:45:04.646613 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 28 04:45:04.650782 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 28 04:45:04.659923 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 28 04:45:04.660050 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 28 04:45:04.667149 initrd-setup-root-after-ignition[1069]: grep: /sysroot/oem/oem-release: No such file or directory Oct 28 04:45:04.669850 initrd-setup-root-after-ignition[1071]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 28 04:45:04.669850 initrd-setup-root-after-ignition[1071]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 28 04:45:04.672723 initrd-setup-root-after-ignition[1075]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 28 04:45:04.673893 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 28 04:45:04.675320 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 28 04:45:04.677924 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 28 04:45:04.731496 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 28 04:45:04.732458 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 28 04:45:04.733724 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 28 04:45:04.735251 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 28 04:45:04.737549 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 28 04:45:04.738427 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 28 04:45:04.765314 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 28 04:45:04.767866 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 28 04:45:04.786474 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Oct 28 04:45:04.786630 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 28 04:45:04.788520 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 28 04:45:04.790323 systemd[1]: Stopped target timers.target - Timer Units. Oct 28 04:45:04.792002 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 28 04:45:04.792145 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 28 04:45:04.794587 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 28 04:45:04.796425 systemd[1]: Stopped target basic.target - Basic System. Oct 28 04:45:04.797965 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 28 04:45:04.799443 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 28 04:45:04.801365 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 28 04:45:04.803195 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 28 04:45:04.804931 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 28 04:45:04.806577 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 28 04:45:04.808452 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 28 04:45:04.810463 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 28 04:45:04.812127 systemd[1]: Stopped target swap.target - Swaps. Oct 28 04:45:04.813507 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 28 04:45:04.813643 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 28 04:45:04.815777 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 28 04:45:04.817553 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 28 04:45:04.819333 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 28 04:45:04.821343 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 28 04:45:04.822686 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 28 04:45:04.822823 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 28 04:45:04.825359 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 28 04:45:04.825509 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 28 04:45:04.827508 systemd[1]: Stopped target paths.target - Path Units. Oct 28 04:45:04.829128 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 28 04:45:04.829242 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 28 04:45:04.831167 systemd[1]: Stopped target slices.target - Slice Units. Oct 28 04:45:04.832628 systemd[1]: Stopped target sockets.target - Socket Units. Oct 28 04:45:04.834353 systemd[1]: iscsid.socket: Deactivated successfully. Oct 28 04:45:04.834496 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 28 04:45:04.836457 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 28 04:45:04.836552 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 28 04:45:04.838099 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 28 04:45:04.838218 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 28 04:45:04.839871 systemd[1]: ignition-files.service: Deactivated successfully. Oct 28 04:45:04.839980 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 28 04:45:04.842316 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 28 04:45:04.843909 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 28 04:45:04.844044 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 28 04:45:04.861115 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 28 04:45:04.861968 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 28 04:45:04.862094 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 28 04:45:04.864065 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 28 04:45:04.864176 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 28 04:45:04.866794 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 28 04:45:04.866941 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 28 04:45:04.873878 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 28 04:45:04.875434 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 28 04:45:04.878439 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 28 04:45:04.879405 ignition[1095]: INFO : Ignition 2.22.0 Oct 28 04:45:04.879405 ignition[1095]: INFO : Stage: umount Oct 28 04:45:04.879405 ignition[1095]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 28 04:45:04.879405 ignition[1095]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Oct 28 04:45:04.883408 ignition[1095]: INFO : umount: umount passed Oct 28 04:45:04.883408 ignition[1095]: INFO : Ignition finished successfully Oct 28 04:45:04.882139 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 28 04:45:04.883437 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 28 04:45:04.885477 systemd[1]: Stopped target network.target - Network. Oct 28 04:45:04.886656 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 28 04:45:04.886719 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 28 04:45:04.888277 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 28 04:45:04.888326 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 28 04:45:04.890193 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 28 04:45:04.890246 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 28 04:45:04.891667 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 28 04:45:04.891711 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 28 04:45:04.893447 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 28 04:45:04.895189 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 28 04:45:04.898657 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 28 04:45:04.898777 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 28 04:45:04.906942 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 28 04:45:04.907046 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 28 04:45:04.908513 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 28 04:45:04.908562 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 28 04:45:04.912198 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 28 04:45:04.912304 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 28 04:45:04.916872 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 28 04:45:04.917894 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 28 04:45:04.917931 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 28 04:45:04.920369 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 28 04:45:04.921135 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 28 04:45:04.921196 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 28 04:45:04.923174 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 28 04:45:04.923216 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 28 04:45:04.924778 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 28 04:45:04.924820 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 28 04:45:04.926405 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 28 04:45:04.942838 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 28 04:45:04.943006 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 28 04:45:04.945314 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 28 04:45:04.945357 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 28 04:45:04.947184 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 28 04:45:04.947228 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 28 04:45:04.949099 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 28 04:45:04.949149 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 28 04:45:04.951822 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 28 04:45:04.951888 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 28 04:45:04.954370 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 28 04:45:04.954437 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 28 04:45:04.960047 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 28 04:45:04.961433 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 28 04:45:04.961498 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 28 04:45:04.963573 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 28 04:45:04.963620 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 28 04:45:04.965522 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 28 04:45:04.965569 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 04:45:04.968262 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 28 04:45:04.968348 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 28 04:45:04.970328 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 28 04:45:04.970444 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 28 04:45:04.971976 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 28 04:45:04.974361 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 28 04:45:04.994897 systemd[1]: Switching root. Oct 28 04:45:05.029961 systemd-journald[344]: Journal stopped Oct 28 04:45:05.797156 systemd-journald[344]: Received SIGTERM from PID 1 (systemd). Oct 28 04:45:05.797206 kernel: SELinux: policy capability network_peer_controls=1 Oct 28 04:45:05.797224 kernel: SELinux: policy capability open_perms=1 Oct 28 04:45:05.797234 kernel: SELinux: policy capability extended_socket_class=1 Oct 28 04:45:05.797244 kernel: SELinux: policy capability always_check_network=0 Oct 28 04:45:05.797256 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 28 04:45:05.797269 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 28 04:45:05.797279 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 28 04:45:05.797289 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 28 04:45:05.797308 kernel: SELinux: policy capability userspace_initial_context=0 Oct 28 04:45:05.797321 kernel: audit: type=1403 audit(1761626705.217:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 28 04:45:05.797333 systemd[1]: Successfully loaded SELinux policy in 60.189ms. Oct 28 04:45:05.797354 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 6.074ms. Oct 28 04:45:05.797368 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Oct 28 04:45:05.797414 systemd[1]: Detected virtualization kvm. Oct 28 04:45:05.797425 systemd[1]: Detected architecture arm64. Oct 28 04:45:05.797435 systemd[1]: Detected first boot. Oct 28 04:45:05.797449 systemd[1]: Initializing machine ID from SMBIOS/DMI UUID. Oct 28 04:45:05.797459 zram_generator::config[1142]: No configuration found. Oct 28 04:45:05.797472 kernel: NET: Registered PF_VSOCK protocol family Oct 28 04:45:05.797482 systemd[1]: Populated /etc with preset unit settings. Oct 28 04:45:05.797495 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 28 04:45:05.797506 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 28 04:45:05.797516 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 28 04:45:05.797528 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 28 04:45:05.797541 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 28 04:45:05.797551 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 28 04:45:05.797562 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 28 04:45:05.797573 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 28 04:45:05.797588 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 28 04:45:05.797600 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 28 04:45:05.797670 systemd[1]: Created slice user.slice - User and Session Slice. Oct 28 04:45:05.797681 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 28 04:45:05.797693 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 28 04:45:05.797705 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 28 04:45:05.797716 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 28 04:45:05.797727 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 28 04:45:05.797739 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 28 04:45:05.797750 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Oct 28 04:45:05.797760 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 28 04:45:05.797771 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 28 04:45:05.797782 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 28 04:45:05.797792 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 28 04:45:05.797804 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 28 04:45:05.797829 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 28 04:45:05.797843 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 28 04:45:05.797854 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 28 04:45:05.797865 systemd[1]: Reached target slices.target - Slice Units. Oct 28 04:45:05.797882 systemd[1]: Reached target swap.target - Swaps. Oct 28 04:45:05.797896 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 28 04:45:05.797910 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 28 04:45:05.797927 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 28 04:45:05.797938 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 28 04:45:05.797950 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 28 04:45:05.797960 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 28 04:45:05.797971 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 28 04:45:05.797982 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 28 04:45:05.797993 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 28 04:45:05.798005 systemd[1]: Mounting media.mount - External Media Directory... Oct 28 04:45:05.798016 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 28 04:45:05.798027 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 28 04:45:05.798037 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 28 04:45:05.798048 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 28 04:45:05.798059 systemd[1]: Reached target machines.target - Containers. Oct 28 04:45:05.798071 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 28 04:45:05.798083 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 04:45:05.798093 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 28 04:45:05.798104 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 28 04:45:05.798114 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 28 04:45:05.798125 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 28 04:45:05.798135 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 28 04:45:05.798148 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 28 04:45:05.798158 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 28 04:45:05.798169 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 28 04:45:05.798180 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 28 04:45:05.798190 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 28 04:45:05.798201 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 28 04:45:05.798212 systemd[1]: Stopped systemd-fsck-usr.service. Oct 28 04:45:05.798224 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 28 04:45:05.798237 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 28 04:45:05.798247 kernel: fuse: init (API version 7.41) Oct 28 04:45:05.798257 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 28 04:45:05.798267 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 28 04:45:05.798278 kernel: ACPI: bus type drm_connector registered Oct 28 04:45:05.798287 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 28 04:45:05.798299 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 28 04:45:05.798310 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 28 04:45:05.798320 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 28 04:45:05.798330 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 28 04:45:05.798343 systemd[1]: Mounted media.mount - External Media Directory. Oct 28 04:45:05.798354 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 28 04:45:05.798391 systemd-journald[1210]: Collecting audit messages is disabled. Oct 28 04:45:05.798419 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 28 04:45:05.798431 systemd-journald[1210]: Journal started Oct 28 04:45:05.798453 systemd-journald[1210]: Runtime Journal (/run/log/journal/35a5254e36f042c7806dd3f18b537ec1) is 6M, max 48.5M, 42.4M free. Oct 28 04:45:05.800499 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 28 04:45:05.590888 systemd[1]: Queued start job for default target multi-user.target. Oct 28 04:45:05.601532 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 28 04:45:05.602002 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 28 04:45:05.802945 systemd[1]: Started systemd-journald.service - Journal Service. Oct 28 04:45:05.805423 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 28 04:45:05.806758 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 28 04:45:05.808155 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 28 04:45:05.809427 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 28 04:45:05.810760 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 28 04:45:05.810939 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 28 04:45:05.812238 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 04:45:05.812427 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 28 04:45:05.813608 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 28 04:45:05.813774 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 28 04:45:05.815200 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 28 04:45:05.815363 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 28 04:45:05.816632 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 28 04:45:05.816807 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 28 04:45:05.818091 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 28 04:45:05.819703 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 28 04:45:05.821770 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 28 04:45:05.823570 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 28 04:45:05.836606 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 28 04:45:05.838260 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Oct 28 04:45:05.840559 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 28 04:45:05.842469 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 28 04:45:05.843489 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 28 04:45:05.843521 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 28 04:45:05.845239 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 28 04:45:05.846783 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 04:45:05.849213 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 28 04:45:05.851271 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 28 04:45:05.852427 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 28 04:45:05.853440 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 28 04:45:05.854672 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 28 04:45:05.856668 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 28 04:45:05.859546 systemd-journald[1210]: Time spent on flushing to /var/log/journal/35a5254e36f042c7806dd3f18b537ec1 is 17.737ms for 864 entries. Oct 28 04:45:05.859546 systemd-journald[1210]: System Journal (/var/log/journal/35a5254e36f042c7806dd3f18b537ec1) is 8M, max 163.5M, 155.5M free. Oct 28 04:45:05.893676 systemd-journald[1210]: Received client request to flush runtime journal. Oct 28 04:45:05.893737 kernel: loop1: detected capacity change from 0 to 119400 Oct 28 04:45:05.859629 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 28 04:45:05.862707 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 28 04:45:05.866179 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 28 04:45:05.867639 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 28 04:45:05.872182 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 28 04:45:05.877915 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 28 04:45:05.882272 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 28 04:45:05.886993 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 28 04:45:05.890520 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 28 04:45:05.901670 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 28 04:45:05.905549 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 28 04:45:05.909900 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 28 04:45:05.913405 kernel: loop2: detected capacity change from 0 to 100192 Oct 28 04:45:05.913551 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 28 04:45:05.920033 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 28 04:45:05.925681 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 28 04:45:05.938543 kernel: loop3: detected capacity change from 0 to 211168 Oct 28 04:45:05.941574 systemd-tmpfiles[1275]: ACLs are not supported, ignoring. Oct 28 04:45:05.941588 systemd-tmpfiles[1275]: ACLs are not supported, ignoring. Oct 28 04:45:05.946466 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 28 04:45:05.958499 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 28 04:45:05.971511 kernel: loop4: detected capacity change from 0 to 119400 Oct 28 04:45:05.977415 kernel: loop5: detected capacity change from 0 to 100192 Oct 28 04:45:05.983413 kernel: loop6: detected capacity change from 0 to 211168 Oct 28 04:45:05.987450 (sd-merge)[1287]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'kubernetes.raw'. Oct 28 04:45:05.990469 (sd-merge)[1287]: Merged extensions into '/usr'. Oct 28 04:45:05.995340 systemd[1]: Reload requested from client PID 1259 ('systemd-sysext') (unit systemd-sysext.service)... Oct 28 04:45:05.995357 systemd[1]: Reloading... Oct 28 04:45:06.012897 systemd-resolved[1274]: Positive Trust Anchors: Oct 28 04:45:06.012919 systemd-resolved[1274]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 28 04:45:06.012923 systemd-resolved[1274]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Oct 28 04:45:06.012955 systemd-resolved[1274]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 28 04:45:06.019369 systemd-resolved[1274]: Defaulting to hostname 'linux'. Oct 28 04:45:06.052427 zram_generator::config[1323]: No configuration found. Oct 28 04:45:06.188753 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 28 04:45:06.189080 systemd[1]: Reloading finished in 193 ms. Oct 28 04:45:06.203916 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 28 04:45:06.206402 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 28 04:45:06.209346 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 28 04:45:06.217626 systemd[1]: Starting ensure-sysext.service... Oct 28 04:45:06.219374 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 28 04:45:06.228690 systemd[1]: Reload requested from client PID 1350 ('systemctl') (unit ensure-sysext.service)... Oct 28 04:45:06.228707 systemd[1]: Reloading... Oct 28 04:45:06.234767 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 28 04:45:06.234799 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 28 04:45:06.235044 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 28 04:45:06.235232 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 28 04:45:06.235859 systemd-tmpfiles[1351]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 28 04:45:06.236043 systemd-tmpfiles[1351]: ACLs are not supported, ignoring. Oct 28 04:45:06.236086 systemd-tmpfiles[1351]: ACLs are not supported, ignoring. Oct 28 04:45:06.239771 systemd-tmpfiles[1351]: Detected autofs mount point /boot during canonicalization of boot. Oct 28 04:45:06.239782 systemd-tmpfiles[1351]: Skipping /boot Oct 28 04:45:06.245720 systemd-tmpfiles[1351]: Detected autofs mount point /boot during canonicalization of boot. Oct 28 04:45:06.245736 systemd-tmpfiles[1351]: Skipping /boot Oct 28 04:45:06.279414 zram_generator::config[1379]: No configuration found. Oct 28 04:45:06.418267 systemd[1]: Reloading finished in 189 ms. Oct 28 04:45:06.429955 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 28 04:45:06.447489 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 28 04:45:06.454845 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 28 04:45:06.456885 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 28 04:45:06.467776 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 28 04:45:06.470350 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 28 04:45:06.472974 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 28 04:45:06.477437 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 28 04:45:06.482459 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 04:45:06.485744 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 28 04:45:06.488173 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 28 04:45:06.491752 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 28 04:45:06.492740 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 04:45:06.492866 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 28 04:45:06.495853 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 28 04:45:06.497075 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 28 04:45:06.500490 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 28 04:45:06.500663 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 28 04:45:06.502349 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 28 04:45:06.503102 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 28 04:45:06.509486 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 28 04:45:06.517194 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 28 04:45:06.523459 augenrules[1449]: No rules Oct 28 04:45:06.524103 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 28 04:45:06.525721 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 28 04:45:06.528799 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 28 04:45:06.534787 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 28 04:45:06.538469 systemd-udevd[1422]: Using default interface naming scheme 'v257'. Oct 28 04:45:06.539633 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 28 04:45:06.540776 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 28 04:45:06.540905 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Oct 28 04:45:06.542527 systemd[1]: audit-rules.service: Deactivated successfully. Oct 28 04:45:06.542724 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 28 04:45:06.544210 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 28 04:45:06.544443 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 28 04:45:06.546123 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 28 04:45:06.546322 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 28 04:45:06.547986 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 28 04:45:06.548155 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 28 04:45:06.553672 systemd[1]: Finished ensure-sysext.service. Oct 28 04:45:06.555019 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 28 04:45:06.555221 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 28 04:45:06.560440 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 28 04:45:06.560554 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 28 04:45:06.562632 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 28 04:45:06.564294 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 28 04:45:06.566209 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 28 04:45:06.567318 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 28 04:45:06.575767 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 28 04:45:06.641918 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 28 04:45:06.645501 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Oct 28 04:45:06.645966 systemd[1]: Reached target time-set.target - System Time Set. Oct 28 04:45:06.657182 systemd-networkd[1478]: lo: Link UP Oct 28 04:45:06.657191 systemd-networkd[1478]: lo: Gained carrier Oct 28 04:45:06.661658 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 28 04:45:06.662889 systemd[1]: Reached target network.target - Network. Oct 28 04:45:06.665624 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 28 04:45:06.668208 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 28 04:45:06.693898 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 28 04:45:06.706085 systemd-networkd[1478]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 04:45:06.706097 systemd-networkd[1478]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 28 04:45:06.707011 systemd-networkd[1478]: eth0: Link UP Oct 28 04:45:06.707132 systemd-networkd[1478]: eth0: Gained carrier Oct 28 04:45:06.707149 systemd-networkd[1478]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Oct 28 04:45:06.707798 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 28 04:45:06.711589 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 28 04:45:06.721442 systemd-networkd[1478]: eth0: DHCPv4 address 10.0.0.85/16, gateway 10.0.0.1 acquired from 10.0.0.1 Oct 28 04:45:06.722219 systemd-timesyncd[1465]: Network configuration changed, trying to establish connection. Oct 28 04:45:06.723218 systemd-timesyncd[1465]: Contacted time server 10.0.0.1:123 (10.0.0.1). Oct 28 04:45:06.723274 systemd-timesyncd[1465]: Initial clock synchronization to Tue 2025-10-28 04:45:06.708731 UTC. Oct 28 04:45:06.739757 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 28 04:45:06.790231 ldconfig[1419]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 28 04:45:06.796218 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 28 04:45:06.800668 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 28 04:45:06.814467 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 28 04:45:06.816663 systemd[1]: Reached target sysinit.target - System Initialization. Oct 28 04:45:06.817918 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 28 04:45:06.819084 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 28 04:45:06.820601 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 28 04:45:06.821902 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 28 04:45:06.823237 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 28 04:45:06.824442 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 28 04:45:06.824482 systemd[1]: Reached target paths.target - Path Units. Oct 28 04:45:06.825265 systemd[1]: Reached target timers.target - Timer Units. Oct 28 04:45:06.826972 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 28 04:45:06.829306 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 28 04:45:06.832056 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 28 04:45:06.833418 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 28 04:45:06.834561 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 28 04:45:06.842273 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 28 04:45:06.843590 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 28 04:45:06.845189 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 28 04:45:06.847940 systemd[1]: Reached target sockets.target - Socket Units. Oct 28 04:45:06.848908 systemd[1]: Reached target basic.target - Basic System. Oct 28 04:45:06.849803 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 28 04:45:06.849911 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 28 04:45:06.851116 systemd[1]: Starting containerd.service - containerd container runtime... Oct 28 04:45:06.853198 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 28 04:45:06.855076 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 28 04:45:06.857038 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 28 04:45:06.858953 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 28 04:45:06.859989 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Oct 28 04:45:06.861100 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 28 04:45:06.863285 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 28 04:45:06.867888 jq[1528]: false Oct 28 04:45:06.867643 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 28 04:45:06.870929 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 28 04:45:06.872539 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 28 04:45:06.873027 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 28 04:45:06.874941 extend-filesystems[1529]: Found /dev/vda6 Oct 28 04:45:06.877605 extend-filesystems[1529]: Found /dev/vda9 Oct 28 04:45:06.880248 extend-filesystems[1529]: Checking size of /dev/vda9 Oct 28 04:45:06.878841 systemd[1]: Starting update-engine.service - Update Engine... Oct 28 04:45:06.881992 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 28 04:45:06.885918 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 28 04:45:06.887713 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 28 04:45:06.887908 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 28 04:45:06.888287 systemd[1]: motdgen.service: Deactivated successfully. Oct 28 04:45:06.888513 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 28 04:45:06.889969 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 28 04:45:06.890195 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 28 04:45:06.898087 extend-filesystems[1529]: Resized partition /dev/vda9 Oct 28 04:45:06.900407 jq[1546]: true Oct 28 04:45:06.903612 extend-filesystems[1566]: resize2fs 1.47.3 (8-Jul-2025) Oct 28 04:45:06.902142 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 28 04:45:06.919661 update_engine[1539]: I20251028 04:45:06.918349 1539 main.cc:92] Flatcar Update Engine starting Oct 28 04:45:06.921412 kernel: EXT4-fs (vda9): resizing filesystem from 456704 to 1784827 blocks Oct 28 04:45:06.945738 jq[1567]: true Oct 28 04:45:06.947754 dbus-daemon[1526]: [system] SELinux support is enabled Oct 28 04:45:06.949553 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 28 04:45:06.953927 update_engine[1539]: I20251028 04:45:06.953751 1539 update_check_scheduler.cc:74] Next update check in 3m25s Oct 28 04:45:06.955692 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 28 04:45:06.955730 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 28 04:45:06.958598 kernel: EXT4-fs (vda9): resized filesystem to 1784827 Oct 28 04:45:06.958343 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 28 04:45:06.958373 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 28 04:45:06.960597 systemd[1]: Started update-engine.service - Update Engine. Oct 28 04:45:06.966579 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 28 04:45:06.969549 extend-filesystems[1566]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 28 04:45:06.969549 extend-filesystems[1566]: old_desc_blocks = 1, new_desc_blocks = 1 Oct 28 04:45:06.969549 extend-filesystems[1566]: The filesystem on /dev/vda9 is now 1784827 (4k) blocks long. Oct 28 04:45:06.978546 extend-filesystems[1529]: Resized filesystem in /dev/vda9 Oct 28 04:45:06.971612 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 28 04:45:06.971815 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 28 04:45:06.985162 systemd-logind[1537]: Watching system buttons on /dev/input/event0 (Power Button) Oct 28 04:45:06.986036 systemd-logind[1537]: New seat seat0. Oct 28 04:45:06.995050 systemd[1]: Started systemd-logind.service - User Login Management. Oct 28 04:45:07.000489 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 28 04:45:07.006461 bash[1595]: Updated "/home/core/.ssh/authorized_keys" Oct 28 04:45:07.010670 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 28 04:45:07.012516 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 28 04:45:07.020171 locksmithd[1577]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 28 04:45:07.049466 sshd_keygen[1558]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 28 04:45:07.069809 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 28 04:45:07.072885 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 28 04:45:07.091324 systemd[1]: issuegen.service: Deactivated successfully. Oct 28 04:45:07.091628 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 28 04:45:07.092788 containerd[1568]: time="2025-10-28T04:45:07Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Oct 28 04:45:07.093501 containerd[1568]: time="2025-10-28T04:45:07.093467900Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 28 04:45:07.100982 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 28 04:45:07.103307 containerd[1568]: time="2025-10-28T04:45:07.103254955Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.756µs" Oct 28 04:45:07.103307 containerd[1568]: time="2025-10-28T04:45:07.103293298Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 28 04:45:07.103456 containerd[1568]: time="2025-10-28T04:45:07.103313009Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 28 04:45:07.103532 containerd[1568]: time="2025-10-28T04:45:07.103496807Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 28 04:45:07.103532 containerd[1568]: time="2025-10-28T04:45:07.103522036Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 28 04:45:07.103587 containerd[1568]: time="2025-10-28T04:45:07.103547384Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 28 04:45:07.103618 containerd[1568]: time="2025-10-28T04:45:07.103603039Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 28 04:45:07.103639 containerd[1568]: time="2025-10-28T04:45:07.103617113Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 28 04:45:07.103863 containerd[1568]: time="2025-10-28T04:45:07.103829898Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 28 04:45:07.103863 containerd[1568]: time="2025-10-28T04:45:07.103854247Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 28 04:45:07.103904 containerd[1568]: time="2025-10-28T04:45:07.103867161Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 28 04:45:07.103904 containerd[1568]: time="2025-10-28T04:45:07.103875477Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 28 04:45:07.103969 containerd[1568]: time="2025-10-28T04:45:07.103953363Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 28 04:45:07.104182 containerd[1568]: time="2025-10-28T04:45:07.104152353Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 28 04:45:07.104213 containerd[1568]: time="2025-10-28T04:45:07.104189257Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 28 04:45:07.104213 containerd[1568]: time="2025-10-28T04:45:07.104201531Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 28 04:45:07.104245 containerd[1568]: time="2025-10-28T04:45:07.104231838Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 28 04:45:07.104468 containerd[1568]: time="2025-10-28T04:45:07.104449140Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 28 04:45:07.104533 containerd[1568]: time="2025-10-28T04:45:07.104517230Z" level=info msg="metadata content store policy set" policy=shared Oct 28 04:45:07.118965 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 28 04:45:07.121772 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 28 04:45:07.123999 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Oct 28 04:45:07.125244 systemd[1]: Reached target getty.target - Login Prompts. Oct 28 04:45:07.223668 containerd[1568]: time="2025-10-28T04:45:07.223527756Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 28 04:45:07.223668 containerd[1568]: time="2025-10-28T04:45:07.223645584Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223673171Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223688005Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223708915Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223746858Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223761852Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223774486Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223787560Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223798035Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223807151Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 28 04:45:07.223856 containerd[1568]: time="2025-10-28T04:45:07.223819186Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 28 04:45:07.224010 containerd[1568]: time="2025-10-28T04:45:07.223968799Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 28 04:45:07.224010 containerd[1568]: time="2025-10-28T04:45:07.223989949Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 28 04:45:07.224010 containerd[1568]: time="2025-10-28T04:45:07.224004863Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 28 04:45:07.224058 containerd[1568]: time="2025-10-28T04:45:07.224018896Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 28 04:45:07.224058 containerd[1568]: time="2025-10-28T04:45:07.224032490Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 28 04:45:07.224058 containerd[1568]: time="2025-10-28T04:45:07.224048883Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 28 04:45:07.224106 containerd[1568]: time="2025-10-28T04:45:07.224059998Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 28 04:45:07.224106 containerd[1568]: time="2025-10-28T04:45:07.224070513Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 28 04:45:07.224106 containerd[1568]: time="2025-10-28T04:45:07.224082308Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 28 04:45:07.224106 containerd[1568]: time="2025-10-28T04:45:07.224093623Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 28 04:45:07.224106 containerd[1568]: time="2025-10-28T04:45:07.224103539Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 28 04:45:07.224391 containerd[1568]: time="2025-10-28T04:45:07.224348989Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 28 04:45:07.224391 containerd[1568]: time="2025-10-28T04:45:07.224374817Z" level=info msg="Start snapshots syncer" Oct 28 04:45:07.224464 containerd[1568]: time="2025-10-28T04:45:07.224430192Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 28 04:45:07.224674 containerd[1568]: time="2025-10-28T04:45:07.224638819Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Oct 28 04:45:07.224791 containerd[1568]: time="2025-10-28T04:45:07.224696113Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 28 04:45:07.224791 containerd[1568]: time="2025-10-28T04:45:07.224773758Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 28 04:45:07.224921 containerd[1568]: time="2025-10-28T04:45:07.224882310Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 28 04:45:07.224921 containerd[1568]: time="2025-10-28T04:45:07.224909977Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 28 04:45:07.224921 containerd[1568]: time="2025-10-28T04:45:07.224921652Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 28 04:45:07.224981 containerd[1568]: time="2025-10-28T04:45:07.224933967Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 28 04:45:07.224981 containerd[1568]: time="2025-10-28T04:45:07.224945721Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 28 04:45:07.224981 containerd[1568]: time="2025-10-28T04:45:07.224955117Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 28 04:45:07.224981 containerd[1568]: time="2025-10-28T04:45:07.224965113Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 28 04:45:07.225043 containerd[1568]: time="2025-10-28T04:45:07.224987063Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 28 04:45:07.225043 containerd[1568]: time="2025-10-28T04:45:07.224999737Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 28 04:45:07.225043 containerd[1568]: time="2025-10-28T04:45:07.225009533Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 28 04:45:07.225043 containerd[1568]: time="2025-10-28T04:45:07.225039679Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 28 04:45:07.225108 containerd[1568]: time="2025-10-28T04:45:07.225054872Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 28 04:45:07.225108 containerd[1568]: time="2025-10-28T04:45:07.225065348Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 28 04:45:07.225108 containerd[1568]: time="2025-10-28T04:45:07.225074703Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 28 04:45:07.225108 containerd[1568]: time="2025-10-28T04:45:07.225082700Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 28 04:45:07.225108 containerd[1568]: time="2025-10-28T04:45:07.225096614Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 28 04:45:07.225108 containerd[1568]: time="2025-10-28T04:45:07.225107329Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 28 04:45:07.225205 containerd[1568]: time="2025-10-28T04:45:07.225182535Z" level=info msg="runtime interface created" Oct 28 04:45:07.225205 containerd[1568]: time="2025-10-28T04:45:07.225188612Z" level=info msg="created NRI interface" Oct 28 04:45:07.225205 containerd[1568]: time="2025-10-28T04:45:07.225196489Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 28 04:45:07.225254 containerd[1568]: time="2025-10-28T04:45:07.225206644Z" level=info msg="Connect containerd service" Oct 28 04:45:07.225254 containerd[1568]: time="2025-10-28T04:45:07.225236191Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 28 04:45:07.225973 containerd[1568]: time="2025-10-28T04:45:07.225926802Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 28 04:45:07.293353 containerd[1568]: time="2025-10-28T04:45:07.293275152Z" level=info msg="Start subscribing containerd event" Oct 28 04:45:07.293353 containerd[1568]: time="2025-10-28T04:45:07.293365191Z" level=info msg="Start recovering state" Oct 28 04:45:07.293589 containerd[1568]: time="2025-10-28T04:45:07.293480699Z" level=info msg="Start event monitor" Oct 28 04:45:07.293589 containerd[1568]: time="2025-10-28T04:45:07.293500011Z" level=info msg="Start cni network conf syncer for default" Oct 28 04:45:07.293589 containerd[1568]: time="2025-10-28T04:45:07.293510166Z" level=info msg="Start streaming server" Oct 28 04:45:07.293589 containerd[1568]: time="2025-10-28T04:45:07.293550308Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 28 04:45:07.293666 containerd[1568]: time="2025-10-28T04:45:07.293596448Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 28 04:45:07.293666 containerd[1568]: time="2025-10-28T04:45:07.293608042Z" level=info msg="runtime interface starting up..." Oct 28 04:45:07.293666 containerd[1568]: time="2025-10-28T04:45:07.293614200Z" level=info msg="starting plugins..." Oct 28 04:45:07.293666 containerd[1568]: time="2025-10-28T04:45:07.293628313Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 28 04:45:07.294404 containerd[1568]: time="2025-10-28T04:45:07.293596607Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 28 04:45:07.294404 containerd[1568]: time="2025-10-28T04:45:07.293876682Z" level=info msg="containerd successfully booted in 0.201577s" Oct 28 04:45:07.294015 systemd[1]: Started containerd.service - containerd container runtime. Oct 28 04:45:08.668568 systemd-networkd[1478]: eth0: Gained IPv6LL Oct 28 04:45:08.670972 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 28 04:45:08.673583 systemd[1]: Reached target network-online.target - Network is Online. Oct 28 04:45:08.676543 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 28 04:45:08.678887 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 28 04:45:08.698785 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 28 04:45:08.725656 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 28 04:45:08.727681 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 28 04:45:08.727937 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 28 04:45:08.730077 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 28 04:45:09.266279 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 28 04:45:09.267785 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 28 04:45:09.269643 systemd[1]: Startup finished in 1.149s (kernel) + 5.097s (initrd) + 4.112s (userspace) = 10.360s. Oct 28 04:45:09.270300 (kubelet)[1663]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Oct 28 04:45:09.637488 kubelet[1663]: E1028 04:45:09.637434 1663 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Oct 28 04:45:09.639803 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Oct 28 04:45:09.639938 systemd[1]: kubelet.service: Failed with result 'exit-code'. Oct 28 04:45:09.640248 systemd[1]: kubelet.service: Consumed 749ms CPU time, 258.1M memory peak. Oct 28 04:45:11.326412 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 28 04:45:11.327604 systemd[1]: Started sshd@0-10.0.0.85:22-10.0.0.1:53476.service - OpenSSH per-connection server daemon (10.0.0.1:53476). Oct 28 04:45:11.408473 sshd[1677]: Accepted publickey for core from 10.0.0.1 port 53476 ssh2: RSA SHA256:G3TnoROQ9Cj4veOf20FQ3O9Hy4+vovM73CW4ZU0CCng Oct 28 04:45:11.410052 sshd-session[1677]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 04:45:11.415911 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 28 04:45:11.416819 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 28 04:45:11.421690 systemd-logind[1537]: New session 1 of user core. Oct 28 04:45:11.441424 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 28 04:45:11.443791 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 28 04:45:11.458569 (systemd)[1682]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 28 04:45:11.460665 systemd-logind[1537]: New session c1 of user core. Oct 28 04:45:11.557653 systemd[1682]: Queued start job for default target default.target. Oct 28 04:45:11.566279 systemd[1682]: Created slice app.slice - User Application Slice. Oct 28 04:45:11.566306 systemd[1682]: Reached target paths.target - Paths. Oct 28 04:45:11.566348 systemd[1682]: Reached target timers.target - Timers. Oct 28 04:45:11.567572 systemd[1682]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 28 04:45:11.576687 systemd[1682]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 28 04:45:11.576751 systemd[1682]: Reached target sockets.target - Sockets. Oct 28 04:45:11.576786 systemd[1682]: Reached target basic.target - Basic System. Oct 28 04:45:11.576812 systemd[1682]: Reached target default.target - Main User Target. Oct 28 04:45:11.576837 systemd[1682]: Startup finished in 110ms. Oct 28 04:45:11.577053 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 28 04:45:11.578321 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 28 04:45:11.588233 systemd[1]: Started sshd@1-10.0.0.85:22-10.0.0.1:53478.service - OpenSSH per-connection server daemon (10.0.0.1:53478). Oct 28 04:45:11.642668 sshd[1693]: Accepted publickey for core from 10.0.0.1 port 53478 ssh2: RSA SHA256:G3TnoROQ9Cj4veOf20FQ3O9Hy4+vovM73CW4ZU0CCng Oct 28 04:45:11.643922 sshd-session[1693]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 04:45:11.648323 systemd-logind[1537]: New session 2 of user core. Oct 28 04:45:11.655594 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 28 04:45:11.666298 sshd[1696]: Connection closed by 10.0.0.1 port 53478 Oct 28 04:45:11.666597 sshd-session[1693]: pam_unix(sshd:session): session closed for user core Oct 28 04:45:11.678286 systemd[1]: sshd@1-10.0.0.85:22-10.0.0.1:53478.service: Deactivated successfully. Oct 28 04:45:11.680831 systemd[1]: session-2.scope: Deactivated successfully. Oct 28 04:45:11.682549 systemd-logind[1537]: Session 2 logged out. Waiting for processes to exit. Oct 28 04:45:11.685150 systemd[1]: Started sshd@2-10.0.0.85:22-10.0.0.1:53482.service - OpenSSH per-connection server daemon (10.0.0.1:53482). Oct 28 04:45:11.686199 systemd-logind[1537]: Removed session 2. Oct 28 04:45:11.748369 sshd[1702]: Accepted publickey for core from 10.0.0.1 port 53482 ssh2: RSA SHA256:G3TnoROQ9Cj4veOf20FQ3O9Hy4+vovM73CW4ZU0CCng Oct 28 04:45:11.749512 sshd-session[1702]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 04:45:11.753489 systemd-logind[1537]: New session 3 of user core. Oct 28 04:45:11.771627 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 28 04:45:11.778372 sshd[1705]: Connection closed by 10.0.0.1 port 53482 Oct 28 04:45:11.778797 sshd-session[1702]: pam_unix(sshd:session): session closed for user core Oct 28 04:45:11.789305 systemd[1]: sshd@2-10.0.0.85:22-10.0.0.1:53482.service: Deactivated successfully. Oct 28 04:45:11.791906 systemd[1]: session-3.scope: Deactivated successfully. Oct 28 04:45:11.792542 systemd-logind[1537]: Session 3 logged out. Waiting for processes to exit. Oct 28 04:45:11.794808 systemd[1]: Started sshd@3-10.0.0.85:22-10.0.0.1:53492.service - OpenSSH per-connection server daemon (10.0.0.1:53492). Oct 28 04:45:11.795261 systemd-logind[1537]: Removed session 3. Oct 28 04:45:11.849799 sshd[1711]: Accepted publickey for core from 10.0.0.1 port 53492 ssh2: RSA SHA256:G3TnoROQ9Cj4veOf20FQ3O9Hy4+vovM73CW4ZU0CCng Oct 28 04:45:11.851099 sshd-session[1711]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 04:45:11.854807 systemd-logind[1537]: New session 4 of user core. Oct 28 04:45:11.871552 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 28 04:45:11.882066 sshd[1714]: Connection closed by 10.0.0.1 port 53492 Oct 28 04:45:11.882642 sshd-session[1711]: pam_unix(sshd:session): session closed for user core Oct 28 04:45:11.894360 systemd[1]: sshd@3-10.0.0.85:22-10.0.0.1:53492.service: Deactivated successfully. Oct 28 04:45:11.896027 systemd[1]: session-4.scope: Deactivated successfully. Oct 28 04:45:11.896674 systemd-logind[1537]: Session 4 logged out. Waiting for processes to exit. Oct 28 04:45:11.898901 systemd[1]: Started sshd@4-10.0.0.85:22-10.0.0.1:53496.service - OpenSSH per-connection server daemon (10.0.0.1:53496). Oct 28 04:45:11.899426 systemd-logind[1537]: Removed session 4. Oct 28 04:45:11.960316 sshd[1720]: Accepted publickey for core from 10.0.0.1 port 53496 ssh2: RSA SHA256:G3TnoROQ9Cj4veOf20FQ3O9Hy4+vovM73CW4ZU0CCng Oct 28 04:45:11.961545 sshd-session[1720]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 28 04:45:11.965445 systemd-logind[1537]: New session 5 of user core. Oct 28 04:45:11.975551 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 28 04:45:11.991336 sudo[1724]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Oct 28 04:45:11.991644 sudo[1724]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 28 04:45:12.002493 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Oct 28 04:45:12.027477 systemd[1]: coreos-metadata.service: Deactivated successfully. Oct 28 04:45:12.028486 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Oct 28 04:45:12.447819 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 28 04:45:12.448024 systemd[1]: kubelet.service: Consumed 749ms CPU time, 258.1M memory peak. Oct 28 04:45:12.449996 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 28 04:45:12.480319 systemd[1]: Reload requested from client PID 1767 ('systemctl') (unit session-5.scope)... Oct 28 04:45:12.480341 systemd[1]: Reloading... Oct 28 04:45:12.554450 zram_generator::config[1810]: No configuration found. Oct 28 04:45:12.732993 systemd[1]: Reloading finished in 252 ms. Oct 28 04:45:12.785862 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Oct 28 04:45:12.785946 systemd[1]: kubelet.service: Failed with result 'signal'. Oct 28 04:45:12.786203 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Oct 28 04:45:12.786250 systemd[1]: kubelet.service: Consumed 98ms CPU time, 95.1M memory peak. Oct 28 04:45:12.787715 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Oct 28 04:45:12.908325 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Oct 28 04:45:12.912696 (kubelet)[1855]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Oct 28 04:45:12.947225 kubelet[1855]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Oct 28 04:45:12.947225 kubelet[1855]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Oct 28 04:45:12.947225 kubelet[1855]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Oct 28 04:45:12.947873 kubelet[1855]: I1028 04:45:12.947246 1855 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Oct 28 04:45:14.625604 kubelet[1855]: I1028 04:45:14.625544 1855 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Oct 28 04:45:14.625604 kubelet[1855]: I1028 04:45:14.625581 1855 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Oct 28 04:45:14.625959 kubelet[1855]: I1028 04:45:14.625804 1855 server.go:956] "Client rotation is on, will bootstrap in background" Oct 28 04:45:14.647729 kubelet[1855]: I1028 04:45:14.647672 1855 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Oct 28 04:45:14.661743 kubelet[1855]: I1028 04:45:14.661470 1855 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Oct 28 04:45:14.664378 kubelet[1855]: I1028 04:45:14.664340 1855 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Oct 28 04:45:14.664767 kubelet[1855]: I1028 04:45:14.664736 1855 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Oct 28 04:45:14.664931 kubelet[1855]: I1028 04:45:14.664769 1855 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.85","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Oct 28 04:45:14.665056 kubelet[1855]: I1028 04:45:14.664995 1855 topology_manager.go:138] "Creating topology manager with none policy" Oct 28 04:45:14.665056 kubelet[1855]: I1028 04:45:14.665005 1855 container_manager_linux.go:303] "Creating device plugin manager" Oct 28 04:45:14.665786 kubelet[1855]: I1028 04:45:14.665765 1855 state_mem.go:36] "Initialized new in-memory state store" Oct 28 04:45:14.668399 kubelet[1855]: I1028 04:45:14.668335 1855 kubelet.go:480] "Attempting to sync node with API server" Oct 28 04:45:14.668399 kubelet[1855]: I1028 04:45:14.668367 1855 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Oct 28 04:45:14.668512 kubelet[1855]: I1028 04:45:14.668416 1855 kubelet.go:386] "Adding apiserver pod source" Oct 28 04:45:14.669544 kubelet[1855]: I1028 04:45:14.669488 1855 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Oct 28 04:45:14.669796 kubelet[1855]: E1028 04:45:14.669628 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:14.669796 kubelet[1855]: E1028 04:45:14.669690 1855 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:14.671005 kubelet[1855]: I1028 04:45:14.670777 1855 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Oct 28 04:45:14.671527 kubelet[1855]: I1028 04:45:14.671504 1855 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Oct 28 04:45:14.671635 kubelet[1855]: W1028 04:45:14.671629 1855 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Oct 28 04:45:14.674000 kubelet[1855]: I1028 04:45:14.673966 1855 watchdog_linux.go:99] "Systemd watchdog is not enabled" Oct 28 04:45:14.674445 kubelet[1855]: I1028 04:45:14.674021 1855 server.go:1289] "Started kubelet" Oct 28 04:45:14.675178 kubelet[1855]: I1028 04:45:14.675152 1855 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Oct 28 04:45:14.676810 kubelet[1855]: I1028 04:45:14.676784 1855 volume_manager.go:297] "Starting Kubelet Volume Manager" Oct 28 04:45:14.677086 kubelet[1855]: I1028 04:45:14.677073 1855 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Oct 28 04:45:14.677243 kubelet[1855]: I1028 04:45:14.677232 1855 reconciler.go:26] "Reconciler: start to sync state" Oct 28 04:45:14.680644 kubelet[1855]: I1028 04:45:14.677730 1855 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Oct 28 04:45:14.680644 kubelet[1855]: I1028 04:45:14.678926 1855 server.go:317] "Adding debug handlers to kubelet server" Oct 28 04:45:14.681910 kubelet[1855]: I1028 04:45:14.681287 1855 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Oct 28 04:45:14.681910 kubelet[1855]: I1028 04:45:14.681602 1855 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Oct 28 04:45:14.682281 kubelet[1855]: E1028 04:45:14.682217 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:14.682654 kubelet[1855]: I1028 04:45:14.682625 1855 factory.go:223] Registration of the systemd container factory successfully Oct 28 04:45:14.682764 kubelet[1855]: I1028 04:45:14.682743 1855 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Oct 28 04:45:14.683331 kubelet[1855]: I1028 04:45:14.683305 1855 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Oct 28 04:45:14.684496 kubelet[1855]: E1028 04:45:14.684464 1855 reflector.go:200] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Oct 28 04:45:14.684651 kubelet[1855]: E1028 04:45:14.684627 1855 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.0.0.85\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Oct 28 04:45:14.689313 kubelet[1855]: I1028 04:45:14.688955 1855 factory.go:223] Registration of the containerd container factory successfully Oct 28 04:45:14.690557 kubelet[1855]: E1028 04:45:14.690524 1855 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Oct 28 04:45:14.701167 kubelet[1855]: E1028 04:45:14.701127 1855 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.85\" not found" node="10.0.0.85" Oct 28 04:45:14.708112 kubelet[1855]: I1028 04:45:14.708086 1855 cpu_manager.go:221] "Starting CPU manager" policy="none" Oct 28 04:45:14.708295 kubelet[1855]: I1028 04:45:14.708282 1855 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Oct 28 04:45:14.708355 kubelet[1855]: I1028 04:45:14.708345 1855 state_mem.go:36] "Initialized new in-memory state store" Oct 28 04:45:14.783295 kubelet[1855]: E1028 04:45:14.783261 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:14.812511 kubelet[1855]: I1028 04:45:14.812488 1855 policy_none.go:49] "None policy: Start" Oct 28 04:45:14.812608 kubelet[1855]: I1028 04:45:14.812597 1855 memory_manager.go:186] "Starting memorymanager" policy="None" Oct 28 04:45:14.812694 kubelet[1855]: I1028 04:45:14.812684 1855 state_mem.go:35] "Initializing new in-memory state store" Oct 28 04:45:14.819055 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Oct 28 04:45:14.832873 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Oct 28 04:45:14.836704 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Oct 28 04:45:14.845968 kubelet[1855]: I1028 04:45:14.845911 1855 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Oct 28 04:45:14.847191 kubelet[1855]: I1028 04:45:14.847161 1855 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Oct 28 04:45:14.847191 kubelet[1855]: I1028 04:45:14.847191 1855 status_manager.go:230] "Starting to sync pod status with apiserver" Oct 28 04:45:14.847191 kubelet[1855]: I1028 04:45:14.847213 1855 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Oct 28 04:45:14.847191 kubelet[1855]: I1028 04:45:14.847220 1855 kubelet.go:2436] "Starting kubelet main sync loop" Oct 28 04:45:14.847191 kubelet[1855]: E1028 04:45:14.847264 1855 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Oct 28 04:45:14.850663 kubelet[1855]: E1028 04:45:14.850634 1855 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Oct 28 04:45:14.850858 kubelet[1855]: I1028 04:45:14.850841 1855 eviction_manager.go:189] "Eviction manager: starting control loop" Oct 28 04:45:14.850892 kubelet[1855]: I1028 04:45:14.850858 1855 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Oct 28 04:45:14.851248 kubelet[1855]: I1028 04:45:14.851155 1855 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Oct 28 04:45:14.852577 kubelet[1855]: E1028 04:45:14.852547 1855 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Oct 28 04:45:14.852838 kubelet[1855]: E1028 04:45:14.852813 1855 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.85\" not found" Oct 28 04:45:14.952753 kubelet[1855]: I1028 04:45:14.952726 1855 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.85" Oct 28 04:45:14.957271 kubelet[1855]: I1028 04:45:14.957183 1855 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.85" Oct 28 04:45:14.957271 kubelet[1855]: E1028 04:45:14.957222 1855 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.85\": node \"10.0.0.85\" not found" Oct 28 04:45:14.967050 kubelet[1855]: E1028 04:45:14.967018 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.067803 kubelet[1855]: E1028 04:45:15.067749 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.168601 kubelet[1855]: E1028 04:45:15.168544 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.269649 kubelet[1855]: E1028 04:45:15.269545 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.370343 kubelet[1855]: E1028 04:45:15.370298 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.434945 sudo[1724]: pam_unix(sudo:session): session closed for user root Oct 28 04:45:15.436614 sshd[1723]: Connection closed by 10.0.0.1 port 53496 Oct 28 04:45:15.436958 sshd-session[1720]: pam_unix(sshd:session): session closed for user core Oct 28 04:45:15.440524 systemd-logind[1537]: Session 5 logged out. Waiting for processes to exit. Oct 28 04:45:15.440640 systemd[1]: sshd@4-10.0.0.85:22-10.0.0.1:53496.service: Deactivated successfully. Oct 28 04:45:15.442522 systemd[1]: session-5.scope: Deactivated successfully. Oct 28 04:45:15.442766 systemd[1]: session-5.scope: Consumed 422ms CPU time, 76.5M memory peak. Oct 28 04:45:15.444903 systemd-logind[1537]: Removed session 5. Oct 28 04:45:15.470950 kubelet[1855]: E1028 04:45:15.470897 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.571731 kubelet[1855]: E1028 04:45:15.571584 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.627313 kubelet[1855]: I1028 04:45:15.627251 1855 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Oct 28 04:45:15.627677 kubelet[1855]: I1028 04:45:15.627465 1855 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 28 04:45:15.627677 kubelet[1855]: I1028 04:45:15.627468 1855 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Oct 28 04:45:15.670733 kubelet[1855]: E1028 04:45:15.670682 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:15.671869 kubelet[1855]: E1028 04:45:15.671838 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.772938 kubelet[1855]: E1028 04:45:15.772867 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.874028 kubelet[1855]: E1028 04:45:15.873887 1855 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.85\" not found" Oct 28 04:45:15.974651 kubelet[1855]: I1028 04:45:15.974627 1855 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Oct 28 04:45:15.974939 containerd[1568]: time="2025-10-28T04:45:15.974907596Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Oct 28 04:45:15.975293 kubelet[1855]: I1028 04:45:15.975164 1855 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Oct 28 04:45:16.671711 kubelet[1855]: E1028 04:45:16.671664 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:16.672122 kubelet[1855]: I1028 04:45:16.671755 1855 apiserver.go:52] "Watching apiserver" Oct 28 04:45:16.689640 systemd[1]: Created slice kubepods-burstable-pod44ae6292_1c40_4695_b47c_4d49feeec934.slice - libcontainer container kubepods-burstable-pod44ae6292_1c40_4695_b47c_4d49feeec934.slice. Oct 28 04:45:16.692815 kubelet[1855]: I1028 04:45:16.692773 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/44ae6292-1c40-4695-b47c-4d49feeec934-run\") pod \"kube-flannel-ds-v2ptb\" (UID: \"44ae6292-1c40-4695-b47c-4d49feeec934\") " pod="kube-flannel/kube-flannel-ds-v2ptb" Oct 28 04:45:16.692815 kubelet[1855]: I1028 04:45:16.692807 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/44ae6292-1c40-4695-b47c-4d49feeec934-cni-plugin\") pod \"kube-flannel-ds-v2ptb\" (UID: \"44ae6292-1c40-4695-b47c-4d49feeec934\") " pod="kube-flannel/kube-flannel-ds-v2ptb" Oct 28 04:45:16.692964 kubelet[1855]: I1028 04:45:16.692830 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/44ae6292-1c40-4695-b47c-4d49feeec934-cni\") pod \"kube-flannel-ds-v2ptb\" (UID: \"44ae6292-1c40-4695-b47c-4d49feeec934\") " pod="kube-flannel/kube-flannel-ds-v2ptb" Oct 28 04:45:16.692964 kubelet[1855]: I1028 04:45:16.692861 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/44ae6292-1c40-4695-b47c-4d49feeec934-flannel-cfg\") pod \"kube-flannel-ds-v2ptb\" (UID: \"44ae6292-1c40-4695-b47c-4d49feeec934\") " pod="kube-flannel/kube-flannel-ds-v2ptb" Oct 28 04:45:16.692964 kubelet[1855]: I1028 04:45:16.692875 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/44ae6292-1c40-4695-b47c-4d49feeec934-xtables-lock\") pod \"kube-flannel-ds-v2ptb\" (UID: \"44ae6292-1c40-4695-b47c-4d49feeec934\") " pod="kube-flannel/kube-flannel-ds-v2ptb" Oct 28 04:45:16.692964 kubelet[1855]: I1028 04:45:16.692891 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-f246h\" (UniqueName: \"kubernetes.io/projected/44ae6292-1c40-4695-b47c-4d49feeec934-kube-api-access-f246h\") pod \"kube-flannel-ds-v2ptb\" (UID: \"44ae6292-1c40-4695-b47c-4d49feeec934\") " pod="kube-flannel/kube-flannel-ds-v2ptb" Oct 28 04:45:16.718761 systemd[1]: Created slice kubepods-besteffort-podc00d3a96_11b4_4ef7_b38b_c36e05d78226.slice - libcontainer container kubepods-besteffort-podc00d3a96_11b4_4ef7_b38b_c36e05d78226.slice. Oct 28 04:45:16.777661 kubelet[1855]: I1028 04:45:16.777578 1855 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Oct 28 04:45:16.793915 kubelet[1855]: I1028 04:45:16.793867 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/c00d3a96-11b4-4ef7-b38b-c36e05d78226-lib-modules\") pod \"kube-proxy-xwf64\" (UID: \"c00d3a96-11b4-4ef7-b38b-c36e05d78226\") " pod="kube-system/kube-proxy-xwf64" Oct 28 04:45:16.793915 kubelet[1855]: I1028 04:45:16.793908 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xsmng\" (UniqueName: \"kubernetes.io/projected/c00d3a96-11b4-4ef7-b38b-c36e05d78226-kube-api-access-xsmng\") pod \"kube-proxy-xwf64\" (UID: \"c00d3a96-11b4-4ef7-b38b-c36e05d78226\") " pod="kube-system/kube-proxy-xwf64" Oct 28 04:45:16.794043 kubelet[1855]: I1028 04:45:16.794026 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/c00d3a96-11b4-4ef7-b38b-c36e05d78226-kube-proxy\") pod \"kube-proxy-xwf64\" (UID: \"c00d3a96-11b4-4ef7-b38b-c36e05d78226\") " pod="kube-system/kube-proxy-xwf64" Oct 28 04:45:16.794067 kubelet[1855]: I1028 04:45:16.794044 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/c00d3a96-11b4-4ef7-b38b-c36e05d78226-xtables-lock\") pod \"kube-proxy-xwf64\" (UID: \"c00d3a96-11b4-4ef7-b38b-c36e05d78226\") " pod="kube-system/kube-proxy-xwf64" Oct 28 04:45:17.014301 kubelet[1855]: E1028 04:45:17.014186 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:17.015119 containerd[1568]: time="2025-10-28T04:45:17.015078454Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-v2ptb,Uid:44ae6292-1c40-4695-b47c-4d49feeec934,Namespace:kube-flannel,Attempt:0,}" Oct 28 04:45:17.035738 kubelet[1855]: E1028 04:45:17.035695 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:17.036185 containerd[1568]: time="2025-10-28T04:45:17.036150903Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-xwf64,Uid:c00d3a96-11b4-4ef7-b38b-c36e05d78226,Namespace:kube-system,Attempt:0,}" Oct 28 04:45:17.507802 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1608521390.mount: Deactivated successfully. Oct 28 04:45:17.516434 containerd[1568]: time="2025-10-28T04:45:17.516372195Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 28 04:45:17.519561 containerd[1568]: time="2025-10-28T04:45:17.519513531Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 28 04:45:17.521039 containerd[1568]: time="2025-10-28T04:45:17.521003685Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Oct 28 04:45:17.522098 containerd[1568]: time="2025-10-28T04:45:17.522037108Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 28 04:45:17.523380 containerd[1568]: time="2025-10-28T04:45:17.523323209Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Oct 28 04:45:17.525050 containerd[1568]: time="2025-10-28T04:45:17.524980308Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Oct 28 04:45:17.527273 containerd[1568]: time="2025-10-28T04:45:17.527226016Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 504.429798ms" Oct 28 04:45:17.529333 containerd[1568]: time="2025-10-28T04:45:17.529015312Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 487.06914ms" Oct 28 04:45:17.548824 containerd[1568]: time="2025-10-28T04:45:17.548763513Z" level=info msg="connecting to shim 6491443e32e5aca96248b40164cabd941f16078d2876289f9be6fb0578c9b503" address="unix:///run/containerd/s/231f43388fc8da9721bad7f219b7baf5f4fb1eb818d5fe1cb733dda727834514" namespace=k8s.io protocol=ttrpc version=3 Oct 28 04:45:17.555936 containerd[1568]: time="2025-10-28T04:45:17.555876194Z" level=info msg="connecting to shim a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8" address="unix:///run/containerd/s/6f2aba7a65ff5829bfafc45c6dd0cbed0eb75cc0dd6c82c920dd0982e6e308f2" namespace=k8s.io protocol=ttrpc version=3 Oct 28 04:45:17.574600 systemd[1]: Started cri-containerd-6491443e32e5aca96248b40164cabd941f16078d2876289f9be6fb0578c9b503.scope - libcontainer container 6491443e32e5aca96248b40164cabd941f16078d2876289f9be6fb0578c9b503. Oct 28 04:45:17.578556 systemd[1]: Started cri-containerd-a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8.scope - libcontainer container a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8. Oct 28 04:45:17.603509 containerd[1568]: time="2025-10-28T04:45:17.603467795Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-xwf64,Uid:c00d3a96-11b4-4ef7-b38b-c36e05d78226,Namespace:kube-system,Attempt:0,} returns sandbox id \"6491443e32e5aca96248b40164cabd941f16078d2876289f9be6fb0578c9b503\"" Oct 28 04:45:17.605119 kubelet[1855]: E1028 04:45:17.605091 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:17.607124 containerd[1568]: time="2025-10-28T04:45:17.606639761Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Oct 28 04:45:17.620973 containerd[1568]: time="2025-10-28T04:45:17.620934980Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-v2ptb,Uid:44ae6292-1c40-4695-b47c-4d49feeec934,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8\"" Oct 28 04:45:17.621640 kubelet[1855]: E1028 04:45:17.621618 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:17.672122 kubelet[1855]: E1028 04:45:17.672084 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:18.612155 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount17823170.mount: Deactivated successfully. Oct 28 04:45:18.672693 kubelet[1855]: E1028 04:45:18.672657 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:18.859257 containerd[1568]: time="2025-10-28T04:45:18.858676119Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:18.859257 containerd[1568]: time="2025-10-28T04:45:18.859245219Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240108" Oct 28 04:45:18.860321 containerd[1568]: time="2025-10-28T04:45:18.860287090Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:18.863075 containerd[1568]: time="2025-10-28T04:45:18.862939812Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:18.863919 containerd[1568]: time="2025-10-28T04:45:18.863870159Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"28239125\" in 1.257177014s" Oct 28 04:45:18.863919 containerd[1568]: time="2025-10-28T04:45:18.863916424Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Oct 28 04:45:18.865222 containerd[1568]: time="2025-10-28T04:45:18.865191021Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Oct 28 04:45:18.869541 containerd[1568]: time="2025-10-28T04:45:18.869500700Z" level=info msg="CreateContainer within sandbox \"6491443e32e5aca96248b40164cabd941f16078d2876289f9be6fb0578c9b503\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Oct 28 04:45:18.878425 containerd[1568]: time="2025-10-28T04:45:18.878061236Z" level=info msg="Container ed7b255bccb42b629c40848b16c9061509c4f033fa1b3cbe37e6ffdd091f93e0: CDI devices from CRI Config.CDIDevices: []" Oct 28 04:45:18.887161 containerd[1568]: time="2025-10-28T04:45:18.887098141Z" level=info msg="CreateContainer within sandbox \"6491443e32e5aca96248b40164cabd941f16078d2876289f9be6fb0578c9b503\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"ed7b255bccb42b629c40848b16c9061509c4f033fa1b3cbe37e6ffdd091f93e0\"" Oct 28 04:45:18.888070 containerd[1568]: time="2025-10-28T04:45:18.888045082Z" level=info msg="StartContainer for \"ed7b255bccb42b629c40848b16c9061509c4f033fa1b3cbe37e6ffdd091f93e0\"" Oct 28 04:45:18.889585 containerd[1568]: time="2025-10-28T04:45:18.889540610Z" level=info msg="connecting to shim ed7b255bccb42b629c40848b16c9061509c4f033fa1b3cbe37e6ffdd091f93e0" address="unix:///run/containerd/s/231f43388fc8da9721bad7f219b7baf5f4fb1eb818d5fe1cb733dda727834514" protocol=ttrpc version=3 Oct 28 04:45:18.908585 systemd[1]: Started cri-containerd-ed7b255bccb42b629c40848b16c9061509c4f033fa1b3cbe37e6ffdd091f93e0.scope - libcontainer container ed7b255bccb42b629c40848b16c9061509c4f033fa1b3cbe37e6ffdd091f93e0. Oct 28 04:45:18.940433 containerd[1568]: time="2025-10-28T04:45:18.940373872Z" level=info msg="StartContainer for \"ed7b255bccb42b629c40848b16c9061509c4f033fa1b3cbe37e6ffdd091f93e0\" returns successfully" Oct 28 04:45:19.673582 kubelet[1855]: E1028 04:45:19.673518 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:19.860208 kubelet[1855]: E1028 04:45:19.860156 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:19.995998 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount634888928.mount: Deactivated successfully. Oct 28 04:45:20.024570 containerd[1568]: time="2025-10-28T04:45:20.024174727Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:20.024873 containerd[1568]: time="2025-10-28T04:45:20.024647427Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Oct 28 04:45:20.026019 containerd[1568]: time="2025-10-28T04:45:20.025976553Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:20.028358 containerd[1568]: time="2025-10-28T04:45:20.028317819Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:20.029340 containerd[1568]: time="2025-10-28T04:45:20.029315283Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"5125394\" in 1.164091033s" Oct 28 04:45:20.029389 containerd[1568]: time="2025-10-28T04:45:20.029346834Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Oct 28 04:45:20.034156 containerd[1568]: time="2025-10-28T04:45:20.033836223Z" level=info msg="CreateContainer within sandbox \"a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Oct 28 04:45:20.041166 containerd[1568]: time="2025-10-28T04:45:20.041127262Z" level=info msg="Container 998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8: CDI devices from CRI Config.CDIDevices: []" Oct 28 04:45:20.048407 containerd[1568]: time="2025-10-28T04:45:20.048341363Z" level=info msg="CreateContainer within sandbox \"a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8\"" Oct 28 04:45:20.049312 containerd[1568]: time="2025-10-28T04:45:20.049288442Z" level=info msg="StartContainer for \"998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8\"" Oct 28 04:45:20.050090 containerd[1568]: time="2025-10-28T04:45:20.050068051Z" level=info msg="connecting to shim 998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8" address="unix:///run/containerd/s/6f2aba7a65ff5829bfafc45c6dd0cbed0eb75cc0dd6c82c920dd0982e6e308f2" protocol=ttrpc version=3 Oct 28 04:45:20.077570 systemd[1]: Started cri-containerd-998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8.scope - libcontainer container 998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8. Oct 28 04:45:20.101803 systemd[1]: cri-containerd-998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8.scope: Deactivated successfully. Oct 28 04:45:20.103901 containerd[1568]: time="2025-10-28T04:45:20.103759895Z" level=info msg="TaskExit event in podsandbox handler container_id:\"998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8\" id:\"998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8\" pid:2195 exited_at:{seconds:1761626720 nanos:102795301}" Oct 28 04:45:20.104094 containerd[1568]: time="2025-10-28T04:45:20.103991826Z" level=info msg="received exit event container_id:\"998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8\" id:\"998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8\" pid:2195 exited_at:{seconds:1761626720 nanos:102795301}" Oct 28 04:45:20.105087 containerd[1568]: time="2025-10-28T04:45:20.104986131Z" level=info msg="StartContainer for \"998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8\" returns successfully" Oct 28 04:45:20.673808 kubelet[1855]: E1028 04:45:20.673774 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:20.863593 kubelet[1855]: E1028 04:45:20.863563 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:20.863711 kubelet[1855]: E1028 04:45:20.863659 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:20.864319 containerd[1568]: time="2025-10-28T04:45:20.864286324Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Oct 28 04:45:20.875743 kubelet[1855]: I1028 04:45:20.875687 1855 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-xwf64" podStartSLOduration=5.616820147 podStartE2EDuration="6.875671788s" podCreationTimestamp="2025-10-28 04:45:14 +0000 UTC" firstStartedPulling="2025-10-28 04:45:17.60621338 +0000 UTC m=+4.690159418" lastFinishedPulling="2025-10-28 04:45:18.865065061 +0000 UTC m=+5.949011059" observedRunningTime="2025-10-28 04:45:19.870031189 +0000 UTC m=+6.953977227" watchObservedRunningTime="2025-10-28 04:45:20.875671788 +0000 UTC m=+7.959617826" Oct 28 04:45:20.930587 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-998f5d7f76049410c5a6728df56213e280ec5152d53e67d4d11808d3cf38a0f8-rootfs.mount: Deactivated successfully. Oct 28 04:45:21.673895 kubelet[1855]: E1028 04:45:21.673866 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:22.552405 containerd[1568]: time="2025-10-28T04:45:22.552341075Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:22.553249 containerd[1568]: time="2025-10-28T04:45:22.552713091Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Oct 28 04:45:22.555397 containerd[1568]: time="2025-10-28T04:45:22.555361914Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:22.559140 containerd[1568]: time="2025-10-28T04:45:22.557804115Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:22.559222 containerd[1568]: time="2025-10-28T04:45:22.559143062Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32412118\" in 1.694815431s" Oct 28 04:45:22.559222 containerd[1568]: time="2025-10-28T04:45:22.559175773Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Oct 28 04:45:22.563247 containerd[1568]: time="2025-10-28T04:45:22.563204932Z" level=info msg="CreateContainer within sandbox \"a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Oct 28 04:45:22.568771 containerd[1568]: time="2025-10-28T04:45:22.568237852Z" level=info msg="Container 3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60: CDI devices from CRI Config.CDIDevices: []" Oct 28 04:45:22.575205 containerd[1568]: time="2025-10-28T04:45:22.575170324Z" level=info msg="CreateContainer within sandbox \"a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60\"" Oct 28 04:45:22.575988 containerd[1568]: time="2025-10-28T04:45:22.575941429Z" level=info msg="StartContainer for \"3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60\"" Oct 28 04:45:22.578945 containerd[1568]: time="2025-10-28T04:45:22.578870414Z" level=info msg="connecting to shim 3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60" address="unix:///run/containerd/s/6f2aba7a65ff5829bfafc45c6dd0cbed0eb75cc0dd6c82c920dd0982e6e308f2" protocol=ttrpc version=3 Oct 28 04:45:22.598545 systemd[1]: Started cri-containerd-3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60.scope - libcontainer container 3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60. Oct 28 04:45:22.620713 systemd[1]: cri-containerd-3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60.scope: Deactivated successfully. Oct 28 04:45:22.621422 containerd[1568]: time="2025-10-28T04:45:22.621374750Z" level=info msg="TaskExit event in podsandbox handler container_id:\"3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60\" id:\"3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60\" pid:2267 exited_at:{seconds:1761626722 nanos:621052319}" Oct 28 04:45:22.622035 containerd[1568]: time="2025-10-28T04:45:22.621999536Z" level=info msg="received exit event container_id:\"3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60\" id:\"3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60\" pid:2267 exited_at:{seconds:1761626722 nanos:621052319}" Oct 28 04:45:22.623388 containerd[1568]: time="2025-10-28T04:45:22.623351600Z" level=info msg="StartContainer for \"3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60\" returns successfully" Oct 28 04:45:22.638250 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3bb619e842399a0c045e3461b927411080876f7f0b34d80248c7400d521f3b60-rootfs.mount: Deactivated successfully. Oct 28 04:45:22.674540 kubelet[1855]: E1028 04:45:22.674456 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:22.702259 kubelet[1855]: I1028 04:45:22.701540 1855 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Oct 28 04:45:22.869332 kubelet[1855]: E1028 04:45:22.868852 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:22.874230 containerd[1568]: time="2025-10-28T04:45:22.874170143Z" level=info msg="CreateContainer within sandbox \"a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Oct 28 04:45:22.882012 containerd[1568]: time="2025-10-28T04:45:22.881956536Z" level=info msg="Container 41e43b4e5e89af058368d2fc5659192c95ff34f5f9f2024dc9568c11b503caa7: CDI devices from CRI Config.CDIDevices: []" Oct 28 04:45:22.887606 containerd[1568]: time="2025-10-28T04:45:22.887561737Z" level=info msg="CreateContainer within sandbox \"a817c0892bfca089f59209be2be74b7712a85100b74fad72122194aecb14a5c8\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"41e43b4e5e89af058368d2fc5659192c95ff34f5f9f2024dc9568c11b503caa7\"" Oct 28 04:45:22.888127 containerd[1568]: time="2025-10-28T04:45:22.888098828Z" level=info msg="StartContainer for \"41e43b4e5e89af058368d2fc5659192c95ff34f5f9f2024dc9568c11b503caa7\"" Oct 28 04:45:22.888977 containerd[1568]: time="2025-10-28T04:45:22.888875412Z" level=info msg="connecting to shim 41e43b4e5e89af058368d2fc5659192c95ff34f5f9f2024dc9568c11b503caa7" address="unix:///run/containerd/s/6f2aba7a65ff5829bfafc45c6dd0cbed0eb75cc0dd6c82c920dd0982e6e308f2" protocol=ttrpc version=3 Oct 28 04:45:22.910545 systemd[1]: Started cri-containerd-41e43b4e5e89af058368d2fc5659192c95ff34f5f9f2024dc9568c11b503caa7.scope - libcontainer container 41e43b4e5e89af058368d2fc5659192c95ff34f5f9f2024dc9568c11b503caa7. Oct 28 04:45:22.937436 containerd[1568]: time="2025-10-28T04:45:22.936364000Z" level=info msg="StartContainer for \"41e43b4e5e89af058368d2fc5659192c95ff34f5f9f2024dc9568c11b503caa7\" returns successfully" Oct 28 04:45:23.675325 kubelet[1855]: E1028 04:45:23.675278 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:23.873562 kubelet[1855]: E1028 04:45:23.873530 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:23.885399 kubelet[1855]: I1028 04:45:23.885313 1855 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-v2ptb" podStartSLOduration=4.947362968 podStartE2EDuration="9.885298752s" podCreationTimestamp="2025-10-28 04:45:14 +0000 UTC" firstStartedPulling="2025-10-28 04:45:17.622310971 +0000 UTC m=+4.706257009" lastFinishedPulling="2025-10-28 04:45:22.560246755 +0000 UTC m=+9.644192793" observedRunningTime="2025-10-28 04:45:23.885263921 +0000 UTC m=+10.969209959" watchObservedRunningTime="2025-10-28 04:45:23.885298752 +0000 UTC m=+10.969244750" Oct 28 04:45:24.000062 systemd-networkd[1478]: flannel.1: Link UP Oct 28 04:45:24.000070 systemd-networkd[1478]: flannel.1: Gained carrier Oct 28 04:45:24.676273 kubelet[1855]: E1028 04:45:24.676228 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:24.875071 kubelet[1855]: E1028 04:45:24.875030 1855 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Oct 28 04:45:25.676498 kubelet[1855]: E1028 04:45:25.676456 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:26.012580 systemd-networkd[1478]: flannel.1: Gained IPv6LL Oct 28 04:45:26.181484 systemd[1]: Created slice kubepods-besteffort-pod0ce26d90_69f8_446c_901f_7c98ca822d22.slice - libcontainer container kubepods-besteffort-pod0ce26d90_69f8_446c_901f_7c98ca822d22.slice. Oct 28 04:45:26.251712 kubelet[1855]: I1028 04:45:26.251665 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-x5s58\" (UniqueName: \"kubernetes.io/projected/0ce26d90-69f8-446c-901f-7c98ca822d22-kube-api-access-x5s58\") pod \"nginx-deployment-7fcdb87857-6dzf5\" (UID: \"0ce26d90-69f8-446c-901f-7c98ca822d22\") " pod="default/nginx-deployment-7fcdb87857-6dzf5" Oct 28 04:45:26.484428 containerd[1568]: time="2025-10-28T04:45:26.484333983Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-6dzf5,Uid:0ce26d90-69f8-446c-901f-7c98ca822d22,Namespace:default,Attempt:0,}" Oct 28 04:45:26.496785 systemd-networkd[1478]: cni0: Link UP Oct 28 04:45:26.496792 systemd-networkd[1478]: cni0: Gained carrier Oct 28 04:45:26.497950 systemd-networkd[1478]: cni0: Lost carrier Oct 28 04:45:26.501173 systemd-networkd[1478]: veth06ca18bf: Link UP Oct 28 04:45:26.502725 kernel: cni0: port 1(veth06ca18bf) entered blocking state Oct 28 04:45:26.502785 kernel: cni0: port 1(veth06ca18bf) entered disabled state Oct 28 04:45:26.502808 kernel: veth06ca18bf: entered allmulticast mode Oct 28 04:45:26.504586 kernel: veth06ca18bf: entered promiscuous mode Oct 28 04:45:26.509742 kernel: cni0: port 1(veth06ca18bf) entered blocking state Oct 28 04:45:26.509802 kernel: cni0: port 1(veth06ca18bf) entered forwarding state Oct 28 04:45:26.509884 systemd-networkd[1478]: veth06ca18bf: Gained carrier Oct 28 04:45:26.510214 systemd-networkd[1478]: cni0: Gained carrier Oct 28 04:45:26.512613 containerd[1568]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40001047f0), "name":"cbr0", "type":"bridge"} Oct 28 04:45:26.512613 containerd[1568]: delegateAdd: netconf sent to delegate plugin: Oct 28 04:45:26.535189 containerd[1568]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-10-28T04:45:26.535060274Z" level=info msg="connecting to shim 3ef479614800d0113a7b60071916d7174b819ecdc262af182ef1f55a3d8a6a46" address="unix:///run/containerd/s/eb8e7eb94c3627191b5807c8e42eaa62397f02c8e66499850765e254c74fbae5" namespace=k8s.io protocol=ttrpc version=3 Oct 28 04:45:26.557535 systemd[1]: Started cri-containerd-3ef479614800d0113a7b60071916d7174b819ecdc262af182ef1f55a3d8a6a46.scope - libcontainer container 3ef479614800d0113a7b60071916d7174b819ecdc262af182ef1f55a3d8a6a46. Oct 28 04:45:26.567944 systemd-resolved[1274]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 28 04:45:26.585649 containerd[1568]: time="2025-10-28T04:45:26.585616208Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-6dzf5,Uid:0ce26d90-69f8-446c-901f-7c98ca822d22,Namespace:default,Attempt:0,} returns sandbox id \"3ef479614800d0113a7b60071916d7174b819ecdc262af182ef1f55a3d8a6a46\"" Oct 28 04:45:26.586993 containerd[1568]: time="2025-10-28T04:45:26.586968796Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 28 04:45:26.677641 kubelet[1855]: E1028 04:45:26.677594 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:27.548627 systemd-networkd[1478]: cni0: Gained IPv6LL Oct 28 04:45:27.678465 kubelet[1855]: E1028 04:45:27.678430 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:28.444522 systemd-networkd[1478]: veth06ca18bf: Gained IPv6LL Oct 28 04:45:28.570533 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1124587167.mount: Deactivated successfully. Oct 28 04:45:28.679776 kubelet[1855]: E1028 04:45:28.679729 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:29.323901 containerd[1568]: time="2025-10-28T04:45:29.323845851Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:29.324700 containerd[1568]: time="2025-10-28T04:45:29.324664269Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=70017144" Oct 28 04:45:29.325113 containerd[1568]: time="2025-10-28T04:45:29.325089734Z" level=info msg="ImageCreate event name:\"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:29.327910 containerd[1568]: time="2025-10-28T04:45:29.327874674Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:29.328938 containerd[1568]: time="2025-10-28T04:45:29.328817824Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 2.741710022s" Oct 28 04:45:29.328938 containerd[1568]: time="2025-10-28T04:45:29.328853816Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 28 04:45:29.332164 containerd[1568]: time="2025-10-28T04:45:29.332134805Z" level=info msg="CreateContainer within sandbox \"3ef479614800d0113a7b60071916d7174b819ecdc262af182ef1f55a3d8a6a46\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Oct 28 04:45:29.339026 containerd[1568]: time="2025-10-28T04:45:29.338985679Z" level=info msg="Container 88d0693c50c677b366ddd9ed244bc9559ef4e35513053343bf29ca64686311f7: CDI devices from CRI Config.CDIDevices: []" Oct 28 04:45:29.344091 containerd[1568]: time="2025-10-28T04:45:29.344060589Z" level=info msg="CreateContainer within sandbox \"3ef479614800d0113a7b60071916d7174b819ecdc262af182ef1f55a3d8a6a46\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"88d0693c50c677b366ddd9ed244bc9559ef4e35513053343bf29ca64686311f7\"" Oct 28 04:45:29.344527 containerd[1568]: time="2025-10-28T04:45:29.344486094Z" level=info msg="StartContainer for \"88d0693c50c677b366ddd9ed244bc9559ef4e35513053343bf29ca64686311f7\"" Oct 28 04:45:29.345301 containerd[1568]: time="2025-10-28T04:45:29.345279237Z" level=info msg="connecting to shim 88d0693c50c677b366ddd9ed244bc9559ef4e35513053343bf29ca64686311f7" address="unix:///run/containerd/s/eb8e7eb94c3627191b5807c8e42eaa62397f02c8e66499850765e254c74fbae5" protocol=ttrpc version=3 Oct 28 04:45:29.363518 systemd[1]: Started cri-containerd-88d0693c50c677b366ddd9ed244bc9559ef4e35513053343bf29ca64686311f7.scope - libcontainer container 88d0693c50c677b366ddd9ed244bc9559ef4e35513053343bf29ca64686311f7. Oct 28 04:45:29.389598 containerd[1568]: time="2025-10-28T04:45:29.389563813Z" level=info msg="StartContainer for \"88d0693c50c677b366ddd9ed244bc9559ef4e35513053343bf29ca64686311f7\" returns successfully" Oct 28 04:45:29.680430 kubelet[1855]: E1028 04:45:29.680374 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:30.680822 kubelet[1855]: E1028 04:45:30.680779 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:31.681079 kubelet[1855]: E1028 04:45:31.681032 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:32.283375 kubelet[1855]: I1028 04:45:32.283305 1855 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-6dzf5" podStartSLOduration=3.540329237 podStartE2EDuration="6.283287727s" podCreationTimestamp="2025-10-28 04:45:26 +0000 UTC" firstStartedPulling="2025-10-28 04:45:26.586522186 +0000 UTC m=+13.670468224" lastFinishedPulling="2025-10-28 04:45:29.329480676 +0000 UTC m=+16.413426714" observedRunningTime="2025-10-28 04:45:29.892533298 +0000 UTC m=+16.976479336" watchObservedRunningTime="2025-10-28 04:45:32.283287727 +0000 UTC m=+19.367233765" Oct 28 04:45:32.292282 systemd[1]: Created slice kubepods-besteffort-pod7e7f656e_34b8_4a54_9400_96be9315f1a6.slice - libcontainer container kubepods-besteffort-pod7e7f656e_34b8_4a54_9400_96be9315f1a6.slice. Oct 28 04:45:32.384517 kubelet[1855]: I1028 04:45:32.384461 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-v7gth\" (UniqueName: \"kubernetes.io/projected/7e7f656e-34b8-4a54-9400-96be9315f1a6-kube-api-access-v7gth\") pod \"nfs-server-provisioner-0\" (UID: \"7e7f656e-34b8-4a54-9400-96be9315f1a6\") " pod="default/nfs-server-provisioner-0" Oct 28 04:45:32.384517 kubelet[1855]: I1028 04:45:32.384513 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/7e7f656e-34b8-4a54-9400-96be9315f1a6-data\") pod \"nfs-server-provisioner-0\" (UID: \"7e7f656e-34b8-4a54-9400-96be9315f1a6\") " pod="default/nfs-server-provisioner-0" Oct 28 04:45:32.595639 containerd[1568]: time="2025-10-28T04:45:32.595518299Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:7e7f656e-34b8-4a54-9400-96be9315f1a6,Namespace:default,Attempt:0,}" Oct 28 04:45:32.605728 systemd-networkd[1478]: veth8a5ea0c0: Link UP Oct 28 04:45:32.608276 kernel: cni0: port 2(veth8a5ea0c0) entered blocking state Oct 28 04:45:32.608343 kernel: cni0: port 2(veth8a5ea0c0) entered disabled state Oct 28 04:45:32.608358 kernel: veth8a5ea0c0: entered allmulticast mode Oct 28 04:45:32.609402 kernel: veth8a5ea0c0: entered promiscuous mode Oct 28 04:45:32.615412 kernel: cni0: port 2(veth8a5ea0c0) entered blocking state Oct 28 04:45:32.615472 kernel: cni0: port 2(veth8a5ea0c0) entered forwarding state Oct 28 04:45:32.615583 systemd-networkd[1478]: veth8a5ea0c0: Gained carrier Oct 28 04:45:32.617377 containerd[1568]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Oct 28 04:45:32.617377 containerd[1568]: delegateAdd: netconf sent to delegate plugin: Oct 28 04:45:32.636929 containerd[1568]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-10-28T04:45:32.636891401Z" level=info msg="connecting to shim bb1b82eedef28a24e644113ec124d5d19764f2cad126abf11ce07dcc916b132b" address="unix:///run/containerd/s/fb07fe88431e253448d9a99204f7b2c5bb0485cb61091a593987f7be47369a61" namespace=k8s.io protocol=ttrpc version=3 Oct 28 04:45:32.659518 systemd[1]: Started cri-containerd-bb1b82eedef28a24e644113ec124d5d19764f2cad126abf11ce07dcc916b132b.scope - libcontainer container bb1b82eedef28a24e644113ec124d5d19764f2cad126abf11ce07dcc916b132b. Oct 28 04:45:32.670555 systemd-resolved[1274]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 28 04:45:32.681295 kubelet[1855]: E1028 04:45:32.681267 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:32.689668 containerd[1568]: time="2025-10-28T04:45:32.689635679Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:7e7f656e-34b8-4a54-9400-96be9315f1a6,Namespace:default,Attempt:0,} returns sandbox id \"bb1b82eedef28a24e644113ec124d5d19764f2cad126abf11ce07dcc916b132b\"" Oct 28 04:45:32.691313 containerd[1568]: time="2025-10-28T04:45:32.691283386Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Oct 28 04:45:33.681464 kubelet[1855]: E1028 04:45:33.681414 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:33.756536 systemd-networkd[1478]: veth8a5ea0c0: Gained IPv6LL Oct 28 04:45:34.513712 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2463890658.mount: Deactivated successfully. Oct 28 04:45:34.668910 kubelet[1855]: E1028 04:45:34.668857 1855 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:34.682289 kubelet[1855]: E1028 04:45:34.682234 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:35.682746 kubelet[1855]: E1028 04:45:35.682709 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:35.825240 containerd[1568]: time="2025-10-28T04:45:35.825174204Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:35.826249 containerd[1568]: time="2025-10-28T04:45:35.826069719Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Oct 28 04:45:35.827597 containerd[1568]: time="2025-10-28T04:45:35.827565924Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:35.830206 containerd[1568]: time="2025-10-28T04:45:35.830176323Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:35.831168 containerd[1568]: time="2025-10-28T04:45:35.831038845Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.139718706s" Oct 28 04:45:35.831168 containerd[1568]: time="2025-10-28T04:45:35.831069039Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Oct 28 04:45:35.835245 containerd[1568]: time="2025-10-28T04:45:35.835214636Z" level=info msg="CreateContainer within sandbox \"bb1b82eedef28a24e644113ec124d5d19764f2cad126abf11ce07dcc916b132b\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Oct 28 04:45:35.842165 containerd[1568]: time="2025-10-28T04:45:35.840715023Z" level=info msg="Container cb0dcbc9e4eaa05fb368825fe65a4f9c563e26b9063e0df11392c1f091b0957d: CDI devices from CRI Config.CDIDevices: []" Oct 28 04:45:35.846646 containerd[1568]: time="2025-10-28T04:45:35.846603739Z" level=info msg="CreateContainer within sandbox \"bb1b82eedef28a24e644113ec124d5d19764f2cad126abf11ce07dcc916b132b\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"cb0dcbc9e4eaa05fb368825fe65a4f9c563e26b9063e0df11392c1f091b0957d\"" Oct 28 04:45:35.847165 containerd[1568]: time="2025-10-28T04:45:35.847048417Z" level=info msg="StartContainer for \"cb0dcbc9e4eaa05fb368825fe65a4f9c563e26b9063e0df11392c1f091b0957d\"" Oct 28 04:45:35.848020 containerd[1568]: time="2025-10-28T04:45:35.847987044Z" level=info msg="connecting to shim cb0dcbc9e4eaa05fb368825fe65a4f9c563e26b9063e0df11392c1f091b0957d" address="unix:///run/containerd/s/fb07fe88431e253448d9a99204f7b2c5bb0485cb61091a593987f7be47369a61" protocol=ttrpc version=3 Oct 28 04:45:35.873549 systemd[1]: Started cri-containerd-cb0dcbc9e4eaa05fb368825fe65a4f9c563e26b9063e0df11392c1f091b0957d.scope - libcontainer container cb0dcbc9e4eaa05fb368825fe65a4f9c563e26b9063e0df11392c1f091b0957d. Oct 28 04:45:35.900029 containerd[1568]: time="2025-10-28T04:45:35.899982832Z" level=info msg="StartContainer for \"cb0dcbc9e4eaa05fb368825fe65a4f9c563e26b9063e0df11392c1f091b0957d\" returns successfully" Oct 28 04:45:36.682893 kubelet[1855]: E1028 04:45:36.682853 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:36.912768 kubelet[1855]: I1028 04:45:36.912702 1855 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.7714695740000002 podStartE2EDuration="4.912687397s" podCreationTimestamp="2025-10-28 04:45:32 +0000 UTC" firstStartedPulling="2025-10-28 04:45:32.690901143 +0000 UTC m=+19.774847181" lastFinishedPulling="2025-10-28 04:45:35.832118966 +0000 UTC m=+22.916065004" observedRunningTime="2025-10-28 04:45:36.912669721 +0000 UTC m=+23.996615758" watchObservedRunningTime="2025-10-28 04:45:36.912687397 +0000 UTC m=+23.996633395" Oct 28 04:45:37.683190 kubelet[1855]: E1028 04:45:37.683147 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:38.684231 kubelet[1855]: E1028 04:45:38.684177 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:39.684982 kubelet[1855]: E1028 04:45:39.684946 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:40.686547 kubelet[1855]: E1028 04:45:40.686463 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:41.155337 systemd[1]: Created slice kubepods-besteffort-podce839af8_02d7_41dc_86f5_b94f19b2025e.slice - libcontainer container kubepods-besteffort-podce839af8_02d7_41dc_86f5_b94f19b2025e.slice. Oct 28 04:45:41.231699 kubelet[1855]: I1028 04:45:41.231663 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-pnhxh\" (UniqueName: \"kubernetes.io/projected/ce839af8-02d7-41dc-86f5-b94f19b2025e-kube-api-access-pnhxh\") pod \"test-pod-1\" (UID: \"ce839af8-02d7-41dc-86f5-b94f19b2025e\") " pod="default/test-pod-1" Oct 28 04:45:41.232062 kubelet[1855]: I1028 04:45:41.231880 1855 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-528eaa5c-f745-4593-9425-d1ef505de37d\" (UniqueName: \"kubernetes.io/nfs/ce839af8-02d7-41dc-86f5-b94f19b2025e-pvc-528eaa5c-f745-4593-9425-d1ef505de37d\") pod \"test-pod-1\" (UID: \"ce839af8-02d7-41dc-86f5-b94f19b2025e\") " pod="default/test-pod-1" Oct 28 04:45:41.351432 kernel: netfs: FS-Cache loaded Oct 28 04:45:41.377267 kernel: RPC: Registered named UNIX socket transport module. Oct 28 04:45:41.377421 kernel: RPC: Registered udp transport module. Oct 28 04:45:41.377441 kernel: RPC: Registered tcp transport module. Oct 28 04:45:41.377458 kernel: RPC: Registered tcp-with-tls transport module. Oct 28 04:45:41.377908 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 28 04:45:41.553743 kernel: NFS: Registering the id_resolver key type Oct 28 04:45:41.553863 kernel: Key type id_resolver registered Oct 28 04:45:41.553884 kernel: Key type id_legacy registered Oct 28 04:45:41.571898 nfsidmap[2828]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Oct 28 04:45:41.572707 nfsidmap[2828]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 28 04:45:41.575614 nfsidmap[2831]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Oct 28 04:45:41.575762 nfsidmap[2831]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Oct 28 04:45:41.581959 nfsrahead[2835]: setting /var/lib/kubelet/pods/ce839af8-02d7-41dc-86f5-b94f19b2025e/volumes/kubernetes.io~nfs/pvc-528eaa5c-f745-4593-9425-d1ef505de37d readahead to 128 Oct 28 04:45:41.686611 kubelet[1855]: E1028 04:45:41.686565 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:41.759147 containerd[1568]: time="2025-10-28T04:45:41.759088080Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ce839af8-02d7-41dc-86f5-b94f19b2025e,Namespace:default,Attempt:0,}" Oct 28 04:45:41.771800 kernel: cni0: port 3(vethd67ba783) entered blocking state Oct 28 04:45:41.771879 kernel: cni0: port 3(vethd67ba783) entered disabled state Oct 28 04:45:41.771896 kernel: vethd67ba783: entered allmulticast mode Oct 28 04:45:41.769886 systemd-networkd[1478]: vethd67ba783: Link UP Oct 28 04:45:41.773419 kernel: vethd67ba783: entered promiscuous mode Oct 28 04:45:41.780022 kernel: cni0: port 3(vethd67ba783) entered blocking state Oct 28 04:45:41.780126 kernel: cni0: port 3(vethd67ba783) entered forwarding state Oct 28 04:45:41.780270 systemd-networkd[1478]: vethd67ba783: Gained carrier Oct 28 04:45:41.781966 containerd[1568]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000aa950), "name":"cbr0", "type":"bridge"} Oct 28 04:45:41.781966 containerd[1568]: delegateAdd: netconf sent to delegate plugin: Oct 28 04:45:41.802252 containerd[1568]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-10-28T04:45:41.802015788Z" level=info msg="connecting to shim f49678d635244a2a5d3f7953c773e62642c488951323dc56000d206cbe22895c" address="unix:///run/containerd/s/88fadf06800f04a8837024a1d1f428c1d3e4f543e902c6baa1a7349027b1e06d" namespace=k8s.io protocol=ttrpc version=3 Oct 28 04:45:41.831571 systemd[1]: Started cri-containerd-f49678d635244a2a5d3f7953c773e62642c488951323dc56000d206cbe22895c.scope - libcontainer container f49678d635244a2a5d3f7953c773e62642c488951323dc56000d206cbe22895c. Oct 28 04:45:41.842796 systemd-resolved[1274]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Oct 28 04:45:41.862295 containerd[1568]: time="2025-10-28T04:45:41.862262740Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ce839af8-02d7-41dc-86f5-b94f19b2025e,Namespace:default,Attempt:0,} returns sandbox id \"f49678d635244a2a5d3f7953c773e62642c488951323dc56000d206cbe22895c\"" Oct 28 04:45:41.863336 containerd[1568]: time="2025-10-28T04:45:41.863305422Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Oct 28 04:45:42.103274 containerd[1568]: time="2025-10-28T04:45:42.102980823Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Oct 28 04:45:42.104426 containerd[1568]: time="2025-10-28T04:45:42.103904367Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Oct 28 04:45:42.107494 containerd[1568]: time="2025-10-28T04:45:42.107461962Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:0537df20ac7c5485a0f6b7bfb8e3fbbc8714fce070bab2a6344e5cadfba58d90\", size \"70017022\" in 244.120107ms" Oct 28 04:45:42.107568 containerd[1568]: time="2025-10-28T04:45:42.107497237Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:93a0bb2334812af7cad47dff33d3bbc1ced8774dea82e727e7d8cb917ca9a024\"" Oct 28 04:45:42.110229 containerd[1568]: time="2025-10-28T04:45:42.110204518Z" level=info msg="CreateContainer within sandbox \"f49678d635244a2a5d3f7953c773e62642c488951323dc56000d206cbe22895c\" for container &ContainerMetadata{Name:test,Attempt:0,}" Oct 28 04:45:42.115699 containerd[1568]: time="2025-10-28T04:45:42.115655754Z" level=info msg="Container 0806de22d6fc2732d48b76a025769c4f27a32c72b3ce03d231d45a670220e082: CDI devices from CRI Config.CDIDevices: []" Oct 28 04:45:42.120706 containerd[1568]: time="2025-10-28T04:45:42.120654898Z" level=info msg="CreateContainer within sandbox \"f49678d635244a2a5d3f7953c773e62642c488951323dc56000d206cbe22895c\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"0806de22d6fc2732d48b76a025769c4f27a32c72b3ce03d231d45a670220e082\"" Oct 28 04:45:42.121224 containerd[1568]: time="2025-10-28T04:45:42.121187419Z" level=info msg="StartContainer for \"0806de22d6fc2732d48b76a025769c4f27a32c72b3ce03d231d45a670220e082\"" Oct 28 04:45:42.122021 containerd[1568]: time="2025-10-28T04:45:42.121988101Z" level=info msg="connecting to shim 0806de22d6fc2732d48b76a025769c4f27a32c72b3ce03d231d45a670220e082" address="unix:///run/containerd/s/88fadf06800f04a8837024a1d1f428c1d3e4f543e902c6baa1a7349027b1e06d" protocol=ttrpc version=3 Oct 28 04:45:42.142540 systemd[1]: Started cri-containerd-0806de22d6fc2732d48b76a025769c4f27a32c72b3ce03d231d45a670220e082.scope - libcontainer container 0806de22d6fc2732d48b76a025769c4f27a32c72b3ce03d231d45a670220e082. Oct 28 04:45:42.165862 containerd[1568]: time="2025-10-28T04:45:42.165828078Z" level=info msg="StartContainer for \"0806de22d6fc2732d48b76a025769c4f27a32c72b3ce03d231d45a670220e082\" returns successfully" Oct 28 04:45:42.687374 kubelet[1855]: E1028 04:45:42.687337 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:42.924462 kubelet[1855]: I1028 04:45:42.924328 1855 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=10.679305295 podStartE2EDuration="10.924312229s" podCreationTimestamp="2025-10-28 04:45:32 +0000 UTC" firstStartedPulling="2025-10-28 04:45:41.863071777 +0000 UTC m=+28.947017815" lastFinishedPulling="2025-10-28 04:45:42.108078711 +0000 UTC m=+29.192024749" observedRunningTime="2025-10-28 04:45:42.92416901 +0000 UTC m=+30.008115048" watchObservedRunningTime="2025-10-28 04:45:42.924312229 +0000 UTC m=+30.008258267" Oct 28 04:45:43.228537 systemd-networkd[1478]: vethd67ba783: Gained IPv6LL Oct 28 04:45:43.687875 kubelet[1855]: E1028 04:45:43.687814 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Oct 28 04:45:44.688821 kubelet[1855]: E1028 04:45:44.688768 1855 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"