May 9 00:10:56.923487 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 9 00:10:56.923510 kernel: Linux version 6.6.89-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Thu May 8 22:43:24 -00 2025 May 9 00:10:56.923520 kernel: KASLR enabled May 9 00:10:56.923526 kernel: efi: EFI v2.7 by EDK II May 9 00:10:56.923532 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdba86018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 May 9 00:10:56.923537 kernel: random: crng init done May 9 00:10:56.923545 kernel: ACPI: Early table checksum verification disabled May 9 00:10:56.923551 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) May 9 00:10:56.923557 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) May 9 00:10:56.923564 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:10:56.923570 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:10:56.923576 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:10:56.923582 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:10:56.923588 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:10:56.923595 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:10:56.923603 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:10:56.923610 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:10:56.923616 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 9 00:10:56.923622 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 9 00:10:56.923628 kernel: NUMA: Failed to initialise from firmware May 9 00:10:56.923635 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 9 00:10:56.923641 kernel: NUMA: NODE_DATA [mem 0xdc95a800-0xdc95ffff] May 9 00:10:56.923647 kernel: Zone ranges: May 9 00:10:56.923654 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 9 00:10:56.923660 kernel: DMA32 empty May 9 00:10:56.923668 kernel: Normal empty May 9 00:10:56.923674 kernel: Movable zone start for each node May 9 00:10:56.923680 kernel: Early memory node ranges May 9 00:10:56.923687 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] May 9 00:10:56.923693 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] May 9 00:10:56.923699 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] May 9 00:10:56.923705 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] May 9 00:10:56.923712 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] May 9 00:10:56.923718 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] May 9 00:10:56.923725 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] May 9 00:10:56.923744 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 9 00:10:56.923751 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 9 00:10:56.923759 kernel: psci: probing for conduit method from ACPI. May 9 00:10:56.923765 kernel: psci: PSCIv1.1 detected in firmware. May 9 00:10:56.923772 kernel: psci: Using standard PSCI v0.2 function IDs May 9 00:10:56.923781 kernel: psci: Trusted OS migration not required May 9 00:10:56.923788 kernel: psci: SMC Calling Convention v1.1 May 9 00:10:56.923794 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 9 00:10:56.923803 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 May 9 00:10:56.923809 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 May 9 00:10:56.923816 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 9 00:10:56.923823 kernel: Detected PIPT I-cache on CPU0 May 9 00:10:56.923830 kernel: CPU features: detected: GIC system register CPU interface May 9 00:10:56.923836 kernel: CPU features: detected: Hardware dirty bit management May 9 00:10:56.923843 kernel: CPU features: detected: Spectre-v4 May 9 00:10:56.923850 kernel: CPU features: detected: Spectre-BHB May 9 00:10:56.923857 kernel: CPU features: kernel page table isolation forced ON by KASLR May 9 00:10:56.923864 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 9 00:10:56.923872 kernel: CPU features: detected: ARM erratum 1418040 May 9 00:10:56.923878 kernel: CPU features: detected: SSBS not fully self-synchronizing May 9 00:10:56.923885 kernel: alternatives: applying boot alternatives May 9 00:10:56.923893 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=8e29bd932c31237847976018676f554a4d09fa105e08b3bc01bcbb09708aefd3 May 9 00:10:56.923900 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 9 00:10:56.923907 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 9 00:10:56.923925 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 9 00:10:56.923936 kernel: Fallback order for Node 0: 0 May 9 00:10:56.923943 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 May 9 00:10:56.923950 kernel: Policy zone: DMA May 9 00:10:56.923956 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 9 00:10:56.923966 kernel: software IO TLB: area num 4. May 9 00:10:56.923973 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) May 9 00:10:56.923980 kernel: Memory: 2386412K/2572288K available (10304K kernel code, 2186K rwdata, 8104K rodata, 39424K init, 897K bss, 185876K reserved, 0K cma-reserved) May 9 00:10:56.923987 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 9 00:10:56.923994 kernel: rcu: Preemptible hierarchical RCU implementation. May 9 00:10:56.924001 kernel: rcu: RCU event tracing is enabled. May 9 00:10:56.924009 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 9 00:10:56.924016 kernel: Trampoline variant of Tasks RCU enabled. May 9 00:10:56.924023 kernel: Tracing variant of Tasks RCU enabled. May 9 00:10:56.924030 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 9 00:10:56.924036 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 9 00:10:56.924043 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 9 00:10:56.924051 kernel: GICv3: 256 SPIs implemented May 9 00:10:56.924058 kernel: GICv3: 0 Extended SPIs implemented May 9 00:10:56.924065 kernel: Root IRQ handler: gic_handle_irq May 9 00:10:56.924072 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 9 00:10:56.924078 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 9 00:10:56.924085 kernel: ITS [mem 0x08080000-0x0809ffff] May 9 00:10:56.924092 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) May 9 00:10:56.924099 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) May 9 00:10:56.924106 kernel: GICv3: using LPI property table @0x00000000400f0000 May 9 00:10:56.924112 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 May 9 00:10:56.924119 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 9 00:10:56.924127 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 9 00:10:56.924134 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 9 00:10:56.924141 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 9 00:10:56.924148 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 9 00:10:56.924155 kernel: arm-pv: using stolen time PV May 9 00:10:56.924162 kernel: Console: colour dummy device 80x25 May 9 00:10:56.924169 kernel: ACPI: Core revision 20230628 May 9 00:10:56.924176 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 9 00:10:56.924183 kernel: pid_max: default: 32768 minimum: 301 May 9 00:10:56.924190 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 9 00:10:56.924198 kernel: landlock: Up and running. May 9 00:10:56.924204 kernel: SELinux: Initializing. May 9 00:10:56.924211 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 9 00:10:56.924218 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 9 00:10:56.924225 kernel: ACPI PPTT: PPTT table found, but unable to locate core 3 (3) May 9 00:10:56.924232 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 9 00:10:56.924239 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 9 00:10:56.924246 kernel: rcu: Hierarchical SRCU implementation. May 9 00:10:56.924253 kernel: rcu: Max phase no-delay instances is 400. May 9 00:10:56.924261 kernel: Platform MSI: ITS@0x8080000 domain created May 9 00:10:56.924268 kernel: PCI/MSI: ITS@0x8080000 domain created May 9 00:10:56.924275 kernel: Remapping and enabling EFI services. May 9 00:10:56.924282 kernel: smp: Bringing up secondary CPUs ... May 9 00:10:56.924289 kernel: Detected PIPT I-cache on CPU1 May 9 00:10:56.924296 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 9 00:10:56.924303 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 May 9 00:10:56.924310 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 9 00:10:56.924317 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 9 00:10:56.924326 kernel: Detected PIPT I-cache on CPU2 May 9 00:10:56.924333 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 9 00:10:56.924340 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 May 9 00:10:56.924352 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 9 00:10:56.924361 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 9 00:10:56.924368 kernel: Detected PIPT I-cache on CPU3 May 9 00:10:56.924375 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 9 00:10:56.924383 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 May 9 00:10:56.924390 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 9 00:10:56.924397 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 9 00:10:56.924404 kernel: smp: Brought up 1 node, 4 CPUs May 9 00:10:56.924413 kernel: SMP: Total of 4 processors activated. May 9 00:10:56.924420 kernel: CPU features: detected: 32-bit EL0 Support May 9 00:10:56.924428 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 9 00:10:56.924435 kernel: CPU features: detected: Common not Private translations May 9 00:10:56.924443 kernel: CPU features: detected: CRC32 instructions May 9 00:10:56.924450 kernel: CPU features: detected: Enhanced Virtualization Traps May 9 00:10:56.924458 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 9 00:10:56.924466 kernel: CPU features: detected: LSE atomic instructions May 9 00:10:56.924473 kernel: CPU features: detected: Privileged Access Never May 9 00:10:56.924480 kernel: CPU features: detected: RAS Extension Support May 9 00:10:56.924487 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 9 00:10:56.924494 kernel: CPU: All CPU(s) started at EL1 May 9 00:10:56.924502 kernel: alternatives: applying system-wide alternatives May 9 00:10:56.924509 kernel: devtmpfs: initialized May 9 00:10:56.924517 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 9 00:10:56.924526 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 9 00:10:56.924533 kernel: pinctrl core: initialized pinctrl subsystem May 9 00:10:56.924540 kernel: SMBIOS 3.0.0 present. May 9 00:10:56.924548 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 May 9 00:10:56.924555 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 9 00:10:56.924562 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 9 00:10:56.924570 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 9 00:10:56.924578 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 9 00:10:56.924585 kernel: audit: initializing netlink subsys (disabled) May 9 00:10:56.924594 kernel: audit: type=2000 audit(0.026:1): state=initialized audit_enabled=0 res=1 May 9 00:10:56.924601 kernel: thermal_sys: Registered thermal governor 'step_wise' May 9 00:10:56.924608 kernel: cpuidle: using governor menu May 9 00:10:56.924615 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 9 00:10:56.924622 kernel: ASID allocator initialised with 32768 entries May 9 00:10:56.924630 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 9 00:10:56.924637 kernel: Serial: AMBA PL011 UART driver May 9 00:10:56.924644 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 9 00:10:56.924651 kernel: Modules: 0 pages in range for non-PLT usage May 9 00:10:56.924660 kernel: Modules: 509008 pages in range for PLT usage May 9 00:10:56.924667 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 9 00:10:56.924674 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 9 00:10:56.924682 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 9 00:10:56.924689 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 9 00:10:56.924696 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 9 00:10:56.924704 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 9 00:10:56.924711 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 9 00:10:56.924718 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 9 00:10:56.924725 kernel: ACPI: Added _OSI(Module Device) May 9 00:10:56.924740 kernel: ACPI: Added _OSI(Processor Device) May 9 00:10:56.924748 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 9 00:10:56.924755 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 9 00:10:56.924763 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 9 00:10:56.924770 kernel: ACPI: Interpreter enabled May 9 00:10:56.924777 kernel: ACPI: Using GIC for interrupt routing May 9 00:10:56.924784 kernel: ACPI: MCFG table detected, 1 entries May 9 00:10:56.924792 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 9 00:10:56.924799 kernel: printk: console [ttyAMA0] enabled May 9 00:10:56.924808 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 9 00:10:56.925036 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 9 00:10:56.925119 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 9 00:10:56.925185 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 9 00:10:56.925251 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 9 00:10:56.925314 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 9 00:10:56.925324 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 9 00:10:56.925335 kernel: PCI host bridge to bus 0000:00 May 9 00:10:56.925406 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 9 00:10:56.925468 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 9 00:10:56.925528 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 9 00:10:56.925586 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 9 00:10:56.925667 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 May 9 00:10:56.925764 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 May 9 00:10:56.925838 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] May 9 00:10:56.925906 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] May 9 00:10:56.925990 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] May 9 00:10:56.926060 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] May 9 00:10:56.926127 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] May 9 00:10:56.926249 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] May 9 00:10:56.926335 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 9 00:10:56.926397 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 9 00:10:56.926474 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 9 00:10:56.926485 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 9 00:10:56.926492 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 9 00:10:56.926500 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 9 00:10:56.926507 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 9 00:10:56.926515 kernel: iommu: Default domain type: Translated May 9 00:10:56.926525 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 9 00:10:56.926533 kernel: efivars: Registered efivars operations May 9 00:10:56.926540 kernel: vgaarb: loaded May 9 00:10:56.926548 kernel: clocksource: Switched to clocksource arch_sys_counter May 9 00:10:56.926555 kernel: VFS: Disk quotas dquot_6.6.0 May 9 00:10:56.926564 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 9 00:10:56.926593 kernel: pnp: PnP ACPI init May 9 00:10:56.926696 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 9 00:10:56.926708 kernel: pnp: PnP ACPI: found 1 devices May 9 00:10:56.926719 kernel: NET: Registered PF_INET protocol family May 9 00:10:56.926726 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 9 00:10:56.926741 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 9 00:10:56.926749 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 9 00:10:56.926757 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 9 00:10:56.926764 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 9 00:10:56.926772 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 9 00:10:56.926779 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 9 00:10:56.926790 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 9 00:10:56.926797 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 9 00:10:56.926804 kernel: PCI: CLS 0 bytes, default 64 May 9 00:10:56.926812 kernel: kvm [1]: HYP mode not available May 9 00:10:56.926819 kernel: Initialise system trusted keyrings May 9 00:10:56.926826 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 9 00:10:56.926834 kernel: Key type asymmetric registered May 9 00:10:56.926841 kernel: Asymmetric key parser 'x509' registered May 9 00:10:56.926848 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 9 00:10:56.926856 kernel: io scheduler mq-deadline registered May 9 00:10:56.926864 kernel: io scheduler kyber registered May 9 00:10:56.926872 kernel: io scheduler bfq registered May 9 00:10:56.926879 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 9 00:10:56.926886 kernel: ACPI: button: Power Button [PWRB] May 9 00:10:56.926894 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 9 00:10:56.927013 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 9 00:10:56.927027 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 9 00:10:56.927034 kernel: thunder_xcv, ver 1.0 May 9 00:10:56.927042 kernel: thunder_bgx, ver 1.0 May 9 00:10:56.927052 kernel: nicpf, ver 1.0 May 9 00:10:56.927060 kernel: nicvf, ver 1.0 May 9 00:10:56.927139 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 9 00:10:56.927204 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-09T00:10:56 UTC (1746749456) May 9 00:10:56.927214 kernel: hid: raw HID events driver (C) Jiri Kosina May 9 00:10:56.927222 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available May 9 00:10:56.927229 kernel: watchdog: Delayed init of the lockup detector failed: -19 May 9 00:10:56.927237 kernel: watchdog: Hard watchdog permanently disabled May 9 00:10:56.927246 kernel: NET: Registered PF_INET6 protocol family May 9 00:10:56.927254 kernel: Segment Routing with IPv6 May 9 00:10:56.927261 kernel: In-situ OAM (IOAM) with IPv6 May 9 00:10:56.927269 kernel: NET: Registered PF_PACKET protocol family May 9 00:10:56.927276 kernel: Key type dns_resolver registered May 9 00:10:56.927283 kernel: registered taskstats version 1 May 9 00:10:56.927290 kernel: Loading compiled-in X.509 certificates May 9 00:10:56.927298 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: 7944e0e0bec5e8cad487856da19569eba337cea0' May 9 00:10:56.927305 kernel: Key type .fscrypt registered May 9 00:10:56.927314 kernel: Key type fscrypt-provisioning registered May 9 00:10:56.927322 kernel: ima: No TPM chip found, activating TPM-bypass! May 9 00:10:56.927329 kernel: ima: Allocated hash algorithm: sha1 May 9 00:10:56.927336 kernel: ima: No architecture policies found May 9 00:10:56.927344 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 9 00:10:56.927351 kernel: clk: Disabling unused clocks May 9 00:10:56.927358 kernel: Freeing unused kernel memory: 39424K May 9 00:10:56.927366 kernel: Run /init as init process May 9 00:10:56.927373 kernel: with arguments: May 9 00:10:56.927382 kernel: /init May 9 00:10:56.927389 kernel: with environment: May 9 00:10:56.927396 kernel: HOME=/ May 9 00:10:56.927403 kernel: TERM=linux May 9 00:10:56.927410 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 9 00:10:56.927420 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) May 9 00:10:56.927429 systemd[1]: Detected virtualization kvm. May 9 00:10:56.927439 systemd[1]: Detected architecture arm64. May 9 00:10:56.927446 systemd[1]: Running in initrd. May 9 00:10:56.927454 systemd[1]: No hostname configured, using default hostname. May 9 00:10:56.927462 systemd[1]: Hostname set to . May 9 00:10:56.927470 systemd[1]: Initializing machine ID from VM UUID. May 9 00:10:56.927477 systemd[1]: Queued start job for default target initrd.target. May 9 00:10:56.927486 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 9 00:10:56.927493 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 9 00:10:56.927504 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 9 00:10:56.927512 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 9 00:10:56.927520 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 9 00:10:56.927528 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 9 00:10:56.927537 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 9 00:10:56.927546 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 9 00:10:56.927554 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 9 00:10:56.927563 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 9 00:10:56.927571 systemd[1]: Reached target paths.target - Path Units. May 9 00:10:56.927579 systemd[1]: Reached target slices.target - Slice Units. May 9 00:10:56.927587 systemd[1]: Reached target swap.target - Swaps. May 9 00:10:56.927595 systemd[1]: Reached target timers.target - Timer Units. May 9 00:10:56.927603 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 9 00:10:56.927611 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 9 00:10:56.927619 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 9 00:10:56.927627 systemd[1]: Listening on systemd-journald.socket - Journal Socket. May 9 00:10:56.927636 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 9 00:10:56.927644 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 9 00:10:56.927652 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 9 00:10:56.927660 systemd[1]: Reached target sockets.target - Socket Units. May 9 00:10:56.927668 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 9 00:10:56.927676 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 9 00:10:56.927688 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 9 00:10:56.927696 systemd[1]: Starting systemd-fsck-usr.service... May 9 00:10:56.927706 systemd[1]: Starting systemd-journald.service - Journal Service... May 9 00:10:56.927714 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 9 00:10:56.927722 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 9 00:10:56.927739 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 9 00:10:56.927748 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 9 00:10:56.927756 systemd[1]: Finished systemd-fsck-usr.service. May 9 00:10:56.927766 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 9 00:10:56.927799 systemd-journald[238]: Collecting audit messages is disabled. May 9 00:10:56.927819 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:10:56.927831 systemd-journald[238]: Journal started May 9 00:10:56.927851 systemd-journald[238]: Runtime Journal (/run/log/journal/68ce020499c1438eacc7f3c7ddb3440f) is 5.9M, max 47.3M, 41.4M free. May 9 00:10:56.937047 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 9 00:10:56.937090 kernel: Bridge firewalling registered May 9 00:10:56.918367 systemd-modules-load[239]: Inserted module 'overlay' May 9 00:10:56.934214 systemd-modules-load[239]: Inserted module 'br_netfilter' May 9 00:10:56.941569 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 9 00:10:56.941595 systemd[1]: Started systemd-journald.service - Journal Service. May 9 00:10:56.945070 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 9 00:10:56.949981 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 9 00:10:56.951538 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 00:10:56.960109 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 9 00:10:56.961886 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 9 00:10:56.965134 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 9 00:10:56.967826 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 9 00:10:56.976404 dracut-cmdline[264]: dracut-dracut-053 May 9 00:10:56.977322 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 9 00:10:56.980112 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 9 00:10:56.981394 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 9 00:10:56.984398 dracut-cmdline[264]: Using kernel command line parameters: rd.driver.pre=btrfs 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=8e29bd932c31237847976018676f554a4d09fa105e08b3bc01bcbb09708aefd3 May 9 00:10:56.998136 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 9 00:10:57.020706 systemd-resolved[298]: Positive Trust Anchors: May 9 00:10:57.020736 systemd-resolved[298]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 9 00:10:57.020768 systemd-resolved[298]: 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 May 9 00:10:57.027187 systemd-resolved[298]: Defaulting to hostname 'linux'. May 9 00:10:57.028286 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 9 00:10:57.029397 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 9 00:10:57.056963 kernel: SCSI subsystem initialized May 9 00:10:57.061929 kernel: Loading iSCSI transport class v2.0-870. May 9 00:10:57.068935 kernel: iscsi: registered transport (tcp) May 9 00:10:57.082205 kernel: iscsi: registered transport (qla4xxx) May 9 00:10:57.082276 kernel: QLogic iSCSI HBA Driver May 9 00:10:57.127321 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 9 00:10:57.137161 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 9 00:10:57.155369 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 9 00:10:57.155454 kernel: device-mapper: uevent: version 1.0.3 May 9 00:10:57.155473 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 9 00:10:57.206966 kernel: raid6: neonx8 gen() 15776 MB/s May 9 00:10:57.223936 kernel: raid6: neonx4 gen() 15657 MB/s May 9 00:10:57.240930 kernel: raid6: neonx2 gen() 13245 MB/s May 9 00:10:57.257933 kernel: raid6: neonx1 gen() 10489 MB/s May 9 00:10:57.274940 kernel: raid6: int64x8 gen() 6968 MB/s May 9 00:10:57.291930 kernel: raid6: int64x4 gen() 7352 MB/s May 9 00:10:57.308931 kernel: raid6: int64x2 gen() 6133 MB/s May 9 00:10:57.325935 kernel: raid6: int64x1 gen() 5056 MB/s May 9 00:10:57.325956 kernel: raid6: using algorithm neonx8 gen() 15776 MB/s May 9 00:10:57.342949 kernel: raid6: .... xor() 11928 MB/s, rmw enabled May 9 00:10:57.342982 kernel: raid6: using neon recovery algorithm May 9 00:10:57.347943 kernel: xor: measuring software checksum speed May 9 00:10:57.347968 kernel: 8regs : 19802 MB/sec May 9 00:10:57.348985 kernel: 32regs : 19646 MB/sec May 9 00:10:57.348998 kernel: arm64_neon : 27105 MB/sec May 9 00:10:57.349008 kernel: xor: using function: arm64_neon (27105 MB/sec) May 9 00:10:57.403962 kernel: Btrfs loaded, zoned=no, fsverity=no May 9 00:10:57.416682 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 9 00:10:57.429144 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 9 00:10:57.440985 systemd-udevd[463]: Using default interface naming scheme 'v255'. May 9 00:10:57.444374 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 9 00:10:57.463166 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 9 00:10:57.475252 dracut-pre-trigger[471]: rd.md=0: removing MD RAID activation May 9 00:10:57.504865 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 9 00:10:57.517091 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 9 00:10:57.559249 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 9 00:10:57.570530 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 9 00:10:57.584192 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 9 00:10:57.587455 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 9 00:10:57.588963 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 9 00:10:57.591015 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 9 00:10:57.601153 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 9 00:10:57.607940 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues May 9 00:10:57.617813 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 9 00:10:57.619342 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 9 00:10:57.622859 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 9 00:10:57.622887 kernel: GPT:9289727 != 19775487 May 9 00:10:57.622897 kernel: GPT:Alternate GPT header not at the end of the disk. May 9 00:10:57.622940 kernel: GPT:9289727 != 19775487 May 9 00:10:57.622952 kernel: GPT: Use GNU Parted to correct GPT errors. May 9 00:10:57.622963 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 00:10:57.619467 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 00:10:57.625054 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 9 00:10:57.627020 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 9 00:10:57.627196 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:10:57.629427 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 9 00:10:57.636934 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (508) May 9 00:10:57.640935 kernel: BTRFS: device fsid 9a510efc-c158-4845-bfb8-279f8b20070f devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (524) May 9 00:10:57.643196 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 9 00:10:57.645412 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 9 00:10:57.656618 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 9 00:10:57.657929 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:10:57.671219 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 9 00:10:57.675746 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 9 00:10:57.679517 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 9 00:10:57.680481 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 9 00:10:57.689079 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 9 00:10:57.691013 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 9 00:10:57.698309 disk-uuid[551]: Primary Header is updated. May 9 00:10:57.698309 disk-uuid[551]: Secondary Entries is updated. May 9 00:10:57.698309 disk-uuid[551]: Secondary Header is updated. May 9 00:10:57.704948 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 00:10:57.720693 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 00:10:57.724928 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 00:10:58.722637 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 9 00:10:58.722721 disk-uuid[552]: The operation has completed successfully. May 9 00:10:58.747099 systemd[1]: disk-uuid.service: Deactivated successfully. May 9 00:10:58.747200 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 9 00:10:58.763145 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 9 00:10:58.766285 sh[574]: Success May 9 00:10:58.779990 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 9 00:10:58.828404 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 9 00:10:58.830158 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 9 00:10:58.830935 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 9 00:10:58.842604 kernel: BTRFS info (device dm-0): first mount of filesystem 9a510efc-c158-4845-bfb8-279f8b20070f May 9 00:10:58.842645 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 9 00:10:58.842656 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 9 00:10:58.842667 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 9 00:10:58.843238 kernel: BTRFS info (device dm-0): using free space tree May 9 00:10:58.847765 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 9 00:10:58.848648 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 9 00:10:58.849394 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 9 00:10:58.852047 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 9 00:10:58.864539 kernel: BTRFS info (device vda6): first mount of filesystem 9e7e8c5a-aee3-4b23-ab26-fabdbd68734c May 9 00:10:58.864602 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 9 00:10:58.864614 kernel: BTRFS info (device vda6): using free space tree May 9 00:10:58.867960 kernel: BTRFS info (device vda6): auto enabling async discard May 9 00:10:58.875662 systemd[1]: mnt-oem.mount: Deactivated successfully. May 9 00:10:58.877319 kernel: BTRFS info (device vda6): last unmount of filesystem 9e7e8c5a-aee3-4b23-ab26-fabdbd68734c May 9 00:10:58.882426 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 9 00:10:58.890121 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 9 00:10:58.955332 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 9 00:10:58.967145 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 9 00:10:58.986024 systemd-networkd[765]: lo: Link UP May 9 00:10:58.986034 systemd-networkd[765]: lo: Gained carrier May 9 00:10:58.986711 systemd-networkd[765]: Enumeration completed May 9 00:10:58.986888 systemd[1]: Started systemd-networkd.service - Network Configuration. May 9 00:10:58.987406 systemd-networkd[765]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 00:10:58.987409 systemd-networkd[765]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 9 00:10:58.988300 systemd[1]: Reached target network.target - Network. May 9 00:10:58.988507 systemd-networkd[765]: eth0: Link UP May 9 00:10:58.988510 systemd-networkd[765]: eth0: Gained carrier May 9 00:10:58.995779 ignition[672]: Ignition 2.19.0 May 9 00:10:58.988518 systemd-networkd[765]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 00:10:58.995786 ignition[672]: Stage: fetch-offline May 9 00:10:58.995820 ignition[672]: no configs at "/usr/lib/ignition/base.d" May 9 00:10:58.995828 ignition[672]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:10:58.995987 ignition[672]: parsed url from cmdline: "" May 9 00:10:58.995990 ignition[672]: no config URL provided May 9 00:10:58.995994 ignition[672]: reading system config file "/usr/lib/ignition/user.ign" May 9 00:10:59.002969 systemd-networkd[765]: eth0: DHCPv4 address 10.0.0.80/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 9 00:10:58.996001 ignition[672]: no config at "/usr/lib/ignition/user.ign" May 9 00:10:58.996025 ignition[672]: op(1): [started] loading QEMU firmware config module May 9 00:10:58.996030 ignition[672]: op(1): executing: "modprobe" "qemu_fw_cfg" May 9 00:10:59.003710 ignition[672]: op(1): [finished] loading QEMU firmware config module May 9 00:10:59.011753 ignition[672]: parsing config with SHA512: c4738f6312ccb806a6582a1600286c72c19e05f5a626fbd2b94ee52e4781093260dc9add6e2a1232bd1e2257480605de731972b33c65e1e5a47d60e9e97da92e May 9 00:10:59.014780 unknown[672]: fetched base config from "system" May 9 00:10:59.014791 unknown[672]: fetched user config from "qemu" May 9 00:10:59.015075 ignition[672]: fetch-offline: fetch-offline passed May 9 00:10:59.016530 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 9 00:10:59.015137 ignition[672]: Ignition finished successfully May 9 00:10:59.018373 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 9 00:10:59.023066 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 9 00:10:59.033622 ignition[772]: Ignition 2.19.0 May 9 00:10:59.033633 ignition[772]: Stage: kargs May 9 00:10:59.033820 ignition[772]: no configs at "/usr/lib/ignition/base.d" May 9 00:10:59.033830 ignition[772]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:10:59.034537 ignition[772]: kargs: kargs passed May 9 00:10:59.034582 ignition[772]: Ignition finished successfully May 9 00:10:59.038287 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 9 00:10:59.052079 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 9 00:10:59.062906 ignition[780]: Ignition 2.19.0 May 9 00:10:59.062963 ignition[780]: Stage: disks May 9 00:10:59.063151 ignition[780]: no configs at "/usr/lib/ignition/base.d" May 9 00:10:59.063162 ignition[780]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:10:59.063863 ignition[780]: disks: disks passed May 9 00:10:59.063946 ignition[780]: Ignition finished successfully May 9 00:10:59.066416 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 9 00:10:59.067640 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 9 00:10:59.068990 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 9 00:10:59.070157 systemd[1]: Reached target local-fs.target - Local File Systems. May 9 00:10:59.071634 systemd[1]: Reached target sysinit.target - System Initialization. May 9 00:10:59.073264 systemd[1]: Reached target basic.target - Basic System. May 9 00:10:59.083096 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 9 00:10:59.097356 systemd-resolved[298]: Detected conflict on linux IN A 10.0.0.80 May 9 00:10:59.097371 systemd-resolved[298]: Hostname conflict, changing published hostname from 'linux' to 'linux9'. May 9 00:10:59.100093 systemd-fsck[791]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 9 00:10:59.103367 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 9 00:10:59.111059 systemd[1]: Mounting sysroot.mount - /sysroot... May 9 00:10:59.159930 kernel: EXT4-fs (vda9): mounted filesystem 1a8c7c5d-87ec-4bc4-aa01-1ebc1d3c20e7 r/w with ordered data mode. Quota mode: none. May 9 00:10:59.160673 systemd[1]: Mounted sysroot.mount - /sysroot. May 9 00:10:59.161794 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 9 00:10:59.175077 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 9 00:10:59.176948 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 9 00:10:59.178277 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 9 00:10:59.178322 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 9 00:10:59.184619 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (799) May 9 00:10:59.184647 kernel: BTRFS info (device vda6): first mount of filesystem 9e7e8c5a-aee3-4b23-ab26-fabdbd68734c May 9 00:10:59.178347 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 9 00:10:59.188122 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 9 00:10:59.188164 kernel: BTRFS info (device vda6): using free space tree May 9 00:10:59.185627 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 9 00:10:59.188253 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 9 00:10:59.191934 kernel: BTRFS info (device vda6): auto enabling async discard May 9 00:10:59.193522 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 9 00:10:59.236825 initrd-setup-root[823]: cut: /sysroot/etc/passwd: No such file or directory May 9 00:10:59.241128 initrd-setup-root[830]: cut: /sysroot/etc/group: No such file or directory May 9 00:10:59.244506 initrd-setup-root[837]: cut: /sysroot/etc/shadow: No such file or directory May 9 00:10:59.248262 initrd-setup-root[844]: cut: /sysroot/etc/gshadow: No such file or directory May 9 00:10:59.318812 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 9 00:10:59.331047 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 9 00:10:59.333616 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 9 00:10:59.337939 kernel: BTRFS info (device vda6): last unmount of filesystem 9e7e8c5a-aee3-4b23-ab26-fabdbd68734c May 9 00:10:59.355072 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 9 00:10:59.356493 ignition[913]: INFO : Ignition 2.19.0 May 9 00:10:59.356493 ignition[913]: INFO : Stage: mount May 9 00:10:59.356493 ignition[913]: INFO : no configs at "/usr/lib/ignition/base.d" May 9 00:10:59.356493 ignition[913]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:10:59.359557 ignition[913]: INFO : mount: mount passed May 9 00:10:59.359557 ignition[913]: INFO : Ignition finished successfully May 9 00:10:59.358815 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 9 00:10:59.371069 systemd[1]: Starting ignition-files.service - Ignition (files)... May 9 00:10:59.841308 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 9 00:10:59.854199 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 9 00:10:59.859928 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (926) May 9 00:10:59.861579 kernel: BTRFS info (device vda6): first mount of filesystem 9e7e8c5a-aee3-4b23-ab26-fabdbd68734c May 9 00:10:59.861600 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 9 00:10:59.861611 kernel: BTRFS info (device vda6): using free space tree May 9 00:10:59.863924 kernel: BTRFS info (device vda6): auto enabling async discard May 9 00:10:59.865278 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 9 00:10:59.882048 ignition[943]: INFO : Ignition 2.19.0 May 9 00:10:59.882048 ignition[943]: INFO : Stage: files May 9 00:10:59.883750 ignition[943]: INFO : no configs at "/usr/lib/ignition/base.d" May 9 00:10:59.883750 ignition[943]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:10:59.883750 ignition[943]: DEBUG : files: compiled without relabeling support, skipping May 9 00:10:59.887231 ignition[943]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 9 00:10:59.887231 ignition[943]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 9 00:10:59.887231 ignition[943]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 9 00:10:59.887231 ignition[943]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 9 00:10:59.887231 ignition[943]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 9 00:10:59.886316 unknown[943]: wrote ssh authorized keys file for user: core May 9 00:10:59.894789 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 9 00:10:59.894789 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 9 00:10:59.894789 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 9 00:10:59.894789 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 9 00:10:59.894789 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 9 00:10:59.894789 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 9 00:10:59.894789 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 9 00:10:59.894789 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.32.0-arm64.raw: attempt #1 May 9 00:11:00.152076 systemd-networkd[765]: eth0: Gained IPv6LL May 9 00:11:00.904507 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 9 00:11:01.258208 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 9 00:11:01.258208 ignition[943]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 9 00:11:01.261772 ignition[943]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 9 00:11:01.261772 ignition[943]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 9 00:11:01.261772 ignition[943]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 9 00:11:01.261772 ignition[943]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" May 9 00:11:01.281333 ignition[943]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" May 9 00:11:01.285403 ignition[943]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 9 00:11:01.288027 ignition[943]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" May 9 00:11:01.288027 ignition[943]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 9 00:11:01.288027 ignition[943]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 9 00:11:01.288027 ignition[943]: INFO : files: files passed May 9 00:11:01.288027 ignition[943]: INFO : Ignition finished successfully May 9 00:11:01.288938 systemd[1]: Finished ignition-files.service - Ignition (files). May 9 00:11:01.300096 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 9 00:11:01.303717 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 9 00:11:01.305805 systemd[1]: ignition-quench.service: Deactivated successfully. May 9 00:11:01.305921 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 9 00:11:01.310475 initrd-setup-root-after-ignition[972]: grep: /sysroot/oem/oem-release: No such file or directory May 9 00:11:01.314605 initrd-setup-root-after-ignition[974]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 9 00:11:01.314605 initrd-setup-root-after-ignition[974]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 9 00:11:01.318204 initrd-setup-root-after-ignition[978]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 9 00:11:01.318968 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 9 00:11:01.321098 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 9 00:11:01.331091 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 9 00:11:01.353852 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 9 00:11:01.354006 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 9 00:11:01.356326 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 9 00:11:01.358100 systemd[1]: Reached target initrd.target - Initrd Default Target. May 9 00:11:01.359863 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 9 00:11:01.360738 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 9 00:11:01.377175 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 9 00:11:01.385199 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 9 00:11:01.393672 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 9 00:11:01.395002 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 9 00:11:01.397229 systemd[1]: Stopped target timers.target - Timer Units. May 9 00:11:01.399043 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 9 00:11:01.399170 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 9 00:11:01.401782 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 9 00:11:01.403873 systemd[1]: Stopped target basic.target - Basic System. May 9 00:11:01.405638 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 9 00:11:01.407428 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 9 00:11:01.409462 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 9 00:11:01.411521 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 9 00:11:01.413483 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 9 00:11:01.415333 systemd[1]: Stopped target sysinit.target - System Initialization. May 9 00:11:01.417380 systemd[1]: Stopped target local-fs.target - Local File Systems. May 9 00:11:01.419163 systemd[1]: Stopped target swap.target - Swaps. May 9 00:11:01.420760 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 9 00:11:01.420894 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 9 00:11:01.423367 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 9 00:11:01.425401 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 9 00:11:01.427394 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 9 00:11:01.431983 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 9 00:11:01.433282 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 9 00:11:01.433413 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 9 00:11:01.436427 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 9 00:11:01.436552 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 9 00:11:01.438691 systemd[1]: Stopped target paths.target - Path Units. May 9 00:11:01.440358 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 9 00:11:01.441035 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 9 00:11:01.442471 systemd[1]: Stopped target slices.target - Slice Units. May 9 00:11:01.443809 systemd[1]: Stopped target sockets.target - Socket Units. May 9 00:11:01.445842 systemd[1]: iscsid.socket: Deactivated successfully. May 9 00:11:01.445950 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 9 00:11:01.447921 systemd[1]: iscsiuio.socket: Deactivated successfully. May 9 00:11:01.448005 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 9 00:11:01.449543 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 9 00:11:01.449659 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 9 00:11:01.451189 systemd[1]: ignition-files.service: Deactivated successfully. May 9 00:11:01.451294 systemd[1]: Stopped ignition-files.service - Ignition (files). May 9 00:11:01.463189 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 9 00:11:01.464815 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 9 00:11:01.465708 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 9 00:11:01.465854 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 9 00:11:01.467613 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 9 00:11:01.467720 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 9 00:11:01.473323 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 9 00:11:01.474388 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 9 00:11:01.477976 ignition[998]: INFO : Ignition 2.19.0 May 9 00:11:01.477976 ignition[998]: INFO : Stage: umount May 9 00:11:01.477976 ignition[998]: INFO : no configs at "/usr/lib/ignition/base.d" May 9 00:11:01.477976 ignition[998]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 9 00:11:01.482797 ignition[998]: INFO : umount: umount passed May 9 00:11:01.482797 ignition[998]: INFO : Ignition finished successfully May 9 00:11:01.481368 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 9 00:11:01.481882 systemd[1]: ignition-mount.service: Deactivated successfully. May 9 00:11:01.481992 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 9 00:11:01.484249 systemd[1]: Stopped target network.target - Network. May 9 00:11:01.485302 systemd[1]: ignition-disks.service: Deactivated successfully. May 9 00:11:01.485384 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 9 00:11:01.486988 systemd[1]: ignition-kargs.service: Deactivated successfully. May 9 00:11:01.487038 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 9 00:11:01.488663 systemd[1]: ignition-setup.service: Deactivated successfully. May 9 00:11:01.488708 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 9 00:11:01.490447 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 9 00:11:01.490503 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 9 00:11:01.492441 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 9 00:11:01.494057 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 9 00:11:01.496068 systemd[1]: sysroot-boot.service: Deactivated successfully. May 9 00:11:01.496156 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 9 00:11:01.497761 systemd[1]: systemd-resolved.service: Deactivated successfully. May 9 00:11:01.497859 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 9 00:11:01.499995 systemd-networkd[765]: eth0: DHCPv6 lease lost May 9 00:11:01.501133 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 9 00:11:01.501200 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 9 00:11:01.502331 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 9 00:11:01.502380 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 9 00:11:01.504328 systemd[1]: systemd-networkd.service: Deactivated successfully. May 9 00:11:01.504469 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 9 00:11:01.506452 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 9 00:11:01.506509 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 9 00:11:01.515028 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 9 00:11:01.516000 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 9 00:11:01.516073 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 9 00:11:01.517861 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 9 00:11:01.517926 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 9 00:11:01.519471 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 9 00:11:01.519521 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 9 00:11:01.521274 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 9 00:11:01.536946 systemd[1]: network-cleanup.service: Deactivated successfully. May 9 00:11:01.537085 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 9 00:11:01.539189 systemd[1]: systemd-udevd.service: Deactivated successfully. May 9 00:11:01.539977 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 9 00:11:01.542510 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 9 00:11:01.542585 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 9 00:11:01.544100 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 9 00:11:01.544137 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 9 00:11:01.545894 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 9 00:11:01.545963 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 9 00:11:01.548368 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 9 00:11:01.548416 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 9 00:11:01.550839 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 9 00:11:01.550888 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 9 00:11:01.561088 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 9 00:11:01.562185 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 9 00:11:01.562257 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 9 00:11:01.564070 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 9 00:11:01.564112 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 9 00:11:01.565840 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 9 00:11:01.565880 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 9 00:11:01.567826 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 9 00:11:01.567868 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:11:01.570092 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 9 00:11:01.570175 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 9 00:11:01.572264 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 9 00:11:01.574309 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 9 00:11:01.585724 systemd[1]: Switching root. May 9 00:11:01.617047 systemd-journald[238]: Journal stopped May 9 00:11:02.302015 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). May 9 00:11:02.302068 kernel: SELinux: policy capability network_peer_controls=1 May 9 00:11:02.302977 kernel: SELinux: policy capability open_perms=1 May 9 00:11:02.303017 kernel: SELinux: policy capability extended_socket_class=1 May 9 00:11:02.303028 kernel: SELinux: policy capability always_check_network=0 May 9 00:11:02.303038 kernel: SELinux: policy capability cgroup_seclabel=1 May 9 00:11:02.303048 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 9 00:11:02.303058 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 9 00:11:02.303068 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 9 00:11:02.303077 kernel: audit: type=1403 audit(1746749461.752:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 9 00:11:02.303089 systemd[1]: Successfully loaded SELinux policy in 32.074ms. May 9 00:11:02.303109 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.550ms. May 9 00:11:02.303123 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) May 9 00:11:02.303134 systemd[1]: Detected virtualization kvm. May 9 00:11:02.303145 systemd[1]: Detected architecture arm64. May 9 00:11:02.303157 systemd[1]: Detected first boot. May 9 00:11:02.303169 systemd[1]: Initializing machine ID from VM UUID. May 9 00:11:02.303180 zram_generator::config[1044]: No configuration found. May 9 00:11:02.303191 systemd[1]: Populated /etc with preset unit settings. May 9 00:11:02.303202 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 9 00:11:02.303213 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 9 00:11:02.303223 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 9 00:11:02.303235 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 9 00:11:02.303248 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 9 00:11:02.303261 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 9 00:11:02.303271 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 9 00:11:02.303283 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 9 00:11:02.303294 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 9 00:11:02.303305 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 9 00:11:02.303316 systemd[1]: Created slice user.slice - User and Session Slice. May 9 00:11:02.303327 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 9 00:11:02.303338 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 9 00:11:02.303349 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 9 00:11:02.303361 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 9 00:11:02.303373 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 9 00:11:02.303384 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 9 00:11:02.303395 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 9 00:11:02.303406 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 9 00:11:02.303417 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 9 00:11:02.303427 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 9 00:11:02.303437 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 9 00:11:02.303450 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 9 00:11:02.303460 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 9 00:11:02.303471 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 9 00:11:02.303483 systemd[1]: Reached target slices.target - Slice Units. May 9 00:11:02.303494 systemd[1]: Reached target swap.target - Swaps. May 9 00:11:02.303504 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 9 00:11:02.303514 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 9 00:11:02.303525 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 9 00:11:02.303536 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 9 00:11:02.303569 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 9 00:11:02.303582 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 9 00:11:02.303593 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 9 00:11:02.303607 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 9 00:11:02.303617 systemd[1]: Mounting media.mount - External Media Directory... May 9 00:11:02.303628 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 9 00:11:02.303638 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 9 00:11:02.303649 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 9 00:11:02.303660 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). May 9 00:11:02.303672 systemd[1]: Reached target machines.target - Containers. May 9 00:11:02.303684 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 9 00:11:02.303695 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 00:11:02.303705 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 9 00:11:02.303716 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 9 00:11:02.303727 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 00:11:02.303740 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 9 00:11:02.303758 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 00:11:02.303772 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 9 00:11:02.303783 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 00:11:02.303794 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 9 00:11:02.303804 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 9 00:11:02.303815 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 9 00:11:02.303826 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 9 00:11:02.303838 systemd[1]: Stopped systemd-fsck-usr.service. May 9 00:11:02.303849 systemd[1]: Starting systemd-journald.service - Journal Service... May 9 00:11:02.303859 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 9 00:11:02.303872 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 9 00:11:02.303883 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 9 00:11:02.303893 kernel: fuse: init (API version 7.39) May 9 00:11:02.303903 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 9 00:11:02.303922 systemd[1]: verity-setup.service: Deactivated successfully. May 9 00:11:02.303933 systemd[1]: Stopped verity-setup.service. May 9 00:11:02.303943 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 9 00:11:02.303954 kernel: loop: module loaded May 9 00:11:02.303964 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 9 00:11:02.303977 systemd[1]: Mounted media.mount - External Media Directory. May 9 00:11:02.303989 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 9 00:11:02.304000 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 9 00:11:02.304042 systemd-journald[1111]: Collecting audit messages is disabled. May 9 00:11:02.304069 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 9 00:11:02.304080 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 9 00:11:02.304092 systemd-journald[1111]: Journal started May 9 00:11:02.304115 systemd-journald[1111]: Runtime Journal (/run/log/journal/68ce020499c1438eacc7f3c7ddb3440f) is 5.9M, max 47.3M, 41.4M free. May 9 00:11:02.122718 systemd[1]: Queued start job for default target multi-user.target. May 9 00:11:02.137570 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 9 00:11:02.137963 systemd[1]: systemd-journald.service: Deactivated successfully. May 9 00:11:02.308438 systemd[1]: Started systemd-journald.service - Journal Service. May 9 00:11:02.309278 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 9 00:11:02.309856 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 9 00:11:02.311313 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 00:11:02.311477 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 00:11:02.312657 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 00:11:02.312825 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 00:11:02.314042 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 9 00:11:02.314172 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 9 00:11:02.315235 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 00:11:02.315364 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 00:11:02.316461 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 9 00:11:02.317590 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 9 00:11:02.318941 kernel: ACPI: bus type drm_connector registered May 9 00:11:02.319370 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 9 00:11:02.320603 systemd[1]: modprobe@drm.service: Deactivated successfully. May 9 00:11:02.320732 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 9 00:11:02.334010 systemd[1]: Reached target network-pre.target - Preparation for Network. May 9 00:11:02.340001 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 9 00:11:02.342297 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 9 00:11:02.343357 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 9 00:11:02.343409 systemd[1]: Reached target local-fs.target - Local File Systems. May 9 00:11:02.345794 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). May 9 00:11:02.351152 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 9 00:11:02.353287 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 9 00:11:02.354381 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 00:11:02.356090 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 9 00:11:02.358512 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 9 00:11:02.359587 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 9 00:11:02.363113 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 9 00:11:02.367896 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 9 00:11:02.372119 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 9 00:11:02.377135 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 9 00:11:02.383827 systemd-journald[1111]: Time spent on flushing to /var/log/journal/68ce020499c1438eacc7f3c7ddb3440f is 26.986ms for 842 entries. May 9 00:11:02.383827 systemd-journald[1111]: System Journal (/var/log/journal/68ce020499c1438eacc7f3c7ddb3440f) is 8.0M, max 195.6M, 187.6M free. May 9 00:11:02.417473 systemd-journald[1111]: Received client request to flush runtime journal. May 9 00:11:02.417516 kernel: loop0: detected capacity change from 0 to 114432 May 9 00:11:02.385081 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 9 00:11:02.392294 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 9 00:11:02.393576 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 9 00:11:02.394864 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 9 00:11:02.395988 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 9 00:11:02.398602 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 9 00:11:02.408064 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 9 00:11:02.409439 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 9 00:11:02.411508 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 9 00:11:02.424233 systemd-tmpfiles[1155]: ACLs are not supported, ignoring. May 9 00:11:02.434298 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 9 00:11:02.424253 systemd-tmpfiles[1155]: ACLs are not supported, ignoring. May 9 00:11:02.434129 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... May 9 00:11:02.439123 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 9 00:11:02.442084 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 9 00:11:02.445344 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 9 00:11:02.451993 kernel: loop1: detected capacity change from 0 to 201592 May 9 00:11:02.450410 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 9 00:11:02.457803 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 9 00:11:02.460013 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. May 9 00:11:02.461767 udevadm[1170]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 9 00:11:02.478895 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 9 00:11:02.489123 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 9 00:11:02.492942 kernel: loop2: detected capacity change from 0 to 114328 May 9 00:11:02.502313 systemd-tmpfiles[1178]: ACLs are not supported, ignoring. May 9 00:11:02.502334 systemd-tmpfiles[1178]: ACLs are not supported, ignoring. May 9 00:11:02.507137 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 9 00:11:02.522349 kernel: loop3: detected capacity change from 0 to 114432 May 9 00:11:02.539945 kernel: loop4: detected capacity change from 0 to 201592 May 9 00:11:02.554126 kernel: loop5: detected capacity change from 0 to 114328 May 9 00:11:02.559187 (sd-merge)[1185]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. May 9 00:11:02.559628 (sd-merge)[1185]: Merged extensions into '/usr'. May 9 00:11:02.564714 systemd[1]: Reloading requested from client PID 1154 ('systemd-sysext') (unit systemd-sysext.service)... May 9 00:11:02.564736 systemd[1]: Reloading... May 9 00:11:02.622274 zram_generator::config[1207]: No configuration found. May 9 00:11:02.735361 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 9 00:11:02.750650 ldconfig[1149]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 9 00:11:02.774399 systemd[1]: Reloading finished in 209 ms. May 9 00:11:02.806682 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 9 00:11:02.808075 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 9 00:11:02.821114 systemd[1]: Starting ensure-sysext.service... May 9 00:11:02.823270 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 9 00:11:02.837734 systemd[1]: Reloading requested from client PID 1245 ('systemctl') (unit ensure-sysext.service)... May 9 00:11:02.837760 systemd[1]: Reloading... May 9 00:11:02.858246 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 9 00:11:02.858551 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 9 00:11:02.859312 systemd-tmpfiles[1246]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 9 00:11:02.859534 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. May 9 00:11:02.859581 systemd-tmpfiles[1246]: ACLs are not supported, ignoring. May 9 00:11:02.864523 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. May 9 00:11:02.864538 systemd-tmpfiles[1246]: Skipping /boot May 9 00:11:02.878633 systemd-tmpfiles[1246]: Detected autofs mount point /boot during canonicalization of boot. May 9 00:11:02.878653 systemd-tmpfiles[1246]: Skipping /boot May 9 00:11:02.880932 zram_generator::config[1273]: No configuration found. May 9 00:11:02.974256 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 9 00:11:03.010639 systemd[1]: Reloading finished in 172 ms. May 9 00:11:03.028482 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 9 00:11:03.042685 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 9 00:11:03.050682 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... May 9 00:11:03.053689 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 9 00:11:03.056656 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 9 00:11:03.063348 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 9 00:11:03.066559 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 9 00:11:03.072252 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 9 00:11:03.078873 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 00:11:03.085274 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 00:11:03.088417 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 00:11:03.091526 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 00:11:03.092501 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 00:11:03.093815 systemd-udevd[1320]: Using default interface naming scheme 'v255'. May 9 00:11:03.096243 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 9 00:11:03.101144 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 9 00:11:03.102995 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 00:11:03.103153 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 00:11:03.105025 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 00:11:03.105215 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 00:11:03.107369 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 00:11:03.107510 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 00:11:03.124242 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 9 00:11:03.128137 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 00:11:03.135299 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 00:11:03.139979 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 00:11:03.144039 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 00:11:03.145184 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 00:11:03.147687 augenrules[1349]: No rules May 9 00:11:03.148064 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 9 00:11:03.151659 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 9 00:11:03.155779 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. May 9 00:11:03.157347 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 9 00:11:03.160412 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 9 00:11:03.161827 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 00:11:03.162194 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 00:11:03.163590 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 00:11:03.163731 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 00:11:03.165766 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 00:11:03.168098 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 00:11:03.180226 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 9 00:11:03.181826 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 9 00:11:03.192485 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 9 00:11:03.192906 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 9 00:11:03.195948 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1365) May 9 00:11:03.202149 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 9 00:11:03.206525 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 9 00:11:03.211176 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 9 00:11:03.214282 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 9 00:11:03.216412 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 9 00:11:03.216491 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). May 9 00:11:03.217072 systemd[1]: Finished ensure-sysext.service. May 9 00:11:03.222825 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 9 00:11:03.225478 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 9 00:11:03.231368 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 9 00:11:03.231523 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 9 00:11:03.235463 systemd[1]: modprobe@drm.service: Deactivated successfully. May 9 00:11:03.236191 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 9 00:11:03.240278 systemd[1]: modprobe@loop.service: Deactivated successfully. May 9 00:11:03.241132 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 9 00:11:03.249288 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 9 00:11:03.261164 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 9 00:11:03.262246 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 9 00:11:03.262315 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 9 00:11:03.267570 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 9 00:11:03.276181 systemd-networkd[1359]: lo: Link UP May 9 00:11:03.276195 systemd-networkd[1359]: lo: Gained carrier May 9 00:11:03.277027 systemd-networkd[1359]: Enumeration completed May 9 00:11:03.277142 systemd[1]: Started systemd-networkd.service - Network Configuration. May 9 00:11:03.282954 systemd-networkd[1359]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 00:11:03.282964 systemd-networkd[1359]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 9 00:11:03.283671 systemd-networkd[1359]: eth0: Link UP May 9 00:11:03.283684 systemd-networkd[1359]: eth0: Gained carrier May 9 00:11:03.283699 systemd-networkd[1359]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 9 00:11:03.286329 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 9 00:11:03.299471 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 9 00:11:03.305331 systemd-resolved[1314]: Positive Trust Anchors: May 9 00:11:03.305350 systemd-resolved[1314]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 9 00:11:03.305382 systemd-resolved[1314]: 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 May 9 00:11:03.306139 systemd-networkd[1359]: eth0: DHCPv4 address 10.0.0.80/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 9 00:11:03.313631 systemd-resolved[1314]: Defaulting to hostname 'linux'. May 9 00:11:03.321018 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 9 00:11:03.322507 systemd[1]: Reached target network.target - Network. May 9 00:11:03.323309 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 9 00:11:03.342274 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 9 00:11:03.343489 systemd[1]: Reached target time-set.target - System Time Set. May 9 00:11:03.344496 systemd-timesyncd[1398]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 9 00:11:03.344625 systemd-timesyncd[1398]: Initial clock synchronization to Fri 2025-05-09 00:11:03.404691 UTC. May 9 00:11:03.355199 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 9 00:11:03.365760 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 9 00:11:03.370548 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 9 00:11:03.389968 lvm[1409]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 9 00:11:03.396518 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 9 00:11:03.421557 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 9 00:11:03.422899 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 9 00:11:03.425086 systemd[1]: Reached target sysinit.target - System Initialization. May 9 00:11:03.426262 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 9 00:11:03.427740 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 9 00:11:03.429330 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 9 00:11:03.430647 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 9 00:11:03.432001 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 9 00:11:03.432984 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 9 00:11:03.433097 systemd[1]: Reached target paths.target - Path Units. May 9 00:11:03.433803 systemd[1]: Reached target timers.target - Timer Units. May 9 00:11:03.435986 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 9 00:11:03.438228 systemd[1]: Starting docker.socket - Docker Socket for the API... May 9 00:11:03.446962 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 9 00:11:03.449423 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 9 00:11:03.451122 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 9 00:11:03.452375 systemd[1]: Reached target sockets.target - Socket Units. May 9 00:11:03.453338 systemd[1]: Reached target basic.target - Basic System. May 9 00:11:03.454308 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 9 00:11:03.454344 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 9 00:11:03.455436 systemd[1]: Starting containerd.service - containerd container runtime... May 9 00:11:03.457947 lvm[1416]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 9 00:11:03.457598 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 9 00:11:03.462065 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 9 00:11:03.465724 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 9 00:11:03.466538 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). May 9 00:11:03.472134 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 9 00:11:03.475105 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 9 00:11:03.478174 jq[1419]: false May 9 00:11:03.478428 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 9 00:11:03.486210 systemd[1]: Starting systemd-logind.service - User Login Management... May 9 00:11:03.488412 dbus-daemon[1418]: [system] SELinux support is enabled May 9 00:11:03.490185 extend-filesystems[1420]: Found loop3 May 9 00:11:03.491631 extend-filesystems[1420]: Found loop4 May 9 00:11:03.491631 extend-filesystems[1420]: Found loop5 May 9 00:11:03.491631 extend-filesystems[1420]: Found vda May 9 00:11:03.491631 extend-filesystems[1420]: Found vda1 May 9 00:11:03.491631 extend-filesystems[1420]: Found vda2 May 9 00:11:03.491631 extend-filesystems[1420]: Found vda3 May 9 00:11:03.491631 extend-filesystems[1420]: Found usr May 9 00:11:03.491631 extend-filesystems[1420]: Found vda4 May 9 00:11:03.491631 extend-filesystems[1420]: Found vda6 May 9 00:11:03.491631 extend-filesystems[1420]: Found vda7 May 9 00:11:03.491631 extend-filesystems[1420]: Found vda9 May 9 00:11:03.491631 extend-filesystems[1420]: Checking size of /dev/vda9 May 9 00:11:03.492230 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 9 00:11:03.492699 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 9 00:11:03.505809 jq[1435]: true May 9 00:11:03.494660 systemd[1]: Starting update-engine.service - Update Engine... May 9 00:11:03.496897 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 9 00:11:03.498302 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 9 00:11:03.503743 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 9 00:11:03.507387 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 9 00:11:03.508091 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 9 00:11:03.508402 systemd[1]: motdgen.service: Deactivated successfully. May 9 00:11:03.509247 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 9 00:11:03.510368 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 9 00:11:03.510810 extend-filesystems[1420]: Resized partition /dev/vda9 May 9 00:11:03.510838 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 9 00:11:03.517259 extend-filesystems[1441]: resize2fs 1.47.1 (20-May-2024) May 9 00:11:03.522936 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 9 00:11:03.533455 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). May 9 00:11:03.533502 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 9 00:11:03.535457 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). May 9 00:11:03.535533 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 9 00:11:03.536974 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1354) May 9 00:11:03.544293 (ntainerd)[1443]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 9 00:11:03.553935 jq[1442]: true May 9 00:11:03.559927 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 9 00:11:03.567191 update_engine[1434]: I20250509 00:11:03.566927 1434 main.cc:92] Flatcar Update Engine starting May 9 00:11:03.573293 systemd[1]: Started update-engine.service - Update Engine. May 9 00:11:03.574702 update_engine[1434]: I20250509 00:11:03.574651 1434 update_check_scheduler.cc:74] Next update check in 7m4s May 9 00:11:03.575398 extend-filesystems[1441]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 9 00:11:03.575398 extend-filesystems[1441]: old_desc_blocks = 1, new_desc_blocks = 1 May 9 00:11:03.575398 extend-filesystems[1441]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 9 00:11:03.580056 extend-filesystems[1420]: Resized filesystem in /dev/vda9 May 9 00:11:03.584209 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 9 00:11:03.585693 systemd[1]: extend-filesystems.service: Deactivated successfully. May 9 00:11:03.585929 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 9 00:11:03.599495 systemd-logind[1427]: Watching system buttons on /dev/input/event0 (Power Button) May 9 00:11:03.599700 systemd-logind[1427]: New seat seat0. May 9 00:11:03.600453 systemd[1]: Started systemd-logind.service - User Login Management. May 9 00:11:03.635632 bash[1469]: Updated "/home/core/.ssh/authorized_keys" May 9 00:11:03.638890 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 9 00:11:03.641549 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 9 00:11:03.642946 locksmithd[1463]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 9 00:11:03.758291 containerd[1443]: time="2025-05-09T00:11:03.758193040Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 May 9 00:11:03.782854 containerd[1443]: time="2025-05-09T00:11:03.782720760Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 9 00:11:03.784252 containerd[1443]: time="2025-05-09T00:11:03.784181160Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.89-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 9 00:11:03.784252 containerd[1443]: time="2025-05-09T00:11:03.784221680Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 9 00:11:03.784252 containerd[1443]: time="2025-05-09T00:11:03.784237960Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 9 00:11:03.784421 containerd[1443]: time="2025-05-09T00:11:03.784399440Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 9 00:11:03.784451 containerd[1443]: time="2025-05-09T00:11:03.784431520Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 9 00:11:03.784517 containerd[1443]: time="2025-05-09T00:11:03.784491600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 9 00:11:03.784517 containerd[1443]: time="2025-05-09T00:11:03.784509040Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 9 00:11:03.784708 containerd[1443]: time="2025-05-09T00:11:03.784681840Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 May 9 00:11:03.784708 containerd[1443]: time="2025-05-09T00:11:03.784703520Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 9 00:11:03.784761 containerd[1443]: time="2025-05-09T00:11:03.784716800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 9 00:11:03.784761 containerd[1443]: time="2025-05-09T00:11:03.784726800Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 9 00:11:03.784826 containerd[1443]: time="2025-05-09T00:11:03.784810120Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 9 00:11:03.785062 containerd[1443]: time="2025-05-09T00:11:03.785036560Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 9 00:11:03.785162 containerd[1443]: time="2025-05-09T00:11:03.785145120Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 9 00:11:03.785184 containerd[1443]: time="2025-05-09T00:11:03.785163880Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 9 00:11:03.785271 containerd[1443]: time="2025-05-09T00:11:03.785255560Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 9 00:11:03.785323 containerd[1443]: time="2025-05-09T00:11:03.785310160Z" level=info msg="metadata content store policy set" policy=shared May 9 00:11:03.788425 containerd[1443]: time="2025-05-09T00:11:03.788388400Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 9 00:11:03.788575 containerd[1443]: time="2025-05-09T00:11:03.788439680Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 9 00:11:03.788575 containerd[1443]: time="2025-05-09T00:11:03.788456560Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 9 00:11:03.788575 containerd[1443]: time="2025-05-09T00:11:03.788473840Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 9 00:11:03.788575 containerd[1443]: time="2025-05-09T00:11:03.788488200Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 9 00:11:03.788669 containerd[1443]: time="2025-05-09T00:11:03.788646200Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 9 00:11:03.789129 containerd[1443]: time="2025-05-09T00:11:03.789005080Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 9 00:11:03.789305 containerd[1443]: time="2025-05-09T00:11:03.789286520Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 9 00:11:03.789394 containerd[1443]: time="2025-05-09T00:11:03.789378040Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 9 00:11:03.789461 containerd[1443]: time="2025-05-09T00:11:03.789449120Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 9 00:11:03.789553 containerd[1443]: time="2025-05-09T00:11:03.789537480Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 9 00:11:03.789676 containerd[1443]: time="2025-05-09T00:11:03.789611200Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 9 00:11:03.789676 containerd[1443]: time="2025-05-09T00:11:03.789630200Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 9 00:11:03.789676 containerd[1443]: time="2025-05-09T00:11:03.789646000Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 9 00:11:03.789747 containerd[1443]: time="2025-05-09T00:11:03.789662160Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 9 00:11:03.789821 containerd[1443]: time="2025-05-09T00:11:03.789798360Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 9 00:11:03.789923 containerd[1443]: time="2025-05-09T00:11:03.789876680Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 9 00:11:03.789923 containerd[1443]: time="2025-05-09T00:11:03.789897080Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 9 00:11:03.790015 containerd[1443]: time="2025-05-09T00:11:03.790001120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790134 containerd[1443]: time="2025-05-09T00:11:03.790076280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790134 containerd[1443]: time="2025-05-09T00:11:03.790094800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790134 containerd[1443]: time="2025-05-09T00:11:03.790109760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790243 containerd[1443]: time="2025-05-09T00:11:03.790124280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790306 containerd[1443]: time="2025-05-09T00:11:03.790286080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790363 containerd[1443]: time="2025-05-09T00:11:03.790351280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790425 containerd[1443]: time="2025-05-09T00:11:03.790403400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790496 containerd[1443]: time="2025-05-09T00:11:03.790472480Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790608 containerd[1443]: time="2025-05-09T00:11:03.790553760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790608 containerd[1443]: time="2025-05-09T00:11:03.790577320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790683 containerd[1443]: time="2025-05-09T00:11:03.790667080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790819 containerd[1443]: time="2025-05-09T00:11:03.790724160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790819 containerd[1443]: time="2025-05-09T00:11:03.790765520Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 9 00:11:03.790819 containerd[1443]: time="2025-05-09T00:11:03.790800440Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 9 00:11:03.790944 containerd[1443]: time="2025-05-09T00:11:03.790927120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 9 00:11:03.791010 containerd[1443]: time="2025-05-09T00:11:03.790996120Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 9 00:11:03.791396 containerd[1443]: time="2025-05-09T00:11:03.791372760Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 9 00:11:03.791558 containerd[1443]: time="2025-05-09T00:11:03.791497160Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 May 9 00:11:03.791558 containerd[1443]: time="2025-05-09T00:11:03.791516800Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 9 00:11:03.792148 containerd[1443]: time="2025-05-09T00:11:03.791535040Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 9 00:11:03.792148 containerd[1443]: time="2025-05-09T00:11:03.792107320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 9 00:11:03.792256 containerd[1443]: time="2025-05-09T00:11:03.792151600Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 9 00:11:03.792256 containerd[1443]: time="2025-05-09T00:11:03.792167000Z" level=info msg="NRI interface is disabled by configuration." May 9 00:11:03.792256 containerd[1443]: time="2025-05-09T00:11:03.792183520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 9 00:11:03.792894 containerd[1443]: time="2025-05-09T00:11:03.792696440Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} 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}" May 9 00:11:03.792894 containerd[1443]: time="2025-05-09T00:11:03.792775480Z" level=info msg="Connect containerd service" May 9 00:11:03.792894 containerd[1443]: time="2025-05-09T00:11:03.792817680Z" level=info msg="using legacy CRI server" May 9 00:11:03.792894 containerd[1443]: time="2025-05-09T00:11:03.792825360Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 9 00:11:03.793099 containerd[1443]: time="2025-05-09T00:11:03.792906200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 9 00:11:03.793621 containerd[1443]: time="2025-05-09T00:11:03.793591440Z" 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" May 9 00:11:03.793928 containerd[1443]: time="2025-05-09T00:11:03.793835920Z" level=info msg="Start subscribing containerd event" May 9 00:11:03.794070 containerd[1443]: time="2025-05-09T00:11:03.794037080Z" level=info msg="Start recovering state" May 9 00:11:03.794381 containerd[1443]: time="2025-05-09T00:11:03.794240000Z" level=info msg="Start event monitor" May 9 00:11:03.794381 containerd[1443]: time="2025-05-09T00:11:03.794278400Z" level=info msg="Start snapshots syncer" May 9 00:11:03.794381 containerd[1443]: time="2025-05-09T00:11:03.794290120Z" level=info msg="Start cni network conf syncer for default" May 9 00:11:03.794381 containerd[1443]: time="2025-05-09T00:11:03.794298880Z" level=info msg="Start streaming server" May 9 00:11:03.794473 containerd[1443]: time="2025-05-09T00:11:03.794254120Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 9 00:11:03.794494 containerd[1443]: time="2025-05-09T00:11:03.794471720Z" level=info msg=serving... address=/run/containerd/containerd.sock May 9 00:11:03.795994 containerd[1443]: time="2025-05-09T00:11:03.794709280Z" level=info msg="containerd successfully booted in 0.037972s" May 9 00:11:03.794786 systemd[1]: Started containerd.service - containerd container runtime. May 9 00:11:04.333195 sshd_keygen[1437]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 9 00:11:04.353186 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 9 00:11:04.365426 systemd[1]: Starting issuegen.service - Generate /run/issue... May 9 00:11:04.371199 systemd[1]: issuegen.service: Deactivated successfully. May 9 00:11:04.371416 systemd[1]: Finished issuegen.service - Generate /run/issue. May 9 00:11:04.374586 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 9 00:11:04.376115 systemd-networkd[1359]: eth0: Gained IPv6LL May 9 00:11:04.377913 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 9 00:11:04.380352 systemd[1]: Reached target network-online.target - Network is Online. May 9 00:11:04.383325 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 9 00:11:04.385655 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 9 00:11:04.388639 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 9 00:11:04.392763 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 9 00:11:04.400437 systemd[1]: Started getty@tty1.service - Getty on tty1. May 9 00:11:04.403310 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 9 00:11:04.404496 systemd[1]: Reached target getty.target - Login Prompts. May 9 00:11:04.412802 systemd[1]: coreos-metadata.service: Deactivated successfully. May 9 00:11:04.413058 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 9 00:11:04.414737 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 9 00:11:04.418905 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 9 00:11:05.037640 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 9 00:11:05.039034 systemd[1]: Reached target multi-user.target - Multi-User System. May 9 00:11:05.040148 systemd[1]: Startup finished in 581ms (kernel) + 5.048s (initrd) + 3.321s (userspace) = 8.951s. May 9 00:11:05.041413 (kubelet)[1525]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 9 00:11:05.439214 kubelet[1525]: E0509 00:11:05.439093 1525 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" May 9 00:11:05.441717 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 9 00:11:05.441869 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 9 00:11:09.633448 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 9 00:11:09.634528 systemd[1]: Started sshd@0-10.0.0.80:22-10.0.0.1:43956.service - OpenSSH per-connection server daemon (10.0.0.1:43956). May 9 00:11:09.684674 sshd[1538]: Accepted publickey for core from 10.0.0.1 port 43956 ssh2: RSA SHA256:FYCv7MddxRJ04VoyXdzc4EtAmK38lsK0g0VE7murXbA May 9 00:11:09.686404 sshd[1538]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:11:09.699581 systemd-logind[1427]: New session 1 of user core. May 9 00:11:09.700495 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 9 00:11:09.715122 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 9 00:11:09.723416 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 9 00:11:09.725433 systemd[1]: Starting user@500.service - User Manager for UID 500... May 9 00:11:09.731145 (systemd)[1542]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 9 00:11:09.800797 systemd[1542]: Queued start job for default target default.target. May 9 00:11:09.812881 systemd[1542]: Created slice app.slice - User Application Slice. May 9 00:11:09.812910 systemd[1542]: Reached target paths.target - Paths. May 9 00:11:09.812940 systemd[1542]: Reached target timers.target - Timers. May 9 00:11:09.814040 systemd[1542]: Starting dbus.socket - D-Bus User Message Bus Socket... May 9 00:11:09.824647 systemd[1542]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 9 00:11:09.824704 systemd[1542]: Reached target sockets.target - Sockets. May 9 00:11:09.824716 systemd[1542]: Reached target basic.target - Basic System. May 9 00:11:09.824748 systemd[1542]: Reached target default.target - Main User Target. May 9 00:11:09.824774 systemd[1542]: Startup finished in 88ms. May 9 00:11:09.824971 systemd[1]: Started user@500.service - User Manager for UID 500. May 9 00:11:09.826211 systemd[1]: Started session-1.scope - Session 1 of User core. May 9 00:11:09.896315 systemd[1]: Started sshd@1-10.0.0.80:22-10.0.0.1:43966.service - OpenSSH per-connection server daemon (10.0.0.1:43966). May 9 00:11:09.931450 sshd[1553]: Accepted publickey for core from 10.0.0.1 port 43966 ssh2: RSA SHA256:FYCv7MddxRJ04VoyXdzc4EtAmK38lsK0g0VE7murXbA May 9 00:11:09.932766 sshd[1553]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:11:09.936730 systemd-logind[1427]: New session 2 of user core. May 9 00:11:09.945060 systemd[1]: Started session-2.scope - Session 2 of User core. May 9 00:11:09.995605 sshd[1553]: pam_unix(sshd:session): session closed for user core May 9 00:11:10.004991 systemd[1]: sshd@1-10.0.0.80:22-10.0.0.1:43966.service: Deactivated successfully. May 9 00:11:10.006226 systemd[1]: session-2.scope: Deactivated successfully. May 9 00:11:10.009107 systemd-logind[1427]: Session 2 logged out. Waiting for processes to exit. May 9 00:11:10.010093 systemd[1]: Started sshd@2-10.0.0.80:22-10.0.0.1:43976.service - OpenSSH per-connection server daemon (10.0.0.1:43976). May 9 00:11:10.011037 systemd-logind[1427]: Removed session 2. May 9 00:11:10.046490 sshd[1560]: Accepted publickey for core from 10.0.0.1 port 43976 ssh2: RSA SHA256:FYCv7MddxRJ04VoyXdzc4EtAmK38lsK0g0VE7murXbA May 9 00:11:10.047623 sshd[1560]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:11:10.050924 systemd-logind[1427]: New session 3 of user core. May 9 00:11:10.065035 systemd[1]: Started session-3.scope - Session 3 of User core. May 9 00:11:10.112015 sshd[1560]: pam_unix(sshd:session): session closed for user core May 9 00:11:10.128007 systemd[1]: sshd@2-10.0.0.80:22-10.0.0.1:43976.service: Deactivated successfully. May 9 00:11:10.129241 systemd[1]: session-3.scope: Deactivated successfully. May 9 00:11:10.132860 systemd-logind[1427]: Session 3 logged out. Waiting for processes to exit. May 9 00:11:10.133991 systemd[1]: Started sshd@3-10.0.0.80:22-10.0.0.1:43990.service - OpenSSH per-connection server daemon (10.0.0.1:43990). May 9 00:11:10.134619 systemd-logind[1427]: Removed session 3. May 9 00:11:10.170833 sshd[1567]: Accepted publickey for core from 10.0.0.1 port 43990 ssh2: RSA SHA256:FYCv7MddxRJ04VoyXdzc4EtAmK38lsK0g0VE7murXbA May 9 00:11:10.172173 sshd[1567]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:11:10.175289 systemd-logind[1427]: New session 4 of user core. May 9 00:11:10.190049 systemd[1]: Started session-4.scope - Session 4 of User core. May 9 00:11:10.241496 sshd[1567]: pam_unix(sshd:session): session closed for user core May 9 00:11:10.252070 systemd[1]: sshd@3-10.0.0.80:22-10.0.0.1:43990.service: Deactivated successfully. May 9 00:11:10.253285 systemd[1]: session-4.scope: Deactivated successfully. May 9 00:11:10.254853 systemd-logind[1427]: Session 4 logged out. Waiting for processes to exit. May 9 00:11:10.265271 systemd[1]: Started sshd@4-10.0.0.80:22-10.0.0.1:43994.service - OpenSSH per-connection server daemon (10.0.0.1:43994). May 9 00:11:10.266064 systemd-logind[1427]: Removed session 4. May 9 00:11:10.298115 sshd[1574]: Accepted publickey for core from 10.0.0.1 port 43994 ssh2: RSA SHA256:FYCv7MddxRJ04VoyXdzc4EtAmK38lsK0g0VE7murXbA May 9 00:11:10.299189 sshd[1574]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 9 00:11:10.302809 systemd-logind[1427]: New session 5 of user core. May 9 00:11:10.312049 systemd[1]: Started session-5.scope - Session 5 of User core. May 9 00:11:10.382181 sudo[1577]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 9 00:11:10.382453 sudo[1577]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 9 00:11:10.402174 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 9 00:11:10.415875 systemd[1]: coreos-metadata.service: Deactivated successfully. May 9 00:11:10.416987 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 9 00:11:10.830494 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 9 00:11:10.841202 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 9 00:11:10.860248 systemd[1]: Reloading requested from client PID 1619 ('systemctl') (unit session-5.scope)... May 9 00:11:10.860399 systemd[1]: Reloading... May 9 00:11:10.922953 zram_generator::config[1660]: No configuration found. May 9 00:11:11.115865 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 9 00:11:11.168333 systemd[1]: Reloading finished in 307 ms. May 9 00:11:11.210157 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... May 9 00:11:11.212822 systemd[1]: kubelet.service: Deactivated successfully. May 9 00:11:11.213028 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 9 00:11:11.214432 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 9 00:11:11.312736 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 9 00:11:11.316488 (kubelet)[1704]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 9 00:11:11.349790 kubelet[1704]: 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. May 9 00:11:11.349790 kubelet[1704]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. May 9 00:11:11.349790 kubelet[1704]: 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. May 9 00:11:11.350060 kubelet[1704]: I0509 00:11:11.349855 1704 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" May 9 00:11:11.935448 kubelet[1704]: I0509 00:11:11.935411 1704 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" May 9 00:11:11.936149 kubelet[1704]: I0509 00:11:11.935588 1704 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 9 00:11:11.936149 kubelet[1704]: I0509 00:11:11.935870 1704 server.go:954] "Client rotation is on, will bootstrap in background" May 9 00:11:11.978458 kubelet[1704]: I0509 00:11:11.978277 1704 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 9 00:11:11.986952 kubelet[1704]: E0509 00:11:11.984956 1704 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" May 9 00:11:11.986952 kubelet[1704]: I0509 00:11:11.984984 1704 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." May 9 00:11:11.987527 kubelet[1704]: I0509 00:11:11.987192 1704 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 9 00:11:11.988379 kubelet[1704]: I0509 00:11:11.988322 1704 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 9 00:11:11.988539 kubelet[1704]: I0509 00:11:11.988371 1704 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.80","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":"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},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} May 9 00:11:11.988611 kubelet[1704]: I0509 00:11:11.988597 1704 topology_manager.go:138] "Creating topology manager with none policy" May 9 00:11:11.988611 kubelet[1704]: I0509 00:11:11.988607 1704 container_manager_linux.go:304] "Creating device plugin manager" May 9 00:11:11.988827 kubelet[1704]: I0509 00:11:11.988798 1704 state_mem.go:36] "Initialized new in-memory state store" May 9 00:11:11.994809 kubelet[1704]: I0509 00:11:11.994768 1704 kubelet.go:446] "Attempting to sync node with API server" May 9 00:11:11.994809 kubelet[1704]: I0509 00:11:11.994804 1704 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" May 9 00:11:11.995055 kubelet[1704]: I0509 00:11:11.995034 1704 kubelet.go:352] "Adding apiserver pod source" May 9 00:11:11.995055 kubelet[1704]: I0509 00:11:11.995052 1704 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 9 00:11:11.995221 kubelet[1704]: E0509 00:11:11.995159 1704 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:11.995221 kubelet[1704]: E0509 00:11:11.995169 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:12.000422 kubelet[1704]: I0509 00:11:12.000401 1704 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" May 9 00:11:12.002531 kubelet[1704]: I0509 00:11:12.001365 1704 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 9 00:11:12.002531 kubelet[1704]: W0509 00:11:12.001489 1704 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 9 00:11:12.002531 kubelet[1704]: I0509 00:11:12.002357 1704 watchdog_linux.go:99] "Systemd watchdog is not enabled" May 9 00:11:12.002531 kubelet[1704]: I0509 00:11:12.002388 1704 server.go:1287] "Started kubelet" May 9 00:11:12.002680 kubelet[1704]: I0509 00:11:12.002531 1704 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 May 9 00:11:12.003861 kubelet[1704]: I0509 00:11:12.003814 1704 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 9 00:11:12.004924 kubelet[1704]: I0509 00:11:12.004824 1704 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 9 00:11:12.006394 kubelet[1704]: I0509 00:11:12.006208 1704 server.go:490] "Adding debug handlers to kubelet server" May 9 00:11:12.006450 kubelet[1704]: I0509 00:11:12.006403 1704 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 9 00:11:12.007409 kubelet[1704]: I0509 00:11:12.007378 1704 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" May 9 00:11:12.007570 kubelet[1704]: E0509 00:11:12.007539 1704 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.80\" not found" May 9 00:11:12.007604 kubelet[1704]: I0509 00:11:12.007577 1704 volume_manager.go:297] "Starting Kubelet Volume Manager" May 9 00:11:12.008071 kubelet[1704]: I0509 00:11:12.007745 1704 desired_state_of_world_populator.go:149] "Desired state populator starts to run" May 9 00:11:12.008071 kubelet[1704]: I0509 00:11:12.007812 1704 reconciler.go:26] "Reconciler: start to sync state" May 9 00:11:12.008872 kubelet[1704]: I0509 00:11:12.008839 1704 factory.go:221] Registration of the systemd container factory successfully May 9 00:11:12.009054 kubelet[1704]: I0509 00:11:12.008963 1704 factory.go:219] 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 May 9 00:11:12.009884 kubelet[1704]: E0509 00:11:12.009866 1704 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 9 00:11:12.010208 kubelet[1704]: I0509 00:11:12.010137 1704 factory.go:221] Registration of the containerd container factory successfully May 9 00:11:12.019714 kubelet[1704]: I0509 00:11:12.019691 1704 cpu_manager.go:221] "Starting CPU manager" policy="none" May 9 00:11:12.019814 kubelet[1704]: I0509 00:11:12.019803 1704 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" May 9 00:11:12.019865 kubelet[1704]: I0509 00:11:12.019857 1704 state_mem.go:36] "Initialized new in-memory state store" May 9 00:11:12.029269 kubelet[1704]: E0509 00:11:12.029219 1704 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.80\" not found" node="10.0.0.80" May 9 00:11:12.087602 kubelet[1704]: I0509 00:11:12.087574 1704 policy_none.go:49] "None policy: Start" May 9 00:11:12.087715 kubelet[1704]: I0509 00:11:12.087705 1704 memory_manager.go:186] "Starting memorymanager" policy="None" May 9 00:11:12.087766 kubelet[1704]: I0509 00:11:12.087758 1704 state_mem.go:35] "Initializing new in-memory state store" May 9 00:11:12.093308 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 9 00:11:12.104957 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 9 00:11:12.107851 kubelet[1704]: E0509 00:11:12.107661 1704 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.80\" not found" May 9 00:11:12.109656 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 9 00:11:12.112291 kubelet[1704]: I0509 00:11:12.112244 1704 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 9 00:11:12.113340 kubelet[1704]: I0509 00:11:12.113307 1704 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 9 00:11:12.113340 kubelet[1704]: I0509 00:11:12.113344 1704 status_manager.go:227] "Starting to sync pod status with apiserver" May 9 00:11:12.113438 kubelet[1704]: I0509 00:11:12.113363 1704 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." May 9 00:11:12.113438 kubelet[1704]: I0509 00:11:12.113369 1704 kubelet.go:2388] "Starting kubelet main sync loop" May 9 00:11:12.113438 kubelet[1704]: E0509 00:11:12.113412 1704 kubelet.go:2412] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" May 9 00:11:12.116935 kubelet[1704]: I0509 00:11:12.116665 1704 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 9 00:11:12.116935 kubelet[1704]: I0509 00:11:12.116867 1704 eviction_manager.go:189] "Eviction manager: starting control loop" May 9 00:11:12.116935 kubelet[1704]: I0509 00:11:12.116878 1704 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 9 00:11:12.117251 kubelet[1704]: I0509 00:11:12.117103 1704 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 9 00:11:12.117986 kubelet[1704]: E0509 00:11:12.117936 1704 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" May 9 00:11:12.117986 kubelet[1704]: E0509 00:11:12.117973 1704 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.80\" not found" May 9 00:11:12.217967 kubelet[1704]: I0509 00:11:12.217823 1704 kubelet_node_status.go:76] "Attempting to register node" node="10.0.0.80" May 9 00:11:12.229201 kubelet[1704]: I0509 00:11:12.229163 1704 kubelet_node_status.go:79] "Successfully registered node" node="10.0.0.80" May 9 00:11:12.333367 kubelet[1704]: I0509 00:11:12.333305 1704 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 9 00:11:12.335763 containerd[1443]: time="2025-05-09T00:11:12.334073325Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 9 00:11:12.336055 kubelet[1704]: I0509 00:11:12.334275 1704 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 9 00:11:12.345222 sudo[1577]: pam_unix(sudo:session): session closed for user root May 9 00:11:12.347102 sshd[1574]: pam_unix(sshd:session): session closed for user core May 9 00:11:12.350192 systemd-logind[1427]: Session 5 logged out. Waiting for processes to exit. May 9 00:11:12.350760 systemd[1]: sshd@4-10.0.0.80:22-10.0.0.1:43994.service: Deactivated successfully. May 9 00:11:12.352499 systemd[1]: session-5.scope: Deactivated successfully. May 9 00:11:12.353619 systemd-logind[1427]: Removed session 5. May 9 00:11:12.937421 kubelet[1704]: I0509 00:11:12.937378 1704 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 9 00:11:12.937996 kubelet[1704]: W0509 00:11:12.937567 1704 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 9 00:11:12.937996 kubelet[1704]: W0509 00:11:12.937622 1704 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 9 00:11:12.937996 kubelet[1704]: W0509 00:11:12.937791 1704 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 9 00:11:12.995720 kubelet[1704]: I0509 00:11:12.995689 1704 apiserver.go:52] "Watching apiserver" May 9 00:11:12.995932 kubelet[1704]: E0509 00:11:12.995723 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:13.006524 systemd[1]: Created slice kubepods-besteffort-pod5854a80f_713a_40eb_bba4_b7e715fc21bb.slice - libcontainer container kubepods-besteffort-pod5854a80f_713a_40eb_bba4_b7e715fc21bb.slice. May 9 00:11:13.008944 kubelet[1704]: I0509 00:11:13.008894 1704 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" May 9 00:11:13.013793 kubelet[1704]: I0509 00:11:13.013766 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/5854a80f-713a-40eb-bba4-b7e715fc21bb-kube-proxy\") pod \"kube-proxy-6nncg\" (UID: \"5854a80f-713a-40eb-bba4-b7e715fc21bb\") " pod="kube-system/kube-proxy-6nncg" May 9 00:11:13.013847 kubelet[1704]: I0509 00:11:13.013799 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/5854a80f-713a-40eb-bba4-b7e715fc21bb-xtables-lock\") pod \"kube-proxy-6nncg\" (UID: \"5854a80f-713a-40eb-bba4-b7e715fc21bb\") " pod="kube-system/kube-proxy-6nncg" May 9 00:11:13.013847 kubelet[1704]: I0509 00:11:13.013819 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/5854a80f-713a-40eb-bba4-b7e715fc21bb-lib-modules\") pod \"kube-proxy-6nncg\" (UID: \"5854a80f-713a-40eb-bba4-b7e715fc21bb\") " pod="kube-system/kube-proxy-6nncg" May 9 00:11:13.013847 kubelet[1704]: I0509 00:11:13.013835 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xs5ww\" (UniqueName: \"kubernetes.io/projected/5854a80f-713a-40eb-bba4-b7e715fc21bb-kube-api-access-xs5ww\") pod \"kube-proxy-6nncg\" (UID: \"5854a80f-713a-40eb-bba4-b7e715fc21bb\") " pod="kube-system/kube-proxy-6nncg" May 9 00:11:13.013928 kubelet[1704]: I0509 00:11:13.013852 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/428b3ed6-0730-4938-af96-1b3fcb9b9d52-run\") pod \"kube-flannel-ds-j98p9\" (UID: \"428b3ed6-0730-4938-af96-1b3fcb9b9d52\") " pod="kube-flannel/kube-flannel-ds-j98p9" May 9 00:11:13.013928 kubelet[1704]: I0509 00:11:13.013872 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/428b3ed6-0730-4938-af96-1b3fcb9b9d52-cni-plugin\") pod \"kube-flannel-ds-j98p9\" (UID: \"428b3ed6-0730-4938-af96-1b3fcb9b9d52\") " pod="kube-flannel/kube-flannel-ds-j98p9" May 9 00:11:13.013928 kubelet[1704]: I0509 00:11:13.013894 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/428b3ed6-0730-4938-af96-1b3fcb9b9d52-cni\") pod \"kube-flannel-ds-j98p9\" (UID: \"428b3ed6-0730-4938-af96-1b3fcb9b9d52\") " pod="kube-flannel/kube-flannel-ds-j98p9" May 9 00:11:13.013928 kubelet[1704]: I0509 00:11:13.013920 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/428b3ed6-0730-4938-af96-1b3fcb9b9d52-flannel-cfg\") pod \"kube-flannel-ds-j98p9\" (UID: \"428b3ed6-0730-4938-af96-1b3fcb9b9d52\") " pod="kube-flannel/kube-flannel-ds-j98p9" May 9 00:11:13.014021 kubelet[1704]: I0509 00:11:13.013939 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/428b3ed6-0730-4938-af96-1b3fcb9b9d52-xtables-lock\") pod \"kube-flannel-ds-j98p9\" (UID: \"428b3ed6-0730-4938-af96-1b3fcb9b9d52\") " pod="kube-flannel/kube-flannel-ds-j98p9" May 9 00:11:13.014021 kubelet[1704]: I0509 00:11:13.013954 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mtsjk\" (UniqueName: \"kubernetes.io/projected/428b3ed6-0730-4938-af96-1b3fcb9b9d52-kube-api-access-mtsjk\") pod \"kube-flannel-ds-j98p9\" (UID: \"428b3ed6-0730-4938-af96-1b3fcb9b9d52\") " pod="kube-flannel/kube-flannel-ds-j98p9" May 9 00:11:13.026461 systemd[1]: Created slice kubepods-burstable-pod428b3ed6_0730_4938_af96_1b3fcb9b9d52.slice - libcontainer container kubepods-burstable-pod428b3ed6_0730_4938_af96_1b3fcb9b9d52.slice. May 9 00:11:13.325375 kubelet[1704]: E0509 00:11:13.325268 1704 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" May 9 00:11:13.326018 containerd[1443]: time="2025-05-09T00:11:13.325945064Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-6nncg,Uid:5854a80f-713a-40eb-bba4-b7e715fc21bb,Namespace:kube-system,Attempt:0,}" May 9 00:11:13.334304 kubelet[1704]: E0509 00:11:13.333946 1704 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" May 9 00:11:13.334661 containerd[1443]: time="2025-05-09T00:11:13.334620987Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-j98p9,Uid:428b3ed6-0730-4938-af96-1b3fcb9b9d52,Namespace:kube-flannel,Attempt:0,}" May 9 00:11:13.923869 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount315716107.mount: Deactivated successfully. May 9 00:11:13.929720 containerd[1443]: time="2025-05-09T00:11:13.929672076Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 9 00:11:13.930536 containerd[1443]: time="2025-05-09T00:11:13.930511737Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 9 00:11:13.931072 containerd[1443]: time="2025-05-09T00:11:13.930921425Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" May 9 00:11:13.931807 containerd[1443]: time="2025-05-09T00:11:13.931778044Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" May 9 00:11:13.932289 containerd[1443]: time="2025-05-09T00:11:13.932239449Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 9 00:11:13.934979 containerd[1443]: time="2025-05-09T00:11:13.934938078Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 9 00:11:13.936365 containerd[1443]: time="2025-05-09T00:11:13.935988457Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 601.150339ms" May 9 00:11:13.937841 containerd[1443]: time="2025-05-09T00:11:13.937805691Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 611.783331ms" May 9 00:11:13.996648 kubelet[1704]: E0509 00:11:13.996610 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:14.033904 containerd[1443]: time="2025-05-09T00:11:14.033768845Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 9 00:11:14.033904 containerd[1443]: time="2025-05-09T00:11:14.033813213Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 9 00:11:14.033904 containerd[1443]: time="2025-05-09T00:11:14.033828844Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:14.033904 containerd[1443]: time="2025-05-09T00:11:14.033577065Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 9 00:11:14.033904 containerd[1443]: time="2025-05-09T00:11:14.033639870Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 9 00:11:14.033904 containerd[1443]: time="2025-05-09T00:11:14.033651573Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:14.034785 containerd[1443]: time="2025-05-09T00:11:14.034695041Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:14.035362 containerd[1443]: time="2025-05-09T00:11:14.034974034Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:14.123084 systemd[1]: Started cri-containerd-05a2a4a0a1143cf8eac4e4a6caa62b502151cb92c5a14934f72043342ccaf4b4.scope - libcontainer container 05a2a4a0a1143cf8eac4e4a6caa62b502151cb92c5a14934f72043342ccaf4b4. May 9 00:11:14.126811 systemd[1]: Started cri-containerd-17d740fe37e3d5fb0ff0908b4c88da6cbf7eae22c4b464b294d8ec22e98e227b.scope - libcontainer container 17d740fe37e3d5fb0ff0908b4c88da6cbf7eae22c4b464b294d8ec22e98e227b. May 9 00:11:14.146899 containerd[1443]: time="2025-05-09T00:11:14.146799785Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-6nncg,Uid:5854a80f-713a-40eb-bba4-b7e715fc21bb,Namespace:kube-system,Attempt:0,} returns sandbox id \"05a2a4a0a1143cf8eac4e4a6caa62b502151cb92c5a14934f72043342ccaf4b4\"" May 9 00:11:14.147977 kubelet[1704]: E0509 00:11:14.147942 1704 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" May 9 00:11:14.149126 containerd[1443]: time="2025-05-09T00:11:14.149093931Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\"" May 9 00:11:14.156272 containerd[1443]: time="2025-05-09T00:11:14.156235523Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-j98p9,Uid:428b3ed6-0730-4938-af96-1b3fcb9b9d52,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"17d740fe37e3d5fb0ff0908b4c88da6cbf7eae22c4b464b294d8ec22e98e227b\"" May 9 00:11:14.156842 kubelet[1704]: E0509 00:11:14.156823 1704 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" May 9 00:11:14.997057 kubelet[1704]: E0509 00:11:14.997015 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:15.118363 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2793882602.mount: Deactivated successfully. May 9 00:11:15.333902 containerd[1443]: time="2025-05-09T00:11:15.333681043Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:15.334859 containerd[1443]: time="2025-05-09T00:11:15.334804391Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.4: active requests=0, bytes read=27370353" May 9 00:11:15.335863 containerd[1443]: time="2025-05-09T00:11:15.335801761Z" level=info msg="ImageCreate event name:\"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:15.337628 containerd[1443]: time="2025-05-09T00:11:15.337592747Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:15.339048 containerd[1443]: time="2025-05-09T00:11:15.339014733Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.4\" with image id \"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\", repo tag \"registry.k8s.io/kube-proxy:v1.32.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\", size \"27369370\" in 1.18988369s" May 9 00:11:15.339048 containerd[1443]: time="2025-05-09T00:11:15.339045627Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\" returns image reference \"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\"" May 9 00:11:15.340238 containerd[1443]: time="2025-05-09T00:11:15.340061669Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 9 00:11:15.341538 containerd[1443]: time="2025-05-09T00:11:15.341323978Z" level=info msg="CreateContainer within sandbox \"05a2a4a0a1143cf8eac4e4a6caa62b502151cb92c5a14934f72043342ccaf4b4\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 9 00:11:15.354893 containerd[1443]: time="2025-05-09T00:11:15.354840700Z" level=info msg="CreateContainer within sandbox \"05a2a4a0a1143cf8eac4e4a6caa62b502151cb92c5a14934f72043342ccaf4b4\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"575edb2c6d3cd500446a74131d8138fe83105fd42311656b723f03003735a2b5\"" May 9 00:11:15.355748 containerd[1443]: time="2025-05-09T00:11:15.355659841Z" level=info msg="StartContainer for \"575edb2c6d3cd500446a74131d8138fe83105fd42311656b723f03003735a2b5\"" May 9 00:11:15.384090 systemd[1]: Started cri-containerd-575edb2c6d3cd500446a74131d8138fe83105fd42311656b723f03003735a2b5.scope - libcontainer container 575edb2c6d3cd500446a74131d8138fe83105fd42311656b723f03003735a2b5. May 9 00:11:15.406848 containerd[1443]: time="2025-05-09T00:11:15.406804502Z" level=info msg="StartContainer for \"575edb2c6d3cd500446a74131d8138fe83105fd42311656b723f03003735a2b5\" returns successfully" May 9 00:11:15.997730 kubelet[1704]: E0509 00:11:15.997688 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:16.121580 kubelet[1704]: E0509 00:11:16.121510 1704 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" May 9 00:11:16.129969 kubelet[1704]: I0509 00:11:16.129890 1704 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-6nncg" podStartSLOduration=2.938573798 podStartE2EDuration="4.129875939s" podCreationTimestamp="2025-05-09 00:11:12 +0000 UTC" firstStartedPulling="2025-05-09 00:11:14.148515545 +0000 UTC m=+2.828388611" lastFinishedPulling="2025-05-09 00:11:15.339817686 +0000 UTC m=+4.019690752" observedRunningTime="2025-05-09 00:11:16.129652921 +0000 UTC m=+4.809525987" watchObservedRunningTime="2025-05-09 00:11:16.129875939 +0000 UTC m=+4.809749005" May 9 00:11:16.446340 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3124775176.mount: Deactivated successfully. May 9 00:11:16.475333 containerd[1443]: time="2025-05-09T00:11:16.475284218Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:16.475822 containerd[1443]: time="2025-05-09T00:11:16.475787983Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" May 9 00:11:16.476769 containerd[1443]: time="2025-05-09T00:11:16.476737384Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:16.478797 containerd[1443]: time="2025-05-09T00:11:16.478765102Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:16.479669 containerd[1443]: time="2025-05-09T00:11:16.479640150Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.13954795s" May 9 00:11:16.479724 containerd[1443]: time="2025-05-09T00:11:16.479675243Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" May 9 00:11:16.481822 containerd[1443]: time="2025-05-09T00:11:16.481731925Z" level=info msg="CreateContainer within sandbox \"17d740fe37e3d5fb0ff0908b4c88da6cbf7eae22c4b464b294d8ec22e98e227b\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 9 00:11:16.491974 containerd[1443]: time="2025-05-09T00:11:16.491934931Z" level=info msg="CreateContainer within sandbox \"17d740fe37e3d5fb0ff0908b4c88da6cbf7eae22c4b464b294d8ec22e98e227b\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"efc755a32abaf32a8eceb0364839d9d04ac8e6c02327ccbe4ad3fef7724e5ff0\"" May 9 00:11:16.492395 containerd[1443]: time="2025-05-09T00:11:16.492373918Z" level=info msg="StartContainer for \"efc755a32abaf32a8eceb0364839d9d04ac8e6c02327ccbe4ad3fef7724e5ff0\"" May 9 00:11:16.520086 systemd[1]: Started cri-containerd-efc755a32abaf32a8eceb0364839d9d04ac8e6c02327ccbe4ad3fef7724e5ff0.scope - libcontainer container efc755a32abaf32a8eceb0364839d9d04ac8e6c02327ccbe4ad3fef7724e5ff0. May 9 00:11:16.546020 containerd[1443]: time="2025-05-09T00:11:16.545254863Z" level=info msg="StartContainer for \"efc755a32abaf32a8eceb0364839d9d04ac8e6c02327ccbe4ad3fef7724e5ff0\" returns successfully" May 9 00:11:16.552037 systemd[1]: cri-containerd-efc755a32abaf32a8eceb0364839d9d04ac8e6c02327ccbe4ad3fef7724e5ff0.scope: Deactivated successfully. May 9 00:11:16.699998 containerd[1443]: time="2025-05-09T00:11:16.699863376Z" level=info msg="shim disconnected" id=efc755a32abaf32a8eceb0364839d9d04ac8e6c02327ccbe4ad3fef7724e5ff0 namespace=k8s.io May 9 00:11:16.699998 containerd[1443]: time="2025-05-09T00:11:16.699932161Z" level=warning msg="cleaning up after shim disconnected" id=efc755a32abaf32a8eceb0364839d9d04ac8e6c02327ccbe4ad3fef7724e5ff0 namespace=k8s.io May 9 00:11:16.699998 containerd[1443]: time="2025-05-09T00:11:16.699941455Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 9 00:11:16.998844 kubelet[1704]: E0509 00:11:16.998729 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:17.124155 kubelet[1704]: E0509 00:11:17.123982 1704 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" May 9 00:11:17.124155 kubelet[1704]: E0509 00:11:17.124027 1704 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" May 9 00:11:17.124930 containerd[1443]: time="2025-05-09T00:11:17.124828212Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 9 00:11:17.391580 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-efc755a32abaf32a8eceb0364839d9d04ac8e6c02327ccbe4ad3fef7724e5ff0-rootfs.mount: Deactivated successfully. May 9 00:11:17.999294 kubelet[1704]: E0509 00:11:17.999244 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:18.237310 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount233165799.mount: Deactivated successfully. May 9 00:11:18.683012 containerd[1443]: time="2025-05-09T00:11:18.682957270Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:18.683958 containerd[1443]: time="2025-05-09T00:11:18.683667816Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" May 9 00:11:18.684599 containerd[1443]: time="2025-05-09T00:11:18.684563858Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:18.687754 containerd[1443]: time="2025-05-09T00:11:18.687720047Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:18.689066 containerd[1443]: time="2025-05-09T00:11:18.689042264Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 1.564168033s" May 9 00:11:18.689269 containerd[1443]: time="2025-05-09T00:11:18.689157037Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" May 9 00:11:18.691085 containerd[1443]: time="2025-05-09T00:11:18.691061651Z" level=info msg="CreateContainer within sandbox \"17d740fe37e3d5fb0ff0908b4c88da6cbf7eae22c4b464b294d8ec22e98e227b\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 9 00:11:18.699168 containerd[1443]: time="2025-05-09T00:11:18.699123904Z" level=info msg="CreateContainer within sandbox \"17d740fe37e3d5fb0ff0908b4c88da6cbf7eae22c4b464b294d8ec22e98e227b\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"25a018302b08dbce40a196badc8a457e702b38460677590e7c31883b93456484\"" May 9 00:11:18.699611 containerd[1443]: time="2025-05-09T00:11:18.699592529Z" level=info msg="StartContainer for \"25a018302b08dbce40a196badc8a457e702b38460677590e7c31883b93456484\"" May 9 00:11:18.724051 systemd[1]: Started cri-containerd-25a018302b08dbce40a196badc8a457e702b38460677590e7c31883b93456484.scope - libcontainer container 25a018302b08dbce40a196badc8a457e702b38460677590e7c31883b93456484. May 9 00:11:18.744903 containerd[1443]: time="2025-05-09T00:11:18.744832281Z" level=info msg="StartContainer for \"25a018302b08dbce40a196badc8a457e702b38460677590e7c31883b93456484\" returns successfully" May 9 00:11:18.751733 systemd[1]: cri-containerd-25a018302b08dbce40a196badc8a457e702b38460677590e7c31883b93456484.scope: Deactivated successfully. May 9 00:11:18.755082 kubelet[1704]: I0509 00:11:18.755062 1704 kubelet_node_status.go:502] "Fast updating node status as it just became ready" May 9 00:11:18.766128 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-25a018302b08dbce40a196badc8a457e702b38460677590e7c31883b93456484-rootfs.mount: Deactivated successfully. May 9 00:11:18.858144 containerd[1443]: time="2025-05-09T00:11:18.858083257Z" level=info msg="shim disconnected" id=25a018302b08dbce40a196badc8a457e702b38460677590e7c31883b93456484 namespace=k8s.io May 9 00:11:18.858144 containerd[1443]: time="2025-05-09T00:11:18.858137160Z" level=warning msg="cleaning up after shim disconnected" id=25a018302b08dbce40a196badc8a457e702b38460677590e7c31883b93456484 namespace=k8s.io May 9 00:11:18.858144 containerd[1443]: time="2025-05-09T00:11:18.858147292Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 9 00:11:19.000046 kubelet[1704]: E0509 00:11:18.999936 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:19.128468 kubelet[1704]: E0509 00:11:19.128433 1704 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" May 9 00:11:19.129961 containerd[1443]: time="2025-05-09T00:11:19.129742766Z" level=info msg="CreateContainer within sandbox \"17d740fe37e3d5fb0ff0908b4c88da6cbf7eae22c4b464b294d8ec22e98e227b\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 9 00:11:19.144487 containerd[1443]: time="2025-05-09T00:11:19.144445644Z" level=info msg="CreateContainer within sandbox \"17d740fe37e3d5fb0ff0908b4c88da6cbf7eae22c4b464b294d8ec22e98e227b\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"1742d4cec492e07aaf786d3e63e00cc2d7edf128d90cbe3a6b898aba29f1a0db\"" May 9 00:11:19.144962 containerd[1443]: time="2025-05-09T00:11:19.144931298Z" level=info msg="StartContainer for \"1742d4cec492e07aaf786d3e63e00cc2d7edf128d90cbe3a6b898aba29f1a0db\"" May 9 00:11:19.173122 systemd[1]: Started cri-containerd-1742d4cec492e07aaf786d3e63e00cc2d7edf128d90cbe3a6b898aba29f1a0db.scope - libcontainer container 1742d4cec492e07aaf786d3e63e00cc2d7edf128d90cbe3a6b898aba29f1a0db. May 9 00:11:19.200406 containerd[1443]: time="2025-05-09T00:11:19.200354323Z" level=info msg="StartContainer for \"1742d4cec492e07aaf786d3e63e00cc2d7edf128d90cbe3a6b898aba29f1a0db\" returns successfully" May 9 00:11:20.000123 kubelet[1704]: E0509 00:11:20.000043 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:20.132235 kubelet[1704]: E0509 00:11:20.132167 1704 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" May 9 00:11:20.143977 kubelet[1704]: I0509 00:11:20.143903 1704 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-j98p9" podStartSLOduration=3.611104579 podStartE2EDuration="8.143888786s" podCreationTimestamp="2025-05-09 00:11:12 +0000 UTC" firstStartedPulling="2025-05-09 00:11:14.157196708 +0000 UTC m=+2.837069734" lastFinishedPulling="2025-05-09 00:11:18.689980915 +0000 UTC m=+7.369853941" observedRunningTime="2025-05-09 00:11:20.143234523 +0000 UTC m=+8.823107589" watchObservedRunningTime="2025-05-09 00:11:20.143888786 +0000 UTC m=+8.823761812" May 9 00:11:20.277197 systemd-networkd[1359]: flannel.1: Link UP May 9 00:11:20.277203 systemd-networkd[1359]: flannel.1: Gained carrier May 9 00:11:21.000257 kubelet[1704]: E0509 00:11:21.000209 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:21.133803 kubelet[1704]: E0509 00:11:21.133768 1704 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" May 9 00:11:22.000498 kubelet[1704]: E0509 00:11:22.000437 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:22.296118 systemd-networkd[1359]: flannel.1: Gained IPv6LL May 9 00:11:23.001215 kubelet[1704]: E0509 00:11:23.001173 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:23.200463 systemd[1]: Created slice kubepods-besteffort-pod21fab475_ef69_47fc_b26d_a8013298154b.slice - libcontainer container kubepods-besteffort-pod21fab475_ef69_47fc_b26d_a8013298154b.slice. May 9 00:11:23.283528 kubelet[1704]: I0509 00:11:23.283350 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-p6hpc\" (UniqueName: \"kubernetes.io/projected/21fab475-ef69-47fc-b26d-a8013298154b-kube-api-access-p6hpc\") pod \"nginx-deployment-7fcdb87857-fsmhj\" (UID: \"21fab475-ef69-47fc-b26d-a8013298154b\") " pod="default/nginx-deployment-7fcdb87857-fsmhj" May 9 00:11:23.503761 containerd[1443]: time="2025-05-09T00:11:23.503721292Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-fsmhj,Uid:21fab475-ef69-47fc-b26d-a8013298154b,Namespace:default,Attempt:0,}" May 9 00:11:23.559822 systemd-networkd[1359]: cni0: Link UP May 9 00:11:23.559829 systemd-networkd[1359]: cni0: Gained carrier May 9 00:11:23.560079 systemd-networkd[1359]: cni0: Lost carrier May 9 00:11:23.568477 kernel: cni0: port 1(veth1e122a73) entered blocking state May 9 00:11:23.568543 kernel: cni0: port 1(veth1e122a73) entered disabled state May 9 00:11:23.568573 kernel: veth1e122a73: entered allmulticast mode May 9 00:11:23.568594 kernel: veth1e122a73: entered promiscuous mode May 9 00:11:23.568609 kernel: cni0: port 1(veth1e122a73) entered blocking state May 9 00:11:23.569449 kernel: cni0: port 1(veth1e122a73) entered forwarding state May 9 00:11:23.570102 kernel: cni0: port 1(veth1e122a73) entered disabled state May 9 00:11:23.570160 systemd-networkd[1359]: veth1e122a73: Link UP May 9 00:11:23.583804 systemd-networkd[1359]: veth1e122a73: Gained carrier May 9 00:11:23.584303 kernel: cni0: port 1(veth1e122a73) entered blocking state May 9 00:11:23.584332 kernel: cni0: port 1(veth1e122a73) entered forwarding state May 9 00:11:23.584606 systemd-networkd[1359]: cni0: Gained carrier May 9 00:11:23.586675 containerd[1443]: 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)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000016938), "name":"cbr0", "type":"bridge"} May 9 00:11:23.586675 containerd[1443]: delegateAdd: netconf sent to delegate plugin: May 9 00:11:23.611375 containerd[1443]: {"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-05-09T00:11:23.611208820Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 9 00:11:23.611375 containerd[1443]: time="2025-05-09T00:11:23.611275139Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 9 00:11:23.611375 containerd[1443]: time="2025-05-09T00:11:23.611305918Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:23.611549 containerd[1443]: time="2025-05-09T00:11:23.611508559Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:23.629060 systemd[1]: Started cri-containerd-564411fbcd5e7f2f94efad61b61cec17e4d131e5cb345e51177b94e77782ce37.scope - libcontainer container 564411fbcd5e7f2f94efad61b61cec17e4d131e5cb345e51177b94e77782ce37. May 9 00:11:23.637505 systemd-resolved[1314]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 9 00:11:23.652403 containerd[1443]: time="2025-05-09T00:11:23.652299815Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-fsmhj,Uid:21fab475-ef69-47fc-b26d-a8013298154b,Namespace:default,Attempt:0,} returns sandbox id \"564411fbcd5e7f2f94efad61b61cec17e4d131e5cb345e51177b94e77782ce37\"" May 9 00:11:23.653332 containerd[1443]: time="2025-05-09T00:11:23.653296890Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 9 00:11:24.001607 kubelet[1704]: E0509 00:11:24.001497 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:24.856313 systemd-networkd[1359]: cni0: Gained IPv6LL May 9 00:11:25.001749 kubelet[1704]: E0509 00:11:25.001586 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:25.176074 systemd-networkd[1359]: veth1e122a73: Gained IPv6LL May 9 00:11:25.530738 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4292704203.mount: Deactivated successfully. May 9 00:11:26.003117 kubelet[1704]: E0509 00:11:26.002797 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:26.252846 containerd[1443]: time="2025-05-09T00:11:26.252794735Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:26.253870 containerd[1443]: time="2025-05-09T00:11:26.253514222Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948859" May 9 00:11:26.254604 containerd[1443]: time="2025-05-09T00:11:26.254533630Z" level=info msg="ImageCreate event name:\"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:26.257160 containerd[1443]: time="2025-05-09T00:11:26.257126306Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:26.258341 containerd[1443]: time="2025-05-09T00:11:26.258305338Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"69948737\" in 2.604983195s" May 9 00:11:26.258381 containerd[1443]: time="2025-05-09T00:11:26.258341432Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 9 00:11:26.260408 containerd[1443]: time="2025-05-09T00:11:26.260372604Z" level=info msg="CreateContainer within sandbox \"564411fbcd5e7f2f94efad61b61cec17e4d131e5cb345e51177b94e77782ce37\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 9 00:11:26.272842 containerd[1443]: time="2025-05-09T00:11:26.272801252Z" level=info msg="CreateContainer within sandbox \"564411fbcd5e7f2f94efad61b61cec17e4d131e5cb345e51177b94e77782ce37\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"46c0f921eaa142a74cbe931aa1da772177301dbf45bf90d6aca9a7ab820c20de\"" May 9 00:11:26.273936 containerd[1443]: time="2025-05-09T00:11:26.273291889Z" level=info msg="StartContainer for \"46c0f921eaa142a74cbe931aa1da772177301dbf45bf90d6aca9a7ab820c20de\"" May 9 00:11:26.298090 systemd[1]: Started cri-containerd-46c0f921eaa142a74cbe931aa1da772177301dbf45bf90d6aca9a7ab820c20de.scope - libcontainer container 46c0f921eaa142a74cbe931aa1da772177301dbf45bf90d6aca9a7ab820c20de. May 9 00:11:26.315676 containerd[1443]: time="2025-05-09T00:11:26.315170030Z" level=info msg="StartContainer for \"46c0f921eaa142a74cbe931aa1da772177301dbf45bf90d6aca9a7ab820c20de\" returns successfully" May 9 00:11:27.003901 kubelet[1704]: E0509 00:11:27.003850 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:27.155364 kubelet[1704]: I0509 00:11:27.155299 1704 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-fsmhj" podStartSLOduration=1.549050345 podStartE2EDuration="4.15527894s" podCreationTimestamp="2025-05-09 00:11:23 +0000 UTC" firstStartedPulling="2025-05-09 00:11:23.65299559 +0000 UTC m=+12.332868656" lastFinishedPulling="2025-05-09 00:11:26.259224185 +0000 UTC m=+14.939097251" observedRunningTime="2025-05-09 00:11:27.155152096 +0000 UTC m=+15.835025162" watchObservedRunningTime="2025-05-09 00:11:27.15527894 +0000 UTC m=+15.835152007" May 9 00:11:28.004186 kubelet[1704]: E0509 00:11:28.004140 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:29.004600 kubelet[1704]: E0509 00:11:29.004560 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:29.696021 systemd[1]: Created slice kubepods-besteffort-pod3d0b0be3_1b16_4e8c_824a_21ae8b372636.slice - libcontainer container kubepods-besteffort-pod3d0b0be3_1b16_4e8c_824a_21ae8b372636.slice. May 9 00:11:29.716614 kubelet[1704]: I0509 00:11:29.716539 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xp7xn\" (UniqueName: \"kubernetes.io/projected/3d0b0be3-1b16-4e8c-824a-21ae8b372636-kube-api-access-xp7xn\") pod \"nfs-server-provisioner-0\" (UID: \"3d0b0be3-1b16-4e8c-824a-21ae8b372636\") " pod="default/nfs-server-provisioner-0" May 9 00:11:29.716614 kubelet[1704]: I0509 00:11:29.716608 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/3d0b0be3-1b16-4e8c-824a-21ae8b372636-data\") pod \"nfs-server-provisioner-0\" (UID: \"3d0b0be3-1b16-4e8c-824a-21ae8b372636\") " pod="default/nfs-server-provisioner-0" May 9 00:11:29.999623 containerd[1443]: time="2025-05-09T00:11:29.999498756Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:3d0b0be3-1b16-4e8c-824a-21ae8b372636,Namespace:default,Attempt:0,}" May 9 00:11:30.004825 kubelet[1704]: E0509 00:11:30.004775 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:30.026303 systemd-networkd[1359]: vethac49fad3: Link UP May 9 00:11:30.028403 kernel: cni0: port 2(vethac49fad3) entered blocking state May 9 00:11:30.028564 kernel: cni0: port 2(vethac49fad3) entered disabled state May 9 00:11:30.028589 kernel: vethac49fad3: entered allmulticast mode May 9 00:11:30.028605 kernel: vethac49fad3: entered promiscuous mode May 9 00:11:30.033364 kernel: cni0: port 2(vethac49fad3) entered blocking state May 9 00:11:30.033413 kernel: cni0: port 2(vethac49fad3) entered forwarding state May 9 00:11:30.033496 systemd-networkd[1359]: vethac49fad3: Gained carrier May 9 00:11:30.034918 containerd[1443]: 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)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} May 9 00:11:30.034918 containerd[1443]: delegateAdd: netconf sent to delegate plugin: May 9 00:11:30.054541 containerd[1443]: {"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-05-09T00:11:30.053840779Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 9 00:11:30.054541 containerd[1443]: time="2025-05-09T00:11:30.053897832Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 9 00:11:30.054541 containerd[1443]: time="2025-05-09T00:11:30.053930080Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:30.054541 containerd[1443]: time="2025-05-09T00:11:30.054004738Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:30.075081 systemd[1]: Started cri-containerd-bda41fc802ee2b6b7c1039ef1b67203e67e85c80178c230d89229d483416ab71.scope - libcontainer container bda41fc802ee2b6b7c1039ef1b67203e67e85c80178c230d89229d483416ab71. May 9 00:11:30.084993 systemd-resolved[1314]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 9 00:11:30.100986 containerd[1443]: time="2025-05-09T00:11:30.100936057Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:3d0b0be3-1b16-4e8c-824a-21ae8b372636,Namespace:default,Attempt:0,} returns sandbox id \"bda41fc802ee2b6b7c1039ef1b67203e67e85c80178c230d89229d483416ab71\"" May 9 00:11:30.102307 containerd[1443]: time="2025-05-09T00:11:30.102229560Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 9 00:11:30.828623 systemd[1]: run-containerd-runc-k8s.io-bda41fc802ee2b6b7c1039ef1b67203e67e85c80178c230d89229d483416ab71-runc.1IHQig.mount: Deactivated successfully. May 9 00:11:31.006109 kubelet[1704]: E0509 00:11:31.005032 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:31.321033 systemd-networkd[1359]: vethac49fad3: Gained IPv6LL May 9 00:11:31.928215 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4211961366.mount: Deactivated successfully. May 9 00:11:31.995941 kubelet[1704]: E0509 00:11:31.995889 1704 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:32.005396 kubelet[1704]: E0509 00:11:32.005359 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:33.005648 kubelet[1704]: E0509 00:11:33.005599 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:33.245240 containerd[1443]: time="2025-05-09T00:11:33.245183685Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:33.245708 containerd[1443]: time="2025-05-09T00:11:33.245669481Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" May 9 00:11:33.246655 containerd[1443]: time="2025-05-09T00:11:33.246615069Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:33.250048 containerd[1443]: time="2025-05-09T00:11:33.250003841Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:33.251129 containerd[1443]: time="2025-05-09T00:11:33.251095693Z" 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.148829685s" May 9 00:11:33.251172 containerd[1443]: time="2025-05-09T00:11:33.251131498Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" May 9 00:11:33.253501 containerd[1443]: time="2025-05-09T00:11:33.253470586Z" level=info msg="CreateContainer within sandbox \"bda41fc802ee2b6b7c1039ef1b67203e67e85c80178c230d89229d483416ab71\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 9 00:11:33.266295 containerd[1443]: time="2025-05-09T00:11:33.266172460Z" level=info msg="CreateContainer within sandbox \"bda41fc802ee2b6b7c1039ef1b67203e67e85c80178c230d89229d483416ab71\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"20198082f5d8085d7d958bd5f208d81a6abc9562889d1435f9ee4769569c69c5\"" May 9 00:11:33.266986 containerd[1443]: time="2025-05-09T00:11:33.266894614Z" level=info msg="StartContainer for \"20198082f5d8085d7d958bd5f208d81a6abc9562889d1435f9ee4769569c69c5\"" May 9 00:11:33.297117 systemd[1]: Started cri-containerd-20198082f5d8085d7d958bd5f208d81a6abc9562889d1435f9ee4769569c69c5.scope - libcontainer container 20198082f5d8085d7d958bd5f208d81a6abc9562889d1435f9ee4769569c69c5. May 9 00:11:33.318294 containerd[1443]: time="2025-05-09T00:11:33.318235555Z" level=info msg="StartContainer for \"20198082f5d8085d7d958bd5f208d81a6abc9562889d1435f9ee4769569c69c5\" returns successfully" May 9 00:11:34.006540 kubelet[1704]: E0509 00:11:34.006480 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:34.167244 kubelet[1704]: I0509 00:11:34.167136 1704 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.016961776 podStartE2EDuration="5.167119773s" podCreationTimestamp="2025-05-09 00:11:29 +0000 UTC" firstStartedPulling="2025-05-09 00:11:30.101963057 +0000 UTC m=+18.781836123" lastFinishedPulling="2025-05-09 00:11:33.252121094 +0000 UTC m=+21.931994120" observedRunningTime="2025-05-09 00:11:34.16651677 +0000 UTC m=+22.846389836" watchObservedRunningTime="2025-05-09 00:11:34.167119773 +0000 UTC m=+22.846992839" May 9 00:11:35.006654 kubelet[1704]: E0509 00:11:35.006606 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:36.007634 kubelet[1704]: E0509 00:11:36.007584 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:37.008442 kubelet[1704]: E0509 00:11:37.008389 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:38.008715 kubelet[1704]: E0509 00:11:38.008657 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:39.009704 kubelet[1704]: E0509 00:11:39.009653 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:40.010617 kubelet[1704]: E0509 00:11:40.010579 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:41.011178 kubelet[1704]: E0509 00:11:41.011128 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:42.011347 kubelet[1704]: E0509 00:11:42.011312 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:43.012013 kubelet[1704]: E0509 00:11:43.011972 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:43.177585 systemd[1]: Created slice kubepods-besteffort-pod05ba1bd0_1c7d_446b_ba72_a67e092d4c69.slice - libcontainer container kubepods-besteffort-pod05ba1bd0_1c7d_446b_ba72_a67e092d4c69.slice. May 9 00:11:43.196766 kubelet[1704]: I0509 00:11:43.196723 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-47tzr\" (UniqueName: \"kubernetes.io/projected/05ba1bd0-1c7d-446b-ba72-a67e092d4c69-kube-api-access-47tzr\") pod \"test-pod-1\" (UID: \"05ba1bd0-1c7d-446b-ba72-a67e092d4c69\") " pod="default/test-pod-1" May 9 00:11:43.196766 kubelet[1704]: I0509 00:11:43.196768 1704 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-729e899f-02ba-401a-8e15-f9b2bef59af4\" (UniqueName: \"kubernetes.io/nfs/05ba1bd0-1c7d-446b-ba72-a67e092d4c69-pvc-729e899f-02ba-401a-8e15-f9b2bef59af4\") pod \"test-pod-1\" (UID: \"05ba1bd0-1c7d-446b-ba72-a67e092d4c69\") " pod="default/test-pod-1" May 9 00:11:43.320013 kernel: FS-Cache: Loaded May 9 00:11:43.344371 kernel: RPC: Registered named UNIX socket transport module. May 9 00:11:43.344476 kernel: RPC: Registered udp transport module. May 9 00:11:43.344495 kernel: RPC: Registered tcp transport module. May 9 00:11:43.344513 kernel: RPC: Registered tcp-with-tls transport module. May 9 00:11:43.344527 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 9 00:11:43.522201 kernel: NFS: Registering the id_resolver key type May 9 00:11:43.522313 kernel: Key type id_resolver registered May 9 00:11:43.522373 kernel: Key type id_legacy registered May 9 00:11:43.548434 nfsidmap[2746]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 9 00:11:43.552280 nfsidmap[2749]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 9 00:11:43.780310 containerd[1443]: time="2025-05-09T00:11:43.780254966Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:05ba1bd0-1c7d-446b-ba72-a67e092d4c69,Namespace:default,Attempt:0,}" May 9 00:11:44.012951 kubelet[1704]: E0509 00:11:44.012889 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:44.045515 systemd-networkd[1359]: veth40dee172: Link UP May 9 00:11:44.050165 kernel: cni0: port 3(veth40dee172) entered blocking state May 9 00:11:44.050239 kernel: cni0: port 3(veth40dee172) entered disabled state May 9 00:11:44.050256 kernel: veth40dee172: entered allmulticast mode May 9 00:11:44.050270 kernel: veth40dee172: entered promiscuous mode May 9 00:11:44.057850 systemd-networkd[1359]: veth40dee172: Gained carrier May 9 00:11:44.057982 kernel: cni0: port 3(veth40dee172) entered blocking state May 9 00:11:44.058009 kernel: cni0: port 3(veth40dee172) entered forwarding state May 9 00:11:44.059351 containerd[1443]: 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)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} May 9 00:11:44.059351 containerd[1443]: delegateAdd: netconf sent to delegate plugin: May 9 00:11:44.076193 containerd[1443]: {"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-05-09T00:11:44.076088054Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 9 00:11:44.076193 containerd[1443]: time="2025-05-09T00:11:44.076143654Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 9 00:11:44.076193 containerd[1443]: time="2025-05-09T00:11:44.076159375Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:44.076385 containerd[1443]: time="2025-05-09T00:11:44.076236016Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 9 00:11:44.099097 systemd[1]: Started cri-containerd-486cb59937a90de66cb1366ec975e301443d8cb497420d4240969b6fb9e2116e.scope - libcontainer container 486cb59937a90de66cb1366ec975e301443d8cb497420d4240969b6fb9e2116e. May 9 00:11:44.108635 systemd-resolved[1314]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 9 00:11:44.125461 containerd[1443]: time="2025-05-09T00:11:44.125422502Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:05ba1bd0-1c7d-446b-ba72-a67e092d4c69,Namespace:default,Attempt:0,} returns sandbox id \"486cb59937a90de66cb1366ec975e301443d8cb497420d4240969b6fb9e2116e\"" May 9 00:11:44.126613 containerd[1443]: time="2025-05-09T00:11:44.126561881Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 9 00:11:44.337054 containerd[1443]: time="2025-05-09T00:11:44.336714445Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 9 00:11:44.337653 containerd[1443]: time="2025-05-09T00:11:44.337606060Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 9 00:11:44.340898 containerd[1443]: time="2025-05-09T00:11:44.340857273Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"69948737\" in 214.269032ms" May 9 00:11:44.340898 containerd[1443]: time="2025-05-09T00:11:44.340891994Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 9 00:11:44.343088 containerd[1443]: time="2025-05-09T00:11:44.343059749Z" level=info msg="CreateContainer within sandbox \"486cb59937a90de66cb1366ec975e301443d8cb497420d4240969b6fb9e2116e\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 9 00:11:44.355152 containerd[1443]: time="2025-05-09T00:11:44.355096546Z" level=info msg="CreateContainer within sandbox \"486cb59937a90de66cb1366ec975e301443d8cb497420d4240969b6fb9e2116e\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"a7d059466680b6c63af20f2f3a0d54cffaa9f570a59faf0221d663e333033f89\"" May 9 00:11:44.355675 containerd[1443]: time="2025-05-09T00:11:44.355631475Z" level=info msg="StartContainer for \"a7d059466680b6c63af20f2f3a0d54cffaa9f570a59faf0221d663e333033f89\"" May 9 00:11:44.383079 systemd[1]: Started cri-containerd-a7d059466680b6c63af20f2f3a0d54cffaa9f570a59faf0221d663e333033f89.scope - libcontainer container a7d059466680b6c63af20f2f3a0d54cffaa9f570a59faf0221d663e333033f89. May 9 00:11:44.404494 containerd[1443]: time="2025-05-09T00:11:44.404427035Z" level=info msg="StartContainer for \"a7d059466680b6c63af20f2f3a0d54cffaa9f570a59faf0221d663e333033f89\" returns successfully" May 9 00:11:45.013438 kubelet[1704]: E0509 00:11:45.013386 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:45.188472 kubelet[1704]: I0509 00:11:45.188392 1704 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.973061715 podStartE2EDuration="16.188372004s" podCreationTimestamp="2025-05-09 00:11:29 +0000 UTC" firstStartedPulling="2025-05-09 00:11:44.126281716 +0000 UTC m=+32.806154782" lastFinishedPulling="2025-05-09 00:11:44.341592005 +0000 UTC m=+33.021465071" observedRunningTime="2025-05-09 00:11:45.187305068 +0000 UTC m=+33.867178134" watchObservedRunningTime="2025-05-09 00:11:45.188372004 +0000 UTC m=+33.868245070" May 9 00:11:45.976092 systemd-networkd[1359]: veth40dee172: Gained IPv6LL May 9 00:11:46.013673 kubelet[1704]: E0509 00:11:46.013621 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 9 00:11:47.014180 kubelet[1704]: E0509 00:11:47.014111 1704 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"