May 14 17:49:51.858526 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 14 17:49:51.858548 kernel: Linux version 6.12.20-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Wed May 14 16:42:23 -00 2025 May 14 17:49:51.858558 kernel: KASLR enabled May 14 17:49:51.858564 kernel: efi: EFI v2.7 by EDK II May 14 17:49:51.858569 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 May 14 17:49:51.858574 kernel: random: crng init done May 14 17:49:51.858581 kernel: secureboot: Secure boot disabled May 14 17:49:51.858587 kernel: ACPI: Early table checksum verification disabled May 14 17:49:51.858593 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) May 14 17:49:51.858600 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) May 14 17:49:51.858606 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 14 17:49:51.858611 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 14 17:49:51.858617 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 14 17:49:51.858623 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 14 17:49:51.858630 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 14 17:49:51.858637 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 14 17:49:51.858643 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 14 17:49:51.858649 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 14 17:49:51.858655 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 14 17:49:51.858661 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 14 17:49:51.858667 kernel: ACPI: Use ACPI SPCR as default console: Yes May 14 17:49:51.858673 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 14 17:49:51.858679 kernel: NODE_DATA(0) allocated [mem 0xdc965dc0-0xdc96cfff] May 14 17:49:51.858685 kernel: Zone ranges: May 14 17:49:51.858690 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 14 17:49:51.858698 kernel: DMA32 empty May 14 17:49:51.858703 kernel: Normal empty May 14 17:49:51.858709 kernel: Device empty May 14 17:49:51.858715 kernel: Movable zone start for each node May 14 17:49:51.858721 kernel: Early memory node ranges May 14 17:49:51.858727 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] May 14 17:49:51.858733 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] May 14 17:49:51.858739 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] May 14 17:49:51.858744 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] May 14 17:49:51.858750 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] May 14 17:49:51.858756 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] May 14 17:49:51.858762 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] May 14 17:49:51.858769 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] May 14 17:49:51.858775 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] May 14 17:49:51.858781 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] May 14 17:49:51.858789 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] May 14 17:49:51.858796 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] May 14 17:49:51.858802 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] May 14 17:49:51.858810 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 14 17:49:51.858816 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 14 17:49:51.858822 kernel: psci: probing for conduit method from ACPI. May 14 17:49:51.858828 kernel: psci: PSCIv1.1 detected in firmware. May 14 17:49:51.858835 kernel: psci: Using standard PSCI v0.2 function IDs May 14 17:49:51.858841 kernel: psci: Trusted OS migration not required May 14 17:49:51.858847 kernel: psci: SMC Calling Convention v1.1 May 14 17:49:51.858853 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 14 17:49:51.858859 kernel: percpu: Embedded 33 pages/cpu s98136 r8192 d28840 u135168 May 14 17:49:51.858866 kernel: pcpu-alloc: s98136 r8192 d28840 u135168 alloc=33*4096 May 14 17:49:51.858873 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 14 17:49:51.858880 kernel: Detected PIPT I-cache on CPU0 May 14 17:49:51.858886 kernel: CPU features: detected: GIC system register CPU interface May 14 17:49:51.858892 kernel: CPU features: detected: Spectre-v4 May 14 17:49:51.858898 kernel: CPU features: detected: Spectre-BHB May 14 17:49:51.858905 kernel: CPU features: kernel page table isolation forced ON by KASLR May 14 17:49:51.858911 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 14 17:49:51.858917 kernel: CPU features: detected: ARM erratum 1418040 May 14 17:49:51.858924 kernel: CPU features: detected: SSBS not fully self-synchronizing May 14 17:49:51.858930 kernel: alternatives: applying boot alternatives May 14 17:49:51.858937 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=fb5d39925446c9958629410eadbe2d2aa0566996d55f4385bdd8a5ce4ad5f562 May 14 17:49:51.858945 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 14 17:49:51.858952 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 14 17:49:51.858958 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 14 17:49:51.858964 kernel: Fallback order for Node 0: 0 May 14 17:49:51.858971 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 May 14 17:49:51.858977 kernel: Policy zone: DMA May 14 17:49:51.858983 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 14 17:49:51.858989 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB May 14 17:49:51.858996 kernel: software IO TLB: area num 4. May 14 17:49:51.859002 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB May 14 17:49:51.859008 kernel: software IO TLB: mapped [mem 0x00000000d8c00000-0x00000000d9000000] (4MB) May 14 17:49:51.859014 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 14 17:49:51.859022 kernel: rcu: Preemptible hierarchical RCU implementation. May 14 17:49:51.859040 kernel: rcu: RCU event tracing is enabled. May 14 17:49:51.859049 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 14 17:49:51.859055 kernel: Trampoline variant of Tasks RCU enabled. May 14 17:49:51.859062 kernel: Tracing variant of Tasks RCU enabled. May 14 17:49:51.859068 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 14 17:49:51.859075 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 14 17:49:51.859081 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 14 17:49:51.859088 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 14 17:49:51.859094 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 14 17:49:51.859101 kernel: GICv3: 256 SPIs implemented May 14 17:49:51.859109 kernel: GICv3: 0 Extended SPIs implemented May 14 17:49:51.859124 kernel: Root IRQ handler: gic_handle_irq May 14 17:49:51.859131 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 14 17:49:51.859137 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 May 14 17:49:51.859144 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 14 17:49:51.859150 kernel: ITS [mem 0x08080000-0x0809ffff] May 14 17:49:51.859157 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400e0000 (indirect, esz 8, psz 64K, shr 1) May 14 17:49:51.859163 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400f0000 (flat, esz 8, psz 64K, shr 1) May 14 17:49:51.859170 kernel: GICv3: using LPI property table @0x0000000040100000 May 14 17:49:51.859176 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040110000 May 14 17:49:51.859183 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 14 17:49:51.859189 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 14 17:49:51.859197 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 14 17:49:51.859204 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 14 17:49:51.859210 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 14 17:49:51.859216 kernel: arm-pv: using stolen time PV May 14 17:49:51.859223 kernel: Console: colour dummy device 80x25 May 14 17:49:51.859230 kernel: ACPI: Core revision 20240827 May 14 17:49:51.859236 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 14 17:49:51.859243 kernel: pid_max: default: 32768 minimum: 301 May 14 17:49:51.859249 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 14 17:49:51.859257 kernel: landlock: Up and running. May 14 17:49:51.859263 kernel: SELinux: Initializing. May 14 17:49:51.859270 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 14 17:49:51.859277 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 14 17:49:51.859283 kernel: rcu: Hierarchical SRCU implementation. May 14 17:49:51.859290 kernel: rcu: Max phase no-delay instances is 400. May 14 17:49:51.859296 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 14 17:49:51.859303 kernel: Remapping and enabling EFI services. May 14 17:49:51.859309 kernel: smp: Bringing up secondary CPUs ... May 14 17:49:51.859316 kernel: Detected PIPT I-cache on CPU1 May 14 17:49:51.859328 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 14 17:49:51.859335 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040120000 May 14 17:49:51.859343 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 14 17:49:51.859350 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 14 17:49:51.859357 kernel: Detected PIPT I-cache on CPU2 May 14 17:49:51.859364 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 14 17:49:51.859371 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040130000 May 14 17:49:51.859379 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 14 17:49:51.859386 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 14 17:49:51.859393 kernel: Detected PIPT I-cache on CPU3 May 14 17:49:51.859400 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 14 17:49:51.859407 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040140000 May 14 17:49:51.859414 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 14 17:49:51.859420 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 14 17:49:51.859427 kernel: smp: Brought up 1 node, 4 CPUs May 14 17:49:51.859434 kernel: SMP: Total of 4 processors activated. May 14 17:49:51.859441 kernel: CPU: All CPU(s) started at EL1 May 14 17:49:51.859449 kernel: CPU features: detected: 32-bit EL0 Support May 14 17:49:51.859456 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 14 17:49:51.859463 kernel: CPU features: detected: Common not Private translations May 14 17:49:51.859470 kernel: CPU features: detected: CRC32 instructions May 14 17:49:51.859477 kernel: CPU features: detected: Enhanced Virtualization Traps May 14 17:49:51.859484 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 14 17:49:51.859491 kernel: CPU features: detected: LSE atomic instructions May 14 17:49:51.859498 kernel: CPU features: detected: Privileged Access Never May 14 17:49:51.859505 kernel: CPU features: detected: RAS Extension Support May 14 17:49:51.859513 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 14 17:49:51.859519 kernel: alternatives: applying system-wide alternatives May 14 17:49:51.859526 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 May 14 17:49:51.859534 kernel: Memory: 2440984K/2572288K available (11072K kernel code, 2276K rwdata, 8928K rodata, 39424K init, 1034K bss, 125536K reserved, 0K cma-reserved) May 14 17:49:51.859540 kernel: devtmpfs: initialized May 14 17:49:51.859548 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 14 17:49:51.859554 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 14 17:49:51.859561 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 14 17:49:51.859568 kernel: 0 pages in range for non-PLT usage May 14 17:49:51.859576 kernel: 508544 pages in range for PLT usage May 14 17:49:51.859583 kernel: pinctrl core: initialized pinctrl subsystem May 14 17:49:51.859589 kernel: SMBIOS 3.0.0 present. May 14 17:49:51.859596 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 May 14 17:49:51.859603 kernel: DMI: Memory slots populated: 1/1 May 14 17:49:51.859610 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 14 17:49:51.859617 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 14 17:49:51.859624 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 14 17:49:51.859631 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 14 17:49:51.859639 kernel: audit: initializing netlink subsys (disabled) May 14 17:49:51.859646 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 May 14 17:49:51.859653 kernel: thermal_sys: Registered thermal governor 'step_wise' May 14 17:49:51.859659 kernel: cpuidle: using governor menu May 14 17:49:51.859666 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 14 17:49:51.859673 kernel: ASID allocator initialised with 32768 entries May 14 17:49:51.859680 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 14 17:49:51.859687 kernel: Serial: AMBA PL011 UART driver May 14 17:49:51.859694 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 14 17:49:51.859702 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 14 17:49:51.859709 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 14 17:49:51.859716 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 14 17:49:51.859722 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 14 17:49:51.859729 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 14 17:49:51.859736 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 14 17:49:51.859743 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 14 17:49:51.859750 kernel: ACPI: Added _OSI(Module Device) May 14 17:49:51.859757 kernel: ACPI: Added _OSI(Processor Device) May 14 17:49:51.859765 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 14 17:49:51.859772 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 14 17:49:51.859779 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 14 17:49:51.859786 kernel: ACPI: Interpreter enabled May 14 17:49:51.859792 kernel: ACPI: Using GIC for interrupt routing May 14 17:49:51.859799 kernel: ACPI: MCFG table detected, 1 entries May 14 17:49:51.859806 kernel: ACPI: CPU0 has been hot-added May 14 17:49:51.859813 kernel: ACPI: CPU1 has been hot-added May 14 17:49:51.859820 kernel: ACPI: CPU2 has been hot-added May 14 17:49:51.859826 kernel: ACPI: CPU3 has been hot-added May 14 17:49:51.859835 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 14 17:49:51.859842 kernel: printk: legacy console [ttyAMA0] enabled May 14 17:49:51.859849 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 14 17:49:51.859988 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 14 17:49:51.860087 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 14 17:49:51.860163 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 14 17:49:51.860224 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 14 17:49:51.860284 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 14 17:49:51.860293 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 14 17:49:51.860300 kernel: PCI host bridge to bus 0000:00 May 14 17:49:51.860364 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 14 17:49:51.860420 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 14 17:49:51.860472 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 14 17:49:51.860523 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 14 17:49:51.860599 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint May 14 17:49:51.860669 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint May 14 17:49:51.860730 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] May 14 17:49:51.860789 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] May 14 17:49:51.860846 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] May 14 17:49:51.860905 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned May 14 17:49:51.860962 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned May 14 17:49:51.861022 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned May 14 17:49:51.861088 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 14 17:49:51.861148 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 14 17:49:51.861202 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 14 17:49:51.861211 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 14 17:49:51.861218 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 14 17:49:51.861225 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 14 17:49:51.861235 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 14 17:49:51.861242 kernel: iommu: Default domain type: Translated May 14 17:49:51.861249 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 14 17:49:51.861256 kernel: efivars: Registered efivars operations May 14 17:49:51.861263 kernel: vgaarb: loaded May 14 17:49:51.861269 kernel: clocksource: Switched to clocksource arch_sys_counter May 14 17:49:51.861276 kernel: VFS: Disk quotas dquot_6.6.0 May 14 17:49:51.861283 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 14 17:49:51.861290 kernel: pnp: PnP ACPI init May 14 17:49:51.861362 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 14 17:49:51.861372 kernel: pnp: PnP ACPI: found 1 devices May 14 17:49:51.861379 kernel: NET: Registered PF_INET protocol family May 14 17:49:51.861386 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 14 17:49:51.861393 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 14 17:49:51.861401 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 14 17:49:51.861408 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 14 17:49:51.861415 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 14 17:49:51.861424 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 14 17:49:51.861431 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 14 17:49:51.861438 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 14 17:49:51.861445 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 14 17:49:51.861452 kernel: PCI: CLS 0 bytes, default 64 May 14 17:49:51.861459 kernel: kvm [1]: HYP mode not available May 14 17:49:51.861467 kernel: Initialise system trusted keyrings May 14 17:49:51.861473 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 14 17:49:51.861480 kernel: Key type asymmetric registered May 14 17:49:51.861488 kernel: Asymmetric key parser 'x509' registered May 14 17:49:51.861495 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 14 17:49:51.861502 kernel: io scheduler mq-deadline registered May 14 17:49:51.861509 kernel: io scheduler kyber registered May 14 17:49:51.861516 kernel: io scheduler bfq registered May 14 17:49:51.861523 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 14 17:49:51.861530 kernel: ACPI: button: Power Button [PWRB] May 14 17:49:51.861537 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 14 17:49:51.861597 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 14 17:49:51.861608 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 14 17:49:51.861615 kernel: thunder_xcv, ver 1.0 May 14 17:49:51.861622 kernel: thunder_bgx, ver 1.0 May 14 17:49:51.861630 kernel: nicpf, ver 1.0 May 14 17:49:51.861637 kernel: nicvf, ver 1.0 May 14 17:49:51.861707 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 14 17:49:51.861774 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-14T17:49:51 UTC (1747244991) May 14 17:49:51.861786 kernel: hid: raw HID events driver (C) Jiri Kosina May 14 17:49:51.861795 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available May 14 17:49:51.861803 kernel: watchdog: NMI not fully supported May 14 17:49:51.861812 kernel: watchdog: Hard watchdog permanently disabled May 14 17:49:51.861821 kernel: NET: Registered PF_INET6 protocol family May 14 17:49:51.861828 kernel: Segment Routing with IPv6 May 14 17:49:51.861835 kernel: In-situ OAM (IOAM) with IPv6 May 14 17:49:51.861845 kernel: NET: Registered PF_PACKET protocol family May 14 17:49:51.861854 kernel: Key type dns_resolver registered May 14 17:49:51.861863 kernel: registered taskstats version 1 May 14 17:49:51.861870 kernel: Loading compiled-in X.509 certificates May 14 17:49:51.861879 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.20-flatcar: c0c250ba312a1bb9bceb2432c486db6e5999df1a' May 14 17:49:51.861886 kernel: Demotion targets for Node 0: null May 14 17:49:51.861893 kernel: Key type .fscrypt registered May 14 17:49:51.861900 kernel: Key type fscrypt-provisioning registered May 14 17:49:51.861908 kernel: ima: No TPM chip found, activating TPM-bypass! May 14 17:49:51.861917 kernel: ima: Allocated hash algorithm: sha1 May 14 17:49:51.861923 kernel: ima: No architecture policies found May 14 17:49:51.861931 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 14 17:49:51.861942 kernel: clk: Disabling unused clocks May 14 17:49:51.861951 kernel: PM: genpd: Disabling unused power domains May 14 17:49:51.861959 kernel: Warning: unable to open an initial console. May 14 17:49:51.861967 kernel: Freeing unused kernel memory: 39424K May 14 17:49:51.861974 kernel: Run /init as init process May 14 17:49:51.861982 kernel: with arguments: May 14 17:49:51.861995 kernel: /init May 14 17:49:51.862002 kernel: with environment: May 14 17:49:51.862009 kernel: HOME=/ May 14 17:49:51.862018 kernel: TERM=linux May 14 17:49:51.862025 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 14 17:49:51.862040 systemd[1]: Successfully made /usr/ read-only. May 14 17:49:51.862051 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 14 17:49:51.862060 systemd[1]: Detected virtualization kvm. May 14 17:49:51.862067 systemd[1]: Detected architecture arm64. May 14 17:49:51.862074 systemd[1]: Running in initrd. May 14 17:49:51.862081 systemd[1]: No hostname configured, using default hostname. May 14 17:49:51.862091 systemd[1]: Hostname set to . May 14 17:49:51.862098 systemd[1]: Initializing machine ID from VM UUID. May 14 17:49:51.862106 systemd[1]: Queued start job for default target initrd.target. May 14 17:49:51.862119 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 17:49:51.862127 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 17:49:51.862135 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 14 17:49:51.862143 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 17:49:51.862150 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 14 17:49:51.862160 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 14 17:49:51.862168 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 14 17:49:51.862176 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 14 17:49:51.862183 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 17:49:51.862191 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 17:49:51.862198 systemd[1]: Reached target paths.target - Path Units. May 14 17:49:51.862208 systemd[1]: Reached target slices.target - Slice Units. May 14 17:49:51.862215 systemd[1]: Reached target swap.target - Swaps. May 14 17:49:51.862223 systemd[1]: Reached target timers.target - Timer Units. May 14 17:49:51.862230 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 14 17:49:51.862238 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 17:49:51.862245 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 14 17:49:51.862253 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 14 17:49:51.862261 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 17:49:51.862268 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 17:49:51.862277 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 17:49:51.862284 systemd[1]: Reached target sockets.target - Socket Units. May 14 17:49:51.862292 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 14 17:49:51.862300 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 17:49:51.862307 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 14 17:49:51.862316 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). May 14 17:49:51.862324 systemd[1]: Starting systemd-fsck-usr.service... May 14 17:49:51.862331 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 17:49:51.862340 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 17:49:51.862348 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 17:49:51.862355 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 14 17:49:51.862364 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 17:49:51.862372 systemd[1]: Finished systemd-fsck-usr.service. May 14 17:49:51.862381 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 14 17:49:51.862409 systemd-journald[242]: Collecting audit messages is disabled. May 14 17:49:51.862429 systemd-journald[242]: Journal started May 14 17:49:51.862450 systemd-journald[242]: Runtime Journal (/run/log/journal/438469a42f874bd3b9b26ffa98677525) is 6M, max 48.5M, 42.4M free. May 14 17:49:51.851749 systemd-modules-load[245]: Inserted module 'overlay' May 14 17:49:51.867586 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 17:49:51.867609 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 14 17:49:51.869578 systemd-modules-load[245]: Inserted module 'br_netfilter' May 14 17:49:51.871314 kernel: Bridge firewalling registered May 14 17:49:51.871335 systemd[1]: Started systemd-journald.service - Journal Service. May 14 17:49:51.872457 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 17:49:51.873695 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 14 17:49:51.877998 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 14 17:49:51.880891 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 17:49:51.890615 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 17:49:51.892330 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 17:49:51.898003 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 17:49:51.904023 systemd-tmpfiles[269]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 14 17:49:51.906848 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 17:49:51.908383 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 17:49:51.910594 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 17:49:51.914072 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 14 17:49:51.916510 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 17:49:51.934484 dracut-cmdline[287]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=fb5d39925446c9958629410eadbe2d2aa0566996d55f4385bdd8a5ce4ad5f562 May 14 17:49:51.951238 systemd-resolved[289]: Positive Trust Anchors: May 14 17:49:51.951257 systemd-resolved[289]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 17:49:51.951293 systemd-resolved[289]: 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 14 17:49:51.956300 systemd-resolved[289]: Defaulting to hostname 'linux'. May 14 17:49:51.957356 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 17:49:51.960514 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 17:49:52.011062 kernel: SCSI subsystem initialized May 14 17:49:52.016053 kernel: Loading iSCSI transport class v2.0-870. May 14 17:49:52.024078 kernel: iscsi: registered transport (tcp) May 14 17:49:52.037051 kernel: iscsi: registered transport (qla4xxx) May 14 17:49:52.037104 kernel: QLogic iSCSI HBA Driver May 14 17:49:52.053982 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 14 17:49:52.076082 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 14 17:49:52.078752 systemd[1]: Reached target network-pre.target - Preparation for Network. May 14 17:49:52.134596 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 14 17:49:52.137121 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 14 17:49:52.199081 kernel: raid6: neonx8 gen() 15782 MB/s May 14 17:49:52.216089 kernel: raid6: neonx4 gen() 15761 MB/s May 14 17:49:52.233076 kernel: raid6: neonx2 gen() 13199 MB/s May 14 17:49:52.250070 kernel: raid6: neonx1 gen() 10291 MB/s May 14 17:49:52.267085 kernel: raid6: int64x8 gen() 6881 MB/s May 14 17:49:52.284066 kernel: raid6: int64x4 gen() 7341 MB/s May 14 17:49:52.301075 kernel: raid6: int64x2 gen() 6098 MB/s May 14 17:49:52.318203 kernel: raid6: int64x1 gen() 5046 MB/s May 14 17:49:52.318237 kernel: raid6: using algorithm neonx8 gen() 15782 MB/s May 14 17:49:52.336130 kernel: raid6: .... xor() 12069 MB/s, rmw enabled May 14 17:49:52.336154 kernel: raid6: using neon recovery algorithm May 14 17:49:52.341424 kernel: xor: measuring software checksum speed May 14 17:49:52.341444 kernel: 8regs : 21613 MB/sec May 14 17:49:52.342118 kernel: 32regs : 21630 MB/sec May 14 17:49:52.343341 kernel: arm64_neon : 28013 MB/sec May 14 17:49:52.343356 kernel: xor: using function: arm64_neon (28013 MB/sec) May 14 17:49:52.399721 kernel: Btrfs loaded, zoned=no, fsverity=no May 14 17:49:52.406539 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 14 17:49:52.408932 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 17:49:52.442282 systemd-udevd[499]: Using default interface naming scheme 'v255'. May 14 17:49:52.446387 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 17:49:52.448292 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 14 17:49:52.472668 dracut-pre-trigger[508]: rd.md=0: removing MD RAID activation May 14 17:49:52.495988 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 14 17:49:52.498193 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 17:49:52.545156 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 17:49:52.548194 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 14 17:49:52.601071 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues May 14 17:49:52.621847 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 14 17:49:52.621956 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 14 17:49:52.621977 kernel: GPT:9289727 != 19775487 May 14 17:49:52.621987 kernel: GPT:Alternate GPT header not at the end of the disk. May 14 17:49:52.621996 kernel: GPT:9289727 != 19775487 May 14 17:49:52.622005 kernel: GPT: Use GNU Parted to correct GPT errors. May 14 17:49:52.622013 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 14 17:49:52.610507 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 17:49:52.610620 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 17:49:52.612022 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 14 17:49:52.613863 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 17:49:52.640427 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 17:49:52.667951 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 14 17:49:52.675613 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 14 17:49:52.677164 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 14 17:49:52.684098 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 14 17:49:52.685320 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 14 17:49:52.695242 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 14 17:49:52.696560 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 14 17:49:52.698558 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 17:49:52.700680 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 17:49:52.703377 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 14 17:49:52.705170 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 14 17:49:52.719618 disk-uuid[592]: Primary Header is updated. May 14 17:49:52.719618 disk-uuid[592]: Secondary Entries is updated. May 14 17:49:52.719618 disk-uuid[592]: Secondary Header is updated. May 14 17:49:52.724831 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 14 17:49:52.728052 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 14 17:49:52.731042 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 14 17:49:53.761122 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 14 17:49:53.761635 disk-uuid[596]: The operation has completed successfully. May 14 17:49:53.783845 systemd[1]: disk-uuid.service: Deactivated successfully. May 14 17:49:53.783937 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 14 17:49:53.811928 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 14 17:49:53.826969 sh[612]: Success May 14 17:49:53.841651 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 14 17:49:53.844084 kernel: device-mapper: uevent: version 1.0.3 May 14 17:49:53.846067 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 14 17:49:53.859195 kernel: device-mapper: verity: sha256 using shash "sha256-ce" May 14 17:49:53.887725 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 14 17:49:53.891260 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 14 17:49:53.908687 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 14 17:49:53.915321 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 14 17:49:53.915369 kernel: BTRFS: device fsid e21bbf34-4c71-4257-bd6f-908a2b81e5ab devid 1 transid 41 /dev/mapper/usr (253:0) scanned by mount (624) May 14 17:49:53.919463 kernel: BTRFS info (device dm-0): first mount of filesystem e21bbf34-4c71-4257-bd6f-908a2b81e5ab May 14 17:49:53.919496 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 14 17:49:53.919506 kernel: BTRFS info (device dm-0): using free-space-tree May 14 17:49:53.925450 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 14 17:49:53.926745 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 14 17:49:53.928732 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 14 17:49:53.929568 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 14 17:49:53.931175 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 14 17:49:53.954150 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 (254:6) scanned by mount (653) May 14 17:49:53.954213 kernel: BTRFS info (device vda6): first mount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 17:49:53.956277 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 14 17:49:53.956329 kernel: BTRFS info (device vda6): using free-space-tree May 14 17:49:53.964058 kernel: BTRFS info (device vda6): last unmount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 17:49:53.964810 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 14 17:49:53.967234 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 14 17:49:54.043133 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 17:49:54.046119 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 17:49:54.093932 systemd-networkd[797]: lo: Link UP May 14 17:49:54.093946 systemd-networkd[797]: lo: Gained carrier May 14 17:49:54.095089 systemd-networkd[797]: Enumeration completed May 14 17:49:54.095456 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 17:49:54.096058 systemd-networkd[797]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 17:49:54.096062 systemd-networkd[797]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 17:49:54.096624 systemd-networkd[797]: eth0: Link UP May 14 17:49:54.096627 systemd-networkd[797]: eth0: Gained carrier May 14 17:49:54.096635 systemd-networkd[797]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 17:49:54.098411 systemd[1]: Reached target network.target - Network. May 14 17:49:54.114104 systemd-networkd[797]: eth0: DHCPv4 address 10.0.0.11/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 14 17:49:54.132153 ignition[698]: Ignition 2.21.0 May 14 17:49:54.132164 ignition[698]: Stage: fetch-offline May 14 17:49:54.132196 ignition[698]: no configs at "/usr/lib/ignition/base.d" May 14 17:49:54.132204 ignition[698]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 17:49:54.132442 ignition[698]: parsed url from cmdline: "" May 14 17:49:54.132445 ignition[698]: no config URL provided May 14 17:49:54.132450 ignition[698]: reading system config file "/usr/lib/ignition/user.ign" May 14 17:49:54.132456 ignition[698]: no config at "/usr/lib/ignition/user.ign" May 14 17:49:54.132477 ignition[698]: op(1): [started] loading QEMU firmware config module May 14 17:49:54.132486 ignition[698]: op(1): executing: "modprobe" "qemu_fw_cfg" May 14 17:49:54.143367 ignition[698]: op(1): [finished] loading QEMU firmware config module May 14 17:49:54.143396 ignition[698]: QEMU firmware config was not found. Ignoring... May 14 17:49:54.150171 ignition[698]: parsing config with SHA512: a4382464d370e83194db82144caea5934f2c347b73580256ec2adf987ab589a6a0d0db40989712c0f8871401dd4805e2bb5611ea6e3ac4ea1be7100eb8ffc3c0 May 14 17:49:54.153624 unknown[698]: fetched base config from "system" May 14 17:49:54.153648 unknown[698]: fetched user config from "qemu" May 14 17:49:54.153918 ignition[698]: fetch-offline: fetch-offline passed May 14 17:49:54.153970 ignition[698]: Ignition finished successfully May 14 17:49:54.157677 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 14 17:49:54.161630 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 14 17:49:54.162635 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 14 17:49:54.195065 ignition[812]: Ignition 2.21.0 May 14 17:49:54.195081 ignition[812]: Stage: kargs May 14 17:49:54.195219 ignition[812]: no configs at "/usr/lib/ignition/base.d" May 14 17:49:54.195228 ignition[812]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 17:49:54.195772 ignition[812]: kargs: kargs passed May 14 17:49:54.197823 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 14 17:49:54.195815 ignition[812]: Ignition finished successfully May 14 17:49:54.199938 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 14 17:49:54.230493 ignition[820]: Ignition 2.21.0 May 14 17:49:54.230510 ignition[820]: Stage: disks May 14 17:49:54.230645 ignition[820]: no configs at "/usr/lib/ignition/base.d" May 14 17:49:54.230655 ignition[820]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 17:49:54.233548 ignition[820]: disks: disks passed May 14 17:49:54.233603 ignition[820]: Ignition finished successfully May 14 17:49:54.236114 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 14 17:49:54.237372 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 14 17:49:54.238974 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 14 17:49:54.240158 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 17:49:54.241862 systemd[1]: Reached target sysinit.target - System Initialization. May 14 17:49:54.243841 systemd[1]: Reached target basic.target - Basic System. May 14 17:49:54.246463 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 14 17:49:54.278462 systemd-fsck[830]: ROOT: clean, 15/553520 files, 52789/553472 blocks May 14 17:49:54.282637 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 14 17:49:54.286425 systemd[1]: Mounting sysroot.mount - /sysroot... May 14 17:49:54.379061 kernel: EXT4-fs (vda9): mounted filesystem a9c1ea72-ce96-48c1-8c16-d7102e51beed r/w with ordered data mode. Quota mode: none. May 14 17:49:54.380212 systemd[1]: Mounted sysroot.mount - /sysroot. May 14 17:49:54.381788 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 14 17:49:54.389123 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 17:49:54.390904 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 14 17:49:54.391929 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 14 17:49:54.391993 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 14 17:49:54.392042 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 14 17:49:54.402111 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 14 17:49:54.404533 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 14 17:49:54.412936 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 (254:6) scanned by mount (838) May 14 17:49:54.413277 kernel: BTRFS info (device vda6): first mount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 17:49:54.413295 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 14 17:49:54.415056 kernel: BTRFS info (device vda6): using free-space-tree May 14 17:49:54.431571 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 17:49:54.487789 initrd-setup-root[862]: cut: /sysroot/etc/passwd: No such file or directory May 14 17:49:54.493305 initrd-setup-root[869]: cut: /sysroot/etc/group: No such file or directory May 14 17:49:54.497348 initrd-setup-root[876]: cut: /sysroot/etc/shadow: No such file or directory May 14 17:49:54.500981 initrd-setup-root[883]: cut: /sysroot/etc/gshadow: No such file or directory May 14 17:49:54.592588 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 14 17:49:54.594720 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 14 17:49:54.598332 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 14 17:49:54.613062 kernel: BTRFS info (device vda6): last unmount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 17:49:54.646096 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 14 17:49:54.651045 ignition[952]: INFO : Ignition 2.21.0 May 14 17:49:54.652074 ignition[952]: INFO : Stage: mount May 14 17:49:54.652074 ignition[952]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 17:49:54.652074 ignition[952]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 17:49:54.652074 ignition[952]: INFO : mount: mount passed May 14 17:49:54.652074 ignition[952]: INFO : Ignition finished successfully May 14 17:49:54.653822 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 14 17:49:54.660280 systemd[1]: Starting ignition-files.service - Ignition (files)... May 14 17:49:54.914119 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 14 17:49:54.917954 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 14 17:49:54.938752 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 (254:6) scanned by mount (964) May 14 17:49:54.938796 kernel: BTRFS info (device vda6): first mount of filesystem 6d47052f-e956-47a0-903a-525ae08a05f2 May 14 17:49:54.938807 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 14 17:49:54.940671 kernel: BTRFS info (device vda6): using free-space-tree May 14 17:49:54.943143 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 14 17:49:54.974525 ignition[981]: INFO : Ignition 2.21.0 May 14 17:49:54.974525 ignition[981]: INFO : Stage: files May 14 17:49:54.976164 ignition[981]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 17:49:54.976164 ignition[981]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 17:49:54.976164 ignition[981]: DEBUG : files: compiled without relabeling support, skipping May 14 17:49:54.979414 ignition[981]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 14 17:49:54.979414 ignition[981]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 14 17:49:54.983072 ignition[981]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 14 17:49:54.984529 ignition[981]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 14 17:49:54.986142 ignition[981]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 14 17:49:54.985980 unknown[981]: wrote ssh authorized keys file for user: core May 14 17:49:54.991712 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 14 17:49:54.991712 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 14 17:49:54.996856 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 14 17:49:54.996856 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 14 17:49:54.996856 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 14 17:49:55.004076 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 14 17:49:55.004076 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 14 17:49:55.004076 ignition[981]: 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 14 17:49:55.312860 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 14 17:49:55.683347 ignition[981]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-arm64.raw" May 14 17:49:55.683347 ignition[981]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 14 17:49:55.686914 ignition[981]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 14 17:49:55.689962 ignition[981]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 14 17:49:55.689962 ignition[981]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 14 17:49:55.693147 ignition[981]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" May 14 17:49:55.704211 ignition[981]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" May 14 17:49:55.707344 ignition[981]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 14 17:49:55.709879 ignition[981]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" May 14 17:49:55.709879 ignition[981]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 14 17:49:55.709879 ignition[981]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 14 17:49:55.709879 ignition[981]: INFO : files: files passed May 14 17:49:55.709879 ignition[981]: INFO : Ignition finished successfully May 14 17:49:55.710568 systemd[1]: Finished ignition-files.service - Ignition (files). May 14 17:49:55.712860 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 14 17:49:55.714844 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 14 17:49:55.728745 systemd[1]: ignition-quench.service: Deactivated successfully. May 14 17:49:55.728838 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 14 17:49:55.731507 initrd-setup-root-after-ignition[1010]: grep: /sysroot/oem/oem-release: No such file or directory May 14 17:49:55.732731 initrd-setup-root-after-ignition[1013]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 17:49:55.732731 initrd-setup-root-after-ignition[1013]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 14 17:49:55.735327 initrd-setup-root-after-ignition[1017]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 14 17:49:55.734910 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 17:49:55.737378 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 14 17:49:55.738939 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 14 17:49:55.743430 systemd-networkd[797]: eth0: Gained IPv6LL May 14 17:49:55.782913 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 14 17:49:55.783060 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 14 17:49:55.785253 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 14 17:49:55.786890 systemd[1]: Reached target initrd.target - Initrd Default Target. May 14 17:49:55.788560 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 14 17:49:55.789371 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 14 17:49:55.802754 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 17:49:55.805000 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 14 17:49:55.822636 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 14 17:49:55.823807 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 17:49:55.825668 systemd[1]: Stopped target timers.target - Timer Units. May 14 17:49:55.827203 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 14 17:49:55.827326 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 14 17:49:55.829532 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 14 17:49:55.831233 systemd[1]: Stopped target basic.target - Basic System. May 14 17:49:55.832640 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 14 17:49:55.834090 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 14 17:49:55.835888 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 14 17:49:55.837710 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 14 17:49:55.839371 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 14 17:49:55.840912 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 14 17:49:55.842771 systemd[1]: Stopped target sysinit.target - System Initialization. May 14 17:49:55.844613 systemd[1]: Stopped target local-fs.target - Local File Systems. May 14 17:49:55.846106 systemd[1]: Stopped target swap.target - Swaps. May 14 17:49:55.847548 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 14 17:49:55.847681 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 14 17:49:55.849813 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 14 17:49:55.850908 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 17:49:55.852696 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 14 17:49:55.857108 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 17:49:55.858197 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 14 17:49:55.858322 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 14 17:49:55.860751 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 14 17:49:55.860860 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 14 17:49:55.862905 systemd[1]: Stopped target paths.target - Path Units. May 14 17:49:55.864364 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 14 17:49:55.864477 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 17:49:55.866165 systemd[1]: Stopped target slices.target - Slice Units. May 14 17:49:55.867550 systemd[1]: Stopped target sockets.target - Socket Units. May 14 17:49:55.869132 systemd[1]: iscsid.socket: Deactivated successfully. May 14 17:49:55.869220 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 14 17:49:55.871154 systemd[1]: iscsiuio.socket: Deactivated successfully. May 14 17:49:55.871238 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 14 17:49:55.872794 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 14 17:49:55.872911 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 14 17:49:55.874423 systemd[1]: ignition-files.service: Deactivated successfully. May 14 17:49:55.874519 systemd[1]: Stopped ignition-files.service - Ignition (files). May 14 17:49:55.876672 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 14 17:49:55.878643 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 14 17:49:55.879448 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 14 17:49:55.879560 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 14 17:49:55.881307 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 14 17:49:55.881411 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 14 17:49:55.886322 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 14 17:49:55.891176 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 14 17:49:55.899512 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 14 17:49:55.904876 ignition[1037]: INFO : Ignition 2.21.0 May 14 17:49:55.904876 ignition[1037]: INFO : Stage: umount May 14 17:49:55.904876 ignition[1037]: INFO : no configs at "/usr/lib/ignition/base.d" May 14 17:49:55.904876 ignition[1037]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 14 17:49:55.909537 ignition[1037]: INFO : umount: umount passed May 14 17:49:55.909537 ignition[1037]: INFO : Ignition finished successfully May 14 17:49:55.911700 systemd[1]: ignition-mount.service: Deactivated successfully. May 14 17:49:55.911834 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 14 17:49:55.913717 systemd[1]: Stopped target network.target - Network. May 14 17:49:55.914602 systemd[1]: ignition-disks.service: Deactivated successfully. May 14 17:49:55.914659 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 14 17:49:55.916163 systemd[1]: ignition-kargs.service: Deactivated successfully. May 14 17:49:55.916206 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 14 17:49:55.917752 systemd[1]: ignition-setup.service: Deactivated successfully. May 14 17:49:55.917799 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 14 17:49:55.919331 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 14 17:49:55.919368 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 14 17:49:55.920998 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 14 17:49:55.922484 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 14 17:49:55.929774 systemd[1]: systemd-resolved.service: Deactivated successfully. May 14 17:49:55.929879 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 14 17:49:55.933900 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 14 17:49:55.934149 systemd[1]: systemd-networkd.service: Deactivated successfully. May 14 17:49:55.934255 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 14 17:49:55.938453 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 14 17:49:55.938969 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 14 17:49:55.940097 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 14 17:49:55.940136 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 14 17:49:55.942787 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 14 17:49:55.943661 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 14 17:49:55.943715 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 14 17:49:55.945482 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 14 17:49:55.945526 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 14 17:49:55.948003 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 14 17:49:55.948062 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 14 17:49:55.949768 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 14 17:49:55.949811 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 17:49:55.953249 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 17:49:55.958194 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 14 17:49:55.958276 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 14 17:49:55.959881 systemd[1]: sysroot-boot.service: Deactivated successfully. May 14 17:49:55.959982 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 14 17:49:55.962476 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 14 17:49:55.962555 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 14 17:49:55.983682 systemd[1]: systemd-udevd.service: Deactivated successfully. May 14 17:49:55.983822 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 17:49:55.985802 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 14 17:49:55.985837 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 14 17:49:55.987651 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 14 17:49:55.987679 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 14 17:49:55.989217 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 14 17:49:55.989262 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 14 17:49:55.991687 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 14 17:49:55.991733 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 14 17:49:55.994106 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 14 17:49:55.994164 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 14 17:49:55.997340 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 14 17:49:55.998326 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 14 17:49:55.998388 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 14 17:49:56.002144 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 14 17:49:56.002193 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 17:49:56.004153 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 14 17:49:56.004194 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 14 17:49:56.007128 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 14 17:49:56.007173 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 14 17:49:56.009093 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 14 17:49:56.009135 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 14 17:49:56.013154 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. May 14 17:49:56.013203 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. May 14 17:49:56.013230 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 14 17:49:56.013260 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 14 17:49:56.013489 systemd[1]: network-cleanup.service: Deactivated successfully. May 14 17:49:56.023263 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 14 17:49:56.028140 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 14 17:49:56.029100 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 14 17:49:56.031346 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 14 17:49:56.033011 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 14 17:49:56.062747 systemd[1]: Switching root. May 14 17:49:56.090137 systemd-journald[242]: Journal stopped May 14 17:49:56.859498 systemd-journald[242]: Received SIGTERM from PID 1 (systemd). May 14 17:49:56.859554 kernel: SELinux: policy capability network_peer_controls=1 May 14 17:49:56.859566 kernel: SELinux: policy capability open_perms=1 May 14 17:49:56.859577 kernel: SELinux: policy capability extended_socket_class=1 May 14 17:49:56.859588 kernel: SELinux: policy capability always_check_network=0 May 14 17:49:56.859598 kernel: SELinux: policy capability cgroup_seclabel=1 May 14 17:49:56.859608 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 14 17:49:56.859617 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 14 17:49:56.859626 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 14 17:49:56.859636 kernel: SELinux: policy capability userspace_initial_context=0 May 14 17:49:56.859646 kernel: audit: type=1403 audit(1747244996.245:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 14 17:49:56.859657 systemd[1]: Successfully loaded SELinux policy in 54.388ms. May 14 17:49:56.859674 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.205ms. May 14 17:49:56.859686 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 14 17:49:56.859698 systemd[1]: Detected virtualization kvm. May 14 17:49:56.859707 systemd[1]: Detected architecture arm64. May 14 17:49:56.859717 systemd[1]: Detected first boot. May 14 17:49:56.859726 systemd[1]: Initializing machine ID from VM UUID. May 14 17:49:56.859736 zram_generator::config[1082]: No configuration found. May 14 17:49:56.859747 kernel: NET: Registered PF_VSOCK protocol family May 14 17:49:56.859765 systemd[1]: Populated /etc with preset unit settings. May 14 17:49:56.859776 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 14 17:49:56.859792 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 14 17:49:56.859802 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 14 17:49:56.859812 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 14 17:49:56.859822 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 14 17:49:56.859832 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 14 17:49:56.859842 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 14 17:49:56.859853 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 14 17:49:56.859864 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 14 17:49:56.859874 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 14 17:49:56.859884 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 14 17:49:56.859893 systemd[1]: Created slice user.slice - User and Session Slice. May 14 17:49:56.859905 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 14 17:49:56.859916 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 14 17:49:56.859926 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 14 17:49:56.859936 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 14 17:49:56.859947 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 14 17:49:56.859957 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 14 17:49:56.859967 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 14 17:49:56.859981 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 14 17:49:56.859991 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 14 17:49:56.860001 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 14 17:49:56.860011 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 14 17:49:56.860022 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 14 17:49:56.860045 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 14 17:49:56.860057 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 14 17:49:56.860067 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 14 17:49:56.860083 systemd[1]: Reached target slices.target - Slice Units. May 14 17:49:56.860095 systemd[1]: Reached target swap.target - Swaps. May 14 17:49:56.860105 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 14 17:49:56.860115 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 14 17:49:56.860125 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 14 17:49:56.860137 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 14 17:49:56.860158 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 14 17:49:56.860176 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 14 17:49:56.860194 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 14 17:49:56.860205 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 14 17:49:56.860215 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 14 17:49:56.860225 systemd[1]: Mounting media.mount - External Media Directory... May 14 17:49:56.860235 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 14 17:49:56.860245 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 14 17:49:56.860255 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 14 17:49:56.860268 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 14 17:49:56.860278 systemd[1]: Reached target machines.target - Containers. May 14 17:49:56.860288 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 14 17:49:56.860299 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 17:49:56.860309 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 14 17:49:56.860319 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 14 17:49:56.860329 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 17:49:56.860339 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 17:49:56.860350 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 17:49:56.860360 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 14 17:49:56.860371 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 17:49:56.860381 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 14 17:49:56.860395 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 14 17:49:56.860409 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 14 17:49:56.860419 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 14 17:49:56.860429 kernel: loop: module loaded May 14 17:49:56.860438 systemd[1]: Stopped systemd-fsck-usr.service. May 14 17:49:56.860450 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 14 17:49:56.860460 kernel: fuse: init (API version 7.41) May 14 17:49:56.860469 systemd[1]: Starting systemd-journald.service - Journal Service... May 14 17:49:56.860479 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 14 17:49:56.860489 kernel: ACPI: bus type drm_connector registered May 14 17:49:56.860499 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 14 17:49:56.860509 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 14 17:49:56.860519 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 14 17:49:56.860530 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 14 17:49:56.860541 systemd[1]: verity-setup.service: Deactivated successfully. May 14 17:49:56.860552 systemd[1]: Stopped verity-setup.service. May 14 17:49:56.860561 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 14 17:49:56.860572 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 14 17:49:56.860584 systemd[1]: Mounted media.mount - External Media Directory. May 14 17:49:56.860595 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 14 17:49:56.860605 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 14 17:49:56.860615 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 14 17:49:56.860625 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 14 17:49:56.860636 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 14 17:49:56.860667 systemd-journald[1150]: Collecting audit messages is disabled. May 14 17:49:56.860688 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 14 17:49:56.860699 systemd-journald[1150]: Journal started May 14 17:49:56.860720 systemd-journald[1150]: Runtime Journal (/run/log/journal/438469a42f874bd3b9b26ffa98677525) is 6M, max 48.5M, 42.4M free. May 14 17:49:56.621185 systemd[1]: Queued start job for default target multi-user.target. May 14 17:49:56.642995 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 14 17:49:56.643396 systemd[1]: systemd-journald.service: Deactivated successfully. May 14 17:49:56.862561 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 14 17:49:56.865841 systemd[1]: Started systemd-journald.service - Journal Service. May 14 17:49:56.866614 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 17:49:56.866801 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 17:49:56.868261 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 17:49:56.868421 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 17:49:56.869696 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 17:49:56.869863 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 17:49:56.871328 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 14 17:49:56.871474 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 14 17:49:56.872803 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 17:49:56.872975 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 17:49:56.874329 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 14 17:49:56.875566 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 14 17:49:56.878118 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 14 17:49:56.879519 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 14 17:49:56.891558 systemd[1]: Reached target network-pre.target - Preparation for Network. May 14 17:49:56.893949 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 14 17:49:56.896143 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 14 17:49:56.897117 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 14 17:49:56.897155 systemd[1]: Reached target local-fs.target - Local File Systems. May 14 17:49:56.898849 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 14 17:49:56.908901 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 14 17:49:56.910109 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 17:49:56.911368 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 14 17:49:56.913185 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 14 17:49:56.914299 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 17:49:56.918187 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 14 17:49:56.919190 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 17:49:56.920178 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 14 17:49:56.922130 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 14 17:49:56.924152 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 14 17:49:56.926976 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 14 17:49:56.930573 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 14 17:49:56.931835 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 14 17:49:56.938562 systemd-journald[1150]: Time spent on flushing to /var/log/journal/438469a42f874bd3b9b26ffa98677525 is 17.469ms for 877 entries. May 14 17:49:56.938562 systemd-journald[1150]: System Journal (/var/log/journal/438469a42f874bd3b9b26ffa98677525) is 8M, max 195.6M, 187.6M free. May 14 17:49:56.962277 systemd-journald[1150]: Received client request to flush runtime journal. May 14 17:49:56.962349 kernel: loop0: detected capacity change from 0 to 201592 May 14 17:49:56.951238 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 14 17:49:56.953177 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 14 17:49:56.957315 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 14 17:49:56.964103 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 14 17:49:56.971048 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 14 17:49:56.979816 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. May 14 17:49:56.979835 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. May 14 17:49:56.983540 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 14 17:49:56.986574 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 14 17:49:56.990230 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 14 17:49:56.996115 kernel: loop1: detected capacity change from 0 to 107312 May 14 17:49:57.000802 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 14 17:49:57.023055 kernel: loop2: detected capacity change from 0 to 138376 May 14 17:49:57.021576 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 14 17:49:57.026187 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 14 17:49:57.045459 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. May 14 17:49:57.045479 systemd-tmpfiles[1221]: ACLs are not supported, ignoring. May 14 17:49:57.049600 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 14 17:49:57.054055 kernel: loop3: detected capacity change from 0 to 201592 May 14 17:49:57.060048 kernel: loop4: detected capacity change from 0 to 107312 May 14 17:49:57.065047 kernel: loop5: detected capacity change from 0 to 138376 May 14 17:49:57.070522 (sd-merge)[1225]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. May 14 17:49:57.070944 (sd-merge)[1225]: Merged extensions into '/usr'. May 14 17:49:57.074295 systemd[1]: Reload requested from client PID 1198 ('systemd-sysext') (unit systemd-sysext.service)... May 14 17:49:57.074404 systemd[1]: Reloading... May 14 17:49:57.150080 zram_generator::config[1254]: No configuration found. May 14 17:49:57.206125 ldconfig[1193]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 14 17:49:57.227019 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 14 17:49:57.289680 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 14 17:49:57.289988 systemd[1]: Reloading finished in 215 ms. May 14 17:49:57.313587 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 14 17:49:57.315018 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 14 17:49:57.332257 systemd[1]: Starting ensure-sysext.service... May 14 17:49:57.334045 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 14 17:49:57.345239 systemd[1]: Reload requested from client PID 1285 ('systemctl') (unit ensure-sysext.service)... May 14 17:49:57.345254 systemd[1]: Reloading... May 14 17:49:57.354702 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 14 17:49:57.354744 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 14 17:49:57.354967 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 14 17:49:57.355180 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 14 17:49:57.355764 systemd-tmpfiles[1286]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 14 17:49:57.355968 systemd-tmpfiles[1286]: ACLs are not supported, ignoring. May 14 17:49:57.356535 systemd-tmpfiles[1286]: ACLs are not supported, ignoring. May 14 17:49:57.359153 systemd-tmpfiles[1286]: Detected autofs mount point /boot during canonicalization of boot. May 14 17:49:57.359164 systemd-tmpfiles[1286]: Skipping /boot May 14 17:49:57.367899 systemd-tmpfiles[1286]: Detected autofs mount point /boot during canonicalization of boot. May 14 17:49:57.367917 systemd-tmpfiles[1286]: Skipping /boot May 14 17:49:57.387601 zram_generator::config[1311]: No configuration found. May 14 17:49:57.493065 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 14 17:49:57.554417 systemd[1]: Reloading finished in 208 ms. May 14 17:49:57.580084 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 14 17:49:57.585249 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 14 17:49:57.592099 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 17:49:57.594216 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 14 17:49:57.596393 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 14 17:49:57.599171 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 14 17:49:57.604166 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 14 17:49:57.606079 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 14 17:49:57.610271 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 17:49:57.611184 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 17:49:57.615705 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 17:49:57.618499 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 17:49:57.619652 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 17:49:57.619762 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 14 17:49:57.626576 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 14 17:49:57.628773 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 17:49:57.628922 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 17:49:57.630554 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 17:49:57.630706 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 17:49:57.632553 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 17:49:57.632679 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 17:49:57.639383 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 17:49:57.640782 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 17:49:57.642903 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 17:49:57.649219 systemd-udevd[1354]: Using default interface naming scheme 'v255'. May 14 17:49:57.649842 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 17:49:57.650994 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 17:49:57.651184 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 14 17:49:57.653921 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 14 17:49:57.656707 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 14 17:49:57.662096 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 14 17:49:57.664348 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 17:49:57.664536 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 17:49:57.666012 augenrules[1386]: No rules May 14 17:49:57.666269 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 17:49:57.666424 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 17:49:57.668199 systemd[1]: audit-rules.service: Deactivated successfully. May 14 17:49:57.668374 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 17:49:57.669770 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 17:49:57.669901 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 17:49:57.673644 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 14 17:49:57.675286 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 14 17:49:57.677756 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 14 17:49:57.688802 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 14 17:49:57.689995 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 14 17:49:57.691418 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 14 17:49:57.695943 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 14 17:49:57.698254 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 14 17:49:57.700657 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 14 17:49:57.702224 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 14 17:49:57.702279 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 14 17:49:57.703756 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 14 17:49:57.704871 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 14 17:49:57.706478 systemd[1]: Finished ensure-sysext.service. May 14 17:49:57.733741 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 14 17:49:57.735148 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 14 17:49:57.736603 systemd[1]: modprobe@drm.service: Deactivated successfully. May 14 17:49:57.738140 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 14 17:49:57.738433 augenrules[1416]: /sbin/augenrules: No change May 14 17:49:57.740345 systemd[1]: modprobe@loop.service: Deactivated successfully. May 14 17:49:57.740499 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 14 17:49:57.746735 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 14 17:49:57.753054 augenrules[1459]: No rules May 14 17:49:57.751028 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 14 17:49:57.752653 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 14 17:49:57.752824 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 14 17:49:57.754239 systemd[1]: audit-rules.service: Deactivated successfully. May 14 17:49:57.754405 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 14 17:49:57.796827 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 14 17:49:57.797301 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 14 17:49:57.797349 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 14 17:49:57.832997 systemd-networkd[1428]: lo: Link UP May 14 17:49:57.833009 systemd-networkd[1428]: lo: Gained carrier May 14 17:49:57.833677 systemd-networkd[1428]: Enumeration completed May 14 17:49:57.833778 systemd[1]: Started systemd-networkd.service - Network Configuration. May 14 17:49:57.836303 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 14 17:49:57.839245 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 14 17:49:57.841550 systemd-networkd[1428]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 17:49:57.841560 systemd-networkd[1428]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 14 17:49:57.842144 systemd-networkd[1428]: eth0: Link UP May 14 17:49:57.850173 systemd-networkd[1428]: eth0: Gained carrier May 14 17:49:57.850464 systemd-networkd[1428]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 14 17:49:57.862220 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 14 17:49:57.868335 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 14 17:49:57.869794 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 14 17:49:57.871532 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 14 17:49:57.873110 systemd-networkd[1428]: eth0: DHCPv4 address 10.0.0.11/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 14 17:49:57.873657 systemd[1]: Reached target time-set.target - System Time Set. May 14 17:49:57.874280 systemd-timesyncd[1440]: Network configuration changed, trying to establish connection. May 14 17:49:57.876758 systemd-timesyncd[1440]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 14 17:49:57.876811 systemd-timesyncd[1440]: Initial clock synchronization to Wed 2025-05-14 17:49:57.497827 UTC. May 14 17:49:57.894715 systemd-resolved[1353]: Positive Trust Anchors: May 14 17:49:57.894729 systemd-resolved[1353]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 14 17:49:57.894767 systemd-resolved[1353]: 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 14 17:49:57.903077 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 14 17:49:57.907711 systemd-resolved[1353]: Defaulting to hostname 'linux'. May 14 17:49:57.914977 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 14 17:49:57.916727 systemd[1]: Reached target network.target - Network. May 14 17:49:57.918109 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 14 17:49:57.919305 systemd[1]: Reached target sysinit.target - System Initialization. May 14 17:49:57.920747 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 14 17:49:57.922487 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 14 17:49:57.924050 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 14 17:49:57.925758 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 14 17:49:57.927200 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 14 17:49:57.928886 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 14 17:49:57.928923 systemd[1]: Reached target paths.target - Path Units. May 14 17:49:57.929868 systemd[1]: Reached target timers.target - Timer Units. May 14 17:49:57.932409 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 14 17:49:57.935453 systemd[1]: Starting docker.socket - Docker Socket for the API... May 14 17:49:57.939524 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 14 17:49:57.942291 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 14 17:49:57.944201 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 14 17:49:57.947903 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 14 17:49:57.949751 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 14 17:49:57.951467 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 14 17:49:57.956219 systemd[1]: Reached target sockets.target - Socket Units. May 14 17:49:57.957152 systemd[1]: Reached target basic.target - Basic System. May 14 17:49:57.958007 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 14 17:49:57.958079 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 14 17:49:57.959109 systemd[1]: Starting containerd.service - containerd container runtime... May 14 17:49:57.960902 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 14 17:49:57.962674 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 14 17:49:57.966792 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 14 17:49:57.968685 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 14 17:49:57.969675 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 14 17:49:57.970671 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 14 17:49:57.974184 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 14 17:49:57.976286 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 14 17:49:57.980504 systemd[1]: Starting systemd-logind.service - User Login Management... May 14 17:49:57.985929 jq[1501]: false May 14 17:49:57.982396 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 14 17:49:57.984941 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 14 17:49:57.985357 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 14 17:49:57.989494 extend-filesystems[1502]: Found loop3 May 14 17:49:57.989494 extend-filesystems[1502]: Found loop4 May 14 17:49:57.989494 extend-filesystems[1502]: Found loop5 May 14 17:49:57.989494 extend-filesystems[1502]: Found vda May 14 17:49:57.989494 extend-filesystems[1502]: Found vda1 May 14 17:49:57.989494 extend-filesystems[1502]: Found vda2 May 14 17:49:57.989494 extend-filesystems[1502]: Found vda3 May 14 17:49:57.989494 extend-filesystems[1502]: Found usr May 14 17:49:57.989494 extend-filesystems[1502]: Found vda4 May 14 17:49:57.993332 systemd[1]: Starting update-engine.service - Update Engine... May 14 17:49:58.000803 extend-filesystems[1502]: Found vda6 May 14 17:49:58.000803 extend-filesystems[1502]: Found vda7 May 14 17:49:58.000803 extend-filesystems[1502]: Found vda9 May 14 17:49:58.000803 extend-filesystems[1502]: Checking size of /dev/vda9 May 14 17:49:57.997337 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 14 17:49:58.001840 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 14 17:49:58.005392 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 14 17:49:58.005560 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 14 17:49:58.005792 systemd[1]: motdgen.service: Deactivated successfully. May 14 17:49:58.005949 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 14 17:49:58.013651 jq[1517]: true May 14 17:49:58.017860 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 14 17:49:58.018183 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 14 17:49:58.023527 (ntainerd)[1528]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 14 17:49:58.030826 jq[1527]: true May 14 17:49:58.032211 extend-filesystems[1502]: Resized partition /dev/vda9 May 14 17:49:58.041458 extend-filesystems[1537]: resize2fs 1.47.2 (1-Jan-2025) May 14 17:49:58.043412 update_engine[1511]: I20250514 17:49:58.040831 1511 main.cc:92] Flatcar Update Engine starting May 14 17:49:58.047067 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 14 17:49:58.060564 dbus-daemon[1499]: [system] SELinux support is enabled May 14 17:49:58.060797 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 14 17:49:58.063864 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 14 17:49:58.063900 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 14 17:49:58.065063 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 14 17:49:58.065079 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 14 17:49:58.070840 systemd[1]: Started update-engine.service - Update Engine. May 14 17:49:58.071240 update_engine[1511]: I20250514 17:49:58.071192 1511 update_check_scheduler.cc:74] Next update check in 10m58s May 14 17:49:58.076686 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 14 17:49:58.080276 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 14 17:49:58.087513 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 14 17:49:58.098566 extend-filesystems[1537]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 14 17:49:58.098566 extend-filesystems[1537]: old_desc_blocks = 1, new_desc_blocks = 1 May 14 17:49:58.098566 extend-filesystems[1537]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 14 17:49:58.104195 extend-filesystems[1502]: Resized filesystem in /dev/vda9 May 14 17:49:58.099629 systemd[1]: extend-filesystems.service: Deactivated successfully. May 14 17:49:58.106757 systemd-logind[1509]: Watching system buttons on /dev/input/event0 (Power Button) May 14 17:49:58.107731 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 14 17:49:58.111221 systemd-logind[1509]: New seat seat0. May 14 17:49:58.116239 systemd[1]: Started systemd-logind.service - User Login Management. May 14 17:49:58.119063 bash[1557]: Updated "/home/core/.ssh/authorized_keys" May 14 17:49:58.120461 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 14 17:49:58.122979 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 14 17:49:58.162320 locksmithd[1548]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 14 17:49:58.250048 containerd[1528]: time="2025-05-14T17:49:58Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 14 17:49:58.251648 containerd[1528]: time="2025-05-14T17:49:58.251569532Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 14 17:49:58.261418 containerd[1528]: time="2025-05-14T17:49:58.261367203Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.307µs" May 14 17:49:58.261418 containerd[1528]: time="2025-05-14T17:49:58.261408700Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 14 17:49:58.261418 containerd[1528]: time="2025-05-14T17:49:58.261427562Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 14 17:49:58.261606 containerd[1528]: time="2025-05-14T17:49:58.261573660Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 14 17:49:58.261606 containerd[1528]: time="2025-05-14T17:49:58.261595685Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 14 17:49:58.261648 containerd[1528]: time="2025-05-14T17:49:58.261621787Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 14 17:49:58.261683 containerd[1528]: time="2025-05-14T17:49:58.261668238Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 14 17:49:58.261702 containerd[1528]: time="2025-05-14T17:49:58.261683557Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 14 17:49:58.261921 containerd[1528]: time="2025-05-14T17:49:58.261894891Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 14 17:49:58.261921 containerd[1528]: time="2025-05-14T17:49:58.261914592Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 14 17:49:58.261961 containerd[1528]: time="2025-05-14T17:49:58.261924728Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 14 17:49:58.261961 containerd[1528]: time="2025-05-14T17:49:58.261931930Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 14 17:49:58.262026 containerd[1528]: time="2025-05-14T17:49:58.262009704Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 14 17:49:58.262257 containerd[1528]: time="2025-05-14T17:49:58.262234337Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 14 17:49:58.262284 containerd[1528]: time="2025-05-14T17:49:58.262269890Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 14 17:49:58.262284 containerd[1528]: time="2025-05-14T17:49:58.262280178Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 14 17:49:58.262800 containerd[1528]: time="2025-05-14T17:49:58.262777306Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 14 17:49:58.263446 containerd[1528]: time="2025-05-14T17:49:58.263411080Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 14 17:49:58.263536 containerd[1528]: time="2025-05-14T17:49:58.263510651Z" level=info msg="metadata content store policy set" policy=shared May 14 17:49:58.267933 containerd[1528]: time="2025-05-14T17:49:58.267888086Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 14 17:49:58.267995 containerd[1528]: time="2025-05-14T17:49:58.267948636Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 14 17:49:58.267995 containerd[1528]: time="2025-05-14T17:49:58.267965097Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 14 17:49:58.267995 containerd[1528]: time="2025-05-14T17:49:58.267978625Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 14 17:49:58.268081 containerd[1528]: time="2025-05-14T17:49:58.267995353Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 14 17:49:58.268081 containerd[1528]: time="2025-05-14T17:49:58.268005756Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 14 17:49:58.268081 containerd[1528]: time="2025-05-14T17:49:58.268017607Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 14 17:49:58.268081 containerd[1528]: time="2025-05-14T17:49:58.268039937Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 14 17:49:58.268081 containerd[1528]: time="2025-05-14T17:49:58.268053769Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 14 17:49:58.268081 containerd[1528]: time="2025-05-14T17:49:58.268063372Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 14 17:49:58.268081 containerd[1528]: time="2025-05-14T17:49:58.268073584Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 14 17:49:58.268204 containerd[1528]: time="2025-05-14T17:49:58.268085283Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 14 17:49:58.268222 containerd[1528]: time="2025-05-14T17:49:58.268212099Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 14 17:49:58.268238 containerd[1528]: time="2025-05-14T17:49:58.268231876Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 14 17:49:58.268254 containerd[1528]: time="2025-05-14T17:49:58.268245594Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 14 17:49:58.268271 containerd[1528]: time="2025-05-14T17:49:58.268256225Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 14 17:49:58.268287 containerd[1528]: time="2025-05-14T17:49:58.268274821Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 14 17:49:58.268306 containerd[1528]: time="2025-05-14T17:49:58.268286176Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 14 17:49:58.268306 containerd[1528]: time="2025-05-14T17:49:58.268298141Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 14 17:49:58.268340 containerd[1528]: time="2025-05-14T17:49:58.268308278Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 14 17:49:58.268340 containerd[1528]: time="2025-05-14T17:49:58.268321233Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 14 17:49:58.268340 containerd[1528]: time="2025-05-14T17:49:58.268335866Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 14 17:49:58.268388 containerd[1528]: time="2025-05-14T17:49:58.268349584Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 14 17:49:58.268826 containerd[1528]: time="2025-05-14T17:49:58.268791877Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 14 17:49:58.268826 containerd[1528]: time="2025-05-14T17:49:58.268815351Z" level=info msg="Start snapshots syncer" May 14 17:49:58.268883 containerd[1528]: time="2025-05-14T17:49:58.268847740Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 14 17:49:58.269247 containerd[1528]: time="2025-05-14T17:49:58.269074622Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 14 17:49:58.269247 containerd[1528]: time="2025-05-14T17:49:58.269140164Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 14 17:49:58.269375 containerd[1528]: time="2025-05-14T17:49:58.269232989Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 14 17:49:58.269375 containerd[1528]: time="2025-05-14T17:49:58.269331836Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 14 17:49:58.269375 containerd[1528]: time="2025-05-14T17:49:58.269353480Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 14 17:49:58.269375 containerd[1528]: time="2025-05-14T17:49:58.269363959Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 14 17:49:58.269375 containerd[1528]: time="2025-05-14T17:49:58.269373447Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 14 17:49:58.269464 containerd[1528]: time="2025-05-14T17:49:58.269384307Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 14 17:49:58.269464 containerd[1528]: time="2025-05-14T17:49:58.269403589Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 14 17:49:58.269464 containerd[1528]: time="2025-05-14T17:49:58.269413877Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 14 17:49:58.269464 containerd[1528]: time="2025-05-14T17:49:58.269437198Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 14 17:49:58.269464 containerd[1528]: time="2025-05-14T17:49:58.269448782Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 14 17:49:58.269464 containerd[1528]: time="2025-05-14T17:49:58.269458537Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 14 17:49:58.269553 containerd[1528]: time="2025-05-14T17:49:58.269497824Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 14 17:49:58.269553 containerd[1528]: time="2025-05-14T17:49:58.269511047Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 14 17:49:58.269553 containerd[1528]: time="2025-05-14T17:49:58.269519049Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 14 17:49:58.269553 containerd[1528]: time="2025-05-14T17:49:58.269527852Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 14 17:49:58.269553 containerd[1528]: time="2025-05-14T17:49:58.269535358Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 14 17:49:58.269553 containerd[1528]: time="2025-05-14T17:49:58.269544618Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 14 17:49:58.269553 containerd[1528]: time="2025-05-14T17:49:58.269554526Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 14 17:49:58.269836 containerd[1528]: time="2025-05-14T17:49:58.269805414Z" level=info msg="runtime interface created" May 14 17:49:58.269836 containerd[1528]: time="2025-05-14T17:49:58.269818675Z" level=info msg="created NRI interface" May 14 17:49:58.269836 containerd[1528]: time="2025-05-14T17:49:58.269828239Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 14 17:49:58.269836 containerd[1528]: time="2025-05-14T17:49:58.269839137Z" level=info msg="Connect containerd service" May 14 17:49:58.269915 containerd[1528]: time="2025-05-14T17:49:58.269864249Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 14 17:49:58.270568 containerd[1528]: time="2025-05-14T17:49:58.270541998Z" 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 14 17:49:58.380209 containerd[1528]: time="2025-05-14T17:49:58.380148276Z" level=info msg="Start subscribing containerd event" May 14 17:49:58.380209 containerd[1528]: time="2025-05-14T17:49:58.380222163Z" level=info msg="Start recovering state" May 14 17:49:58.380370 containerd[1528]: time="2025-05-14T17:49:58.380309844Z" level=info msg="Start event monitor" May 14 17:49:58.380370 containerd[1528]: time="2025-05-14T17:49:58.380327602Z" level=info msg="Start cni network conf syncer for default" May 14 17:49:58.380370 containerd[1528]: time="2025-05-14T17:49:58.380337471Z" level=info msg="Start streaming server" May 14 17:49:58.380370 containerd[1528]: time="2025-05-14T17:49:58.380346731Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 14 17:49:58.380370 containerd[1528]: time="2025-05-14T17:49:58.380353895Z" level=info msg="runtime interface starting up..." May 14 17:49:58.380370 containerd[1528]: time="2025-05-14T17:49:58.380359115Z" level=info msg="starting plugins..." May 14 17:49:58.380625 containerd[1528]: time="2025-05-14T17:49:58.380374853Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 14 17:49:58.380827 containerd[1528]: time="2025-05-14T17:49:58.380810859Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 14 17:49:58.380864 containerd[1528]: time="2025-05-14T17:49:58.380851594Z" level=info msg=serving... address=/run/containerd/containerd.sock May 14 17:49:58.382002 containerd[1528]: time="2025-05-14T17:49:58.380897321Z" level=info msg="containerd successfully booted in 0.131219s" May 14 17:49:58.380987 systemd[1]: Started containerd.service - containerd container runtime. May 14 17:49:58.527220 sshd_keygen[1522]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 14 17:49:58.544792 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 14 17:49:58.548043 systemd[1]: Starting issuegen.service - Generate /run/issue... May 14 17:49:58.575400 systemd[1]: issuegen.service: Deactivated successfully. May 14 17:49:58.575660 systemd[1]: Finished issuegen.service - Generate /run/issue. May 14 17:49:58.578413 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 14 17:49:58.609991 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 14 17:49:58.612592 systemd[1]: Started getty@tty1.service - Getty on tty1. May 14 17:49:58.614685 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 14 17:49:58.615892 systemd[1]: Reached target getty.target - Login Prompts. May 14 17:49:59.006203 systemd-networkd[1428]: eth0: Gained IPv6LL May 14 17:49:59.008812 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 14 17:49:59.010376 systemd[1]: Reached target network-online.target - Network is Online. May 14 17:49:59.012689 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 14 17:49:59.016316 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 14 17:49:59.030883 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 14 17:49:59.056485 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 14 17:49:59.058883 systemd[1]: coreos-metadata.service: Deactivated successfully. May 14 17:49:59.059086 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 14 17:49:59.062494 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 14 17:49:59.556530 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 14 17:49:59.557923 systemd[1]: Reached target multi-user.target - Multi-User System. May 14 17:49:59.562381 (kubelet)[1626]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 14 17:49:59.562834 systemd[1]: Startup finished in 2.158s (kernel) + 4.591s (initrd) + 3.374s (userspace) = 10.123s. May 14 17:49:59.980775 kubelet[1626]: E0514 17:49:59.980684 1626 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 14 17:49:59.983197 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 14 17:49:59.983326 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 14 17:49:59.983759 systemd[1]: kubelet.service: Consumed 806ms CPU time, 248M memory peak. May 14 17:50:04.718350 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 14 17:50:04.720490 systemd[1]: Started sshd@0-10.0.0.11:22-10.0.0.1:54056.service - OpenSSH per-connection server daemon (10.0.0.1:54056). May 14 17:50:04.828958 sshd[1639]: Accepted publickey for core from 10.0.0.1 port 54056 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 17:50:04.831247 sshd-session[1639]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 17:50:04.837416 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 14 17:50:04.838367 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 14 17:50:04.843701 systemd-logind[1509]: New session 1 of user core. May 14 17:50:04.860057 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 14 17:50:04.862604 systemd[1]: Starting user@500.service - User Manager for UID 500... May 14 17:50:04.883860 (systemd)[1643]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 14 17:50:04.886116 systemd-logind[1509]: New session c1 of user core. May 14 17:50:04.993234 systemd[1643]: Queued start job for default target default.target. May 14 17:50:05.008866 systemd[1643]: Created slice app.slice - User Application Slice. May 14 17:50:05.008893 systemd[1643]: Reached target paths.target - Paths. May 14 17:50:05.008927 systemd[1643]: Reached target timers.target - Timers. May 14 17:50:05.010142 systemd[1643]: Starting dbus.socket - D-Bus User Message Bus Socket... May 14 17:50:05.021134 systemd[1643]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 14 17:50:05.021236 systemd[1643]: Reached target sockets.target - Sockets. May 14 17:50:05.021273 systemd[1643]: Reached target basic.target - Basic System. May 14 17:50:05.021299 systemd[1643]: Reached target default.target - Main User Target. May 14 17:50:05.021322 systemd[1643]: Startup finished in 129ms. May 14 17:50:05.021411 systemd[1]: Started user@500.service - User Manager for UID 500. May 14 17:50:05.023008 systemd[1]: Started session-1.scope - Session 1 of User core. May 14 17:50:05.093062 systemd[1]: Started sshd@1-10.0.0.11:22-10.0.0.1:54058.service - OpenSSH per-connection server daemon (10.0.0.1:54058). May 14 17:50:05.139623 sshd[1654]: Accepted publickey for core from 10.0.0.1 port 54058 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 17:50:05.140578 sshd-session[1654]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 17:50:05.144889 systemd-logind[1509]: New session 2 of user core. May 14 17:50:05.157173 systemd[1]: Started session-2.scope - Session 2 of User core. May 14 17:50:05.208391 sshd[1656]: Connection closed by 10.0.0.1 port 54058 May 14 17:50:05.208658 sshd-session[1654]: pam_unix(sshd:session): session closed for user core May 14 17:50:05.217885 systemd[1]: sshd@1-10.0.0.11:22-10.0.0.1:54058.service: Deactivated successfully. May 14 17:50:05.220312 systemd[1]: session-2.scope: Deactivated successfully. May 14 17:50:05.221023 systemd-logind[1509]: Session 2 logged out. Waiting for processes to exit. May 14 17:50:05.224232 systemd[1]: Started sshd@2-10.0.0.11:22-10.0.0.1:54068.service - OpenSSH per-connection server daemon (10.0.0.1:54068). May 14 17:50:05.225106 systemd-logind[1509]: Removed session 2. May 14 17:50:05.274758 sshd[1662]: Accepted publickey for core from 10.0.0.1 port 54068 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 17:50:05.275959 sshd-session[1662]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 17:50:05.279552 systemd-logind[1509]: New session 3 of user core. May 14 17:50:05.297190 systemd[1]: Started session-3.scope - Session 3 of User core. May 14 17:50:05.344158 sshd[1664]: Connection closed by 10.0.0.1 port 54068 May 14 17:50:05.344431 sshd-session[1662]: pam_unix(sshd:session): session closed for user core May 14 17:50:05.353884 systemd[1]: sshd@2-10.0.0.11:22-10.0.0.1:54068.service: Deactivated successfully. May 14 17:50:05.356134 systemd[1]: session-3.scope: Deactivated successfully. May 14 17:50:05.357106 systemd-logind[1509]: Session 3 logged out. Waiting for processes to exit. May 14 17:50:05.358878 systemd[1]: Started sshd@3-10.0.0.11:22-10.0.0.1:54076.service - OpenSSH per-connection server daemon (10.0.0.1:54076). May 14 17:50:05.359802 systemd-logind[1509]: Removed session 3. May 14 17:50:05.403509 sshd[1670]: Accepted publickey for core from 10.0.0.1 port 54076 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 17:50:05.404588 sshd-session[1670]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 17:50:05.407969 systemd-logind[1509]: New session 4 of user core. May 14 17:50:05.420195 systemd[1]: Started session-4.scope - Session 4 of User core. May 14 17:50:05.472444 sshd[1672]: Connection closed by 10.0.0.1 port 54076 May 14 17:50:05.472775 sshd-session[1670]: pam_unix(sshd:session): session closed for user core May 14 17:50:05.483818 systemd[1]: sshd@3-10.0.0.11:22-10.0.0.1:54076.service: Deactivated successfully. May 14 17:50:05.485162 systemd[1]: session-4.scope: Deactivated successfully. May 14 17:50:05.487086 systemd-logind[1509]: Session 4 logged out. Waiting for processes to exit. May 14 17:50:05.488633 systemd[1]: Started sshd@4-10.0.0.11:22-10.0.0.1:54092.service - OpenSSH per-connection server daemon (10.0.0.1:54092). May 14 17:50:05.489444 systemd-logind[1509]: Removed session 4. May 14 17:50:05.540898 sshd[1678]: Accepted publickey for core from 10.0.0.1 port 54092 ssh2: RSA SHA256:BMeAQICuA2OnIsP+qyp4K3RmZxP3sZUKEyFSi3UEAFA May 14 17:50:05.542084 sshd-session[1678]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 14 17:50:05.546596 systemd-logind[1509]: New session 5 of user core. May 14 17:50:05.556189 systemd[1]: Started session-5.scope - Session 5 of User core. May 14 17:50:05.616471 sudo[1681]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 14 17:50:05.616737 sudo[1681]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 14 17:50:05.626602 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 14 17:50:05.653872 systemd[1]: coreos-metadata.service: Deactivated successfully. May 14 17:50:05.654134 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 14 17:50:06.144186 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 14 17:50:06.144471 systemd[1]: kubelet.service: Consumed 806ms CPU time, 248M memory peak. May 14 17:50:06.146572 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 14 17:50:06.168022 systemd[1]: Reload requested from client PID 1723 ('systemctl') (unit session-5.scope)... May 14 17:50:06.168063 systemd[1]: Reloading... May 14 17:50:06.232525 zram_generator::config[1765]: No configuration found. May 14 17:50:06.384749 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 14 17:50:06.466279 systemd[1]: Reloading finished in 297 ms. May 14 17:50:06.516809 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 14 17:50:06.519225 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... May 14 17:50:06.520840 systemd[1]: kubelet.service: Deactivated successfully. May 14 17:50:06.521047 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 14 17:50:06.521088 systemd[1]: kubelet.service: Consumed 95ms CPU time, 90.2M memory peak. May 14 17:50:06.522477 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 14 17:50:06.634770 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 14 17:50:06.643345 (kubelet)[1812]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 14 17:50:06.677629 kubelet[1812]: 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 14 17:50:06.677629 kubelet[1812]: 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 14 17:50:06.677629 kubelet[1812]: 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 14 17:50:06.677920 kubelet[1812]: I0514 17:50:06.677678 1812 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 14 17:50:07.598273 kubelet[1812]: I0514 17:50:07.598235 1812 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" May 14 17:50:07.599858 kubelet[1812]: I0514 17:50:07.598443 1812 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 14 17:50:07.599858 kubelet[1812]: I0514 17:50:07.598704 1812 server.go:954] "Client rotation is on, will bootstrap in background" May 14 17:50:07.651304 kubelet[1812]: I0514 17:50:07.651139 1812 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 14 17:50:07.658021 kubelet[1812]: I0514 17:50:07.657980 1812 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" May 14 17:50:07.662920 kubelet[1812]: I0514 17:50:07.661995 1812 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 14 17:50:07.662920 kubelet[1812]: I0514 17:50:07.662266 1812 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 14 17:50:07.662920 kubelet[1812]: I0514 17:50:07.662296 1812 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.11","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 14 17:50:07.662920 kubelet[1812]: I0514 17:50:07.662552 1812 topology_manager.go:138] "Creating topology manager with none policy" May 14 17:50:07.663233 kubelet[1812]: I0514 17:50:07.662563 1812 container_manager_linux.go:304] "Creating device plugin manager" May 14 17:50:07.663233 kubelet[1812]: I0514 17:50:07.662751 1812 state_mem.go:36] "Initialized new in-memory state store" May 14 17:50:07.664942 kubelet[1812]: I0514 17:50:07.664926 1812 kubelet.go:446] "Attempting to sync node with API server" May 14 17:50:07.665438 kubelet[1812]: I0514 17:50:07.665419 1812 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" May 14 17:50:07.665990 kubelet[1812]: I0514 17:50:07.665971 1812 kubelet.go:352] "Adding apiserver pod source" May 14 17:50:07.666147 kubelet[1812]: E0514 17:50:07.666068 1812 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:07.666147 kubelet[1812]: E0514 17:50:07.666100 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:07.666239 kubelet[1812]: I0514 17:50:07.666227 1812 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 14 17:50:07.671375 kubelet[1812]: I0514 17:50:07.671357 1812 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" May 14 17:50:07.672314 kubelet[1812]: I0514 17:50:07.672291 1812 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 14 17:50:07.672637 kubelet[1812]: W0514 17:50:07.672615 1812 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 14 17:50:07.672714 kubelet[1812]: W0514 17:50:07.672689 1812 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.11" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope May 14 17:50:07.672750 kubelet[1812]: E0514 17:50:07.672740 1812 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.11\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" May 14 17:50:07.673191 kubelet[1812]: W0514 17:50:07.673133 1812 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope May 14 17:50:07.673191 kubelet[1812]: E0514 17:50:07.673170 1812 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" May 14 17:50:07.674183 kubelet[1812]: I0514 17:50:07.674159 1812 watchdog_linux.go:99] "Systemd watchdog is not enabled" May 14 17:50:07.674305 kubelet[1812]: I0514 17:50:07.674294 1812 server.go:1287] "Started kubelet" May 14 17:50:07.675960 kubelet[1812]: I0514 17:50:07.675941 1812 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 14 17:50:07.678864 kubelet[1812]: I0514 17:50:07.678814 1812 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 14 17:50:07.678864 kubelet[1812]: I0514 17:50:07.678547 1812 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 May 14 17:50:07.682214 kubelet[1812]: I0514 17:50:07.679120 1812 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 14 17:50:07.682214 kubelet[1812]: I0514 17:50:07.679898 1812 server.go:490] "Adding debug handlers to kubelet server" May 14 17:50:07.682214 kubelet[1812]: I0514 17:50:07.680759 1812 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 14 17:50:07.682214 kubelet[1812]: E0514 17:50:07.681166 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:07.682214 kubelet[1812]: I0514 17:50:07.681210 1812 volume_manager.go:297] "Starting Kubelet Volume Manager" May 14 17:50:07.682214 kubelet[1812]: I0514 17:50:07.681886 1812 desired_state_of_world_populator.go:149] "Desired state populator starts to run" May 14 17:50:07.682214 kubelet[1812]: I0514 17:50:07.682137 1812 reconciler.go:26] "Reconciler: start to sync state" May 14 17:50:07.683782 kubelet[1812]: E0514 17:50:07.683670 1812 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 14 17:50:07.685007 kubelet[1812]: I0514 17:50:07.684982 1812 factory.go:221] Registration of the systemd container factory successfully May 14 17:50:07.685131 kubelet[1812]: I0514 17:50:07.685109 1812 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 14 17:50:07.685680 kubelet[1812]: E0514 17:50:07.685649 1812 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.11\" not found" node="10.0.0.11" May 14 17:50:07.686091 kubelet[1812]: I0514 17:50:07.686073 1812 factory.go:221] Registration of the containerd container factory successfully May 14 17:50:07.695301 kubelet[1812]: I0514 17:50:07.695280 1812 cpu_manager.go:221] "Starting CPU manager" policy="none" May 14 17:50:07.695301 kubelet[1812]: I0514 17:50:07.695294 1812 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" May 14 17:50:07.695426 kubelet[1812]: I0514 17:50:07.695315 1812 state_mem.go:36] "Initialized new in-memory state store" May 14 17:50:07.782245 kubelet[1812]: E0514 17:50:07.782195 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:07.785568 kubelet[1812]: I0514 17:50:07.785546 1812 policy_none.go:49] "None policy: Start" May 14 17:50:07.785568 kubelet[1812]: I0514 17:50:07.785573 1812 memory_manager.go:186] "Starting memorymanager" policy="None" May 14 17:50:07.785661 kubelet[1812]: I0514 17:50:07.785585 1812 state_mem.go:35] "Initializing new in-memory state store" May 14 17:50:07.790807 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 14 17:50:07.805673 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 14 17:50:07.808758 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 14 17:50:07.809170 kubelet[1812]: I0514 17:50:07.808523 1812 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 14 17:50:07.810892 kubelet[1812]: I0514 17:50:07.810869 1812 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 14 17:50:07.810944 kubelet[1812]: I0514 17:50:07.810901 1812 status_manager.go:227] "Starting to sync pod status with apiserver" May 14 17:50:07.810944 kubelet[1812]: I0514 17:50:07.810924 1812 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." May 14 17:50:07.810944 kubelet[1812]: I0514 17:50:07.810932 1812 kubelet.go:2388] "Starting kubelet main sync loop" May 14 17:50:07.811026 kubelet[1812]: E0514 17:50:07.810972 1812 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 14 17:50:07.815851 kubelet[1812]: I0514 17:50:07.815818 1812 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 14 17:50:07.816067 kubelet[1812]: I0514 17:50:07.816048 1812 eviction_manager.go:189] "Eviction manager: starting control loop" May 14 17:50:07.816110 kubelet[1812]: I0514 17:50:07.816064 1812 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 14 17:50:07.816605 kubelet[1812]: I0514 17:50:07.816453 1812 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 14 17:50:07.817539 kubelet[1812]: E0514 17:50:07.817515 1812 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" May 14 17:50:07.817600 kubelet[1812]: E0514 17:50:07.817555 1812 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.11\" not found" May 14 17:50:07.918203 kubelet[1812]: I0514 17:50:07.917720 1812 kubelet_node_status.go:76] "Attempting to register node" node="10.0.0.11" May 14 17:50:07.921878 kubelet[1812]: I0514 17:50:07.921850 1812 kubelet_node_status.go:79] "Successfully registered node" node="10.0.0.11" May 14 17:50:07.922001 kubelet[1812]: E0514 17:50:07.921989 1812 kubelet_node_status.go:549] "Error updating node status, will retry" err="error getting node \"10.0.0.11\": node \"10.0.0.11\" not found" May 14 17:50:07.929939 kubelet[1812]: I0514 17:50:07.929833 1812 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 14 17:50:07.930311 containerd[1528]: time="2025-05-14T17:50:07.930276925Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 14 17:50:07.930961 kubelet[1812]: I0514 17:50:07.930935 1812 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 14 17:50:07.939945 kubelet[1812]: E0514 17:50:07.939906 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.040695 kubelet[1812]: E0514 17:50:08.040648 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.141130 kubelet[1812]: E0514 17:50:08.141086 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.162537 sudo[1681]: pam_unix(sudo:session): session closed for user root May 14 17:50:08.163850 sshd[1680]: Connection closed by 10.0.0.1 port 54092 May 14 17:50:08.164226 sshd-session[1678]: pam_unix(sshd:session): session closed for user core May 14 17:50:08.167610 systemd[1]: sshd@4-10.0.0.11:22-10.0.0.1:54092.service: Deactivated successfully. May 14 17:50:08.169510 systemd[1]: session-5.scope: Deactivated successfully. May 14 17:50:08.169952 systemd[1]: session-5.scope: Consumed 446ms CPU time, 73.2M memory peak. May 14 17:50:08.171131 systemd-logind[1509]: Session 5 logged out. Waiting for processes to exit. May 14 17:50:08.172104 systemd-logind[1509]: Removed session 5. May 14 17:50:08.241648 kubelet[1812]: E0514 17:50:08.241588 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.342104 kubelet[1812]: E0514 17:50:08.342068 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.442673 kubelet[1812]: E0514 17:50:08.442579 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.543035 kubelet[1812]: E0514 17:50:08.543003 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.600510 kubelet[1812]: I0514 17:50:08.600438 1812 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 14 17:50:08.600634 kubelet[1812]: W0514 17:50:08.600600 1812 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 14 17:50:08.600634 kubelet[1812]: W0514 17:50:08.600629 1812 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 14 17:50:08.643607 kubelet[1812]: E0514 17:50:08.643581 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.666922 kubelet[1812]: E0514 17:50:08.666885 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:08.744750 kubelet[1812]: E0514 17:50:08.744647 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.845245 kubelet[1812]: E0514 17:50:08.845217 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:08.945701 kubelet[1812]: E0514 17:50:08.945662 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:09.046229 kubelet[1812]: E0514 17:50:09.046098 1812 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.11\" not found" May 14 17:50:09.667131 kubelet[1812]: I0514 17:50:09.667089 1812 apiserver.go:52] "Watching apiserver" May 14 17:50:09.667255 kubelet[1812]: E0514 17:50:09.667106 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:09.679579 systemd[1]: Created slice kubepods-besteffort-podf1a21aa3_735d_4639_ab6a_ec2fcbebfdc5.slice - libcontainer container kubepods-besteffort-podf1a21aa3_735d_4639_ab6a_ec2fcbebfdc5.slice. May 14 17:50:09.683158 kubelet[1812]: I0514 17:50:09.683123 1812 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" May 14 17:50:09.691757 kubelet[1812]: I0514 17:50:09.691679 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9-cni-plugin\") pod \"kube-flannel-ds-tvfxb\" (UID: \"8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9\") " pod="kube-flannel/kube-flannel-ds-tvfxb" May 14 17:50:09.691757 kubelet[1812]: I0514 17:50:09.691720 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9-cni\") pod \"kube-flannel-ds-tvfxb\" (UID: \"8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9\") " pod="kube-flannel/kube-flannel-ds-tvfxb" May 14 17:50:09.692015 kubelet[1812]: I0514 17:50:09.691932 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sjq99\" (UniqueName: \"kubernetes.io/projected/8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9-kube-api-access-sjq99\") pod \"kube-flannel-ds-tvfxb\" (UID: \"8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9\") " pod="kube-flannel/kube-flannel-ds-tvfxb" May 14 17:50:09.692015 kubelet[1812]: I0514 17:50:09.691960 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5-lib-modules\") pod \"kube-proxy-kxtrs\" (UID: \"f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5\") " pod="kube-system/kube-proxy-kxtrs" May 14 17:50:09.692229 kubelet[1812]: I0514 17:50:09.692044 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9-run\") pod \"kube-flannel-ds-tvfxb\" (UID: \"8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9\") " pod="kube-flannel/kube-flannel-ds-tvfxb" May 14 17:50:09.692229 kubelet[1812]: I0514 17:50:09.692163 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9-flannel-cfg\") pod \"kube-flannel-ds-tvfxb\" (UID: \"8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9\") " pod="kube-flannel/kube-flannel-ds-tvfxb" May 14 17:50:09.692229 kubelet[1812]: I0514 17:50:09.692182 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9-xtables-lock\") pod \"kube-flannel-ds-tvfxb\" (UID: \"8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9\") " pod="kube-flannel/kube-flannel-ds-tvfxb" May 14 17:50:09.692229 kubelet[1812]: I0514 17:50:09.692199 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5-kube-proxy\") pod \"kube-proxy-kxtrs\" (UID: \"f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5\") " pod="kube-system/kube-proxy-kxtrs" May 14 17:50:09.692415 kubelet[1812]: I0514 17:50:09.692358 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5-xtables-lock\") pod \"kube-proxy-kxtrs\" (UID: \"f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5\") " pod="kube-system/kube-proxy-kxtrs" May 14 17:50:09.692415 kubelet[1812]: I0514 17:50:09.692381 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-f42cc\" (UniqueName: \"kubernetes.io/projected/f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5-kube-api-access-f42cc\") pod \"kube-proxy-kxtrs\" (UID: \"f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5\") " pod="kube-system/kube-proxy-kxtrs" May 14 17:50:09.697762 systemd[1]: Created slice kubepods-burstable-pod8c0f0460_4916_4cfe_9dca_c33b8f3bb2c9.slice - libcontainer container kubepods-burstable-pod8c0f0460_4916_4cfe_9dca_c33b8f3bb2c9.slice. May 14 17:50:09.997065 containerd[1528]: time="2025-05-14T17:50:09.996167056Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-kxtrs,Uid:f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5,Namespace:kube-system,Attempt:0,}" May 14 17:50:10.009625 containerd[1528]: time="2025-05-14T17:50:10.009587252Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-tvfxb,Uid:8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9,Namespace:kube-flannel,Attempt:0,}" May 14 17:50:10.668011 kubelet[1812]: E0514 17:50:10.667965 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:10.678846 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2574912666.mount: Deactivated successfully. May 14 17:50:10.684886 containerd[1528]: time="2025-05-14T17:50:10.684834629Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 14 17:50:10.687310 containerd[1528]: time="2025-05-14T17:50:10.687279009Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" May 14 17:50:10.689960 containerd[1528]: time="2025-05-14T17:50:10.688814185Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 14 17:50:10.691330 containerd[1528]: time="2025-05-14T17:50:10.691298143Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" May 14 17:50:10.693207 containerd[1528]: time="2025-05-14T17:50:10.693174910Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 14 17:50:10.694785 containerd[1528]: time="2025-05-14T17:50:10.694758380Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 681.113088ms" May 14 17:50:10.695487 containerd[1528]: time="2025-05-14T17:50:10.695166886Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 14 17:50:10.695846 containerd[1528]: time="2025-05-14T17:50:10.695790085Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 694.246886ms" May 14 17:50:10.711371 containerd[1528]: time="2025-05-14T17:50:10.711316917Z" level=info msg="connecting to shim 16b644444cdcb6a557e11b9afc460165abe34855cfbbe2e5c38fb80f36066548" address="unix:///run/containerd/s/7ed20e7b2d5138a98fd91906c931d392aee5547290dcbffcb79a06375839b643" namespace=k8s.io protocol=ttrpc version=3 May 14 17:50:10.711630 containerd[1528]: time="2025-05-14T17:50:10.711578835Z" level=info msg="connecting to shim 468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64" address="unix:///run/containerd/s/aa022154b7e9966e01ca7b95963e61d82cb0fd8348c326c6b3153e64fefad6ff" namespace=k8s.io protocol=ttrpc version=3 May 14 17:50:10.738219 systemd[1]: Started cri-containerd-468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64.scope - libcontainer container 468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64. May 14 17:50:10.741691 systemd[1]: Started cri-containerd-16b644444cdcb6a557e11b9afc460165abe34855cfbbe2e5c38fb80f36066548.scope - libcontainer container 16b644444cdcb6a557e11b9afc460165abe34855cfbbe2e5c38fb80f36066548. May 14 17:50:10.766619 containerd[1528]: time="2025-05-14T17:50:10.766570489Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-kxtrs,Uid:f1a21aa3-735d-4639-ab6a-ec2fcbebfdc5,Namespace:kube-system,Attempt:0,} returns sandbox id \"16b644444cdcb6a557e11b9afc460165abe34855cfbbe2e5c38fb80f36066548\"" May 14 17:50:10.769325 containerd[1528]: time="2025-05-14T17:50:10.769293110Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\"" May 14 17:50:10.770719 containerd[1528]: time="2025-05-14T17:50:10.770679201Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-tvfxb,Uid:8c0f0460-4916-4cfe-9dca-c33b8f3bb2c9,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64\"" May 14 17:50:11.668522 kubelet[1812]: E0514 17:50:11.668369 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:11.968663 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount253243919.mount: Deactivated successfully. May 14 17:50:12.198734 containerd[1528]: time="2025-05-14T17:50:12.198684702Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:12.199154 containerd[1528]: time="2025-05-14T17:50:12.199106677Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.4: active requests=0, bytes read=27370353" May 14 17:50:12.200043 containerd[1528]: time="2025-05-14T17:50:12.199977471Z" level=info msg="ImageCreate event name:\"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:12.201771 containerd[1528]: time="2025-05-14T17:50:12.201745147Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:12.202378 containerd[1528]: time="2025-05-14T17:50:12.202266353Z" 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.43293697s" May 14 17:50:12.202378 containerd[1528]: time="2025-05-14T17:50:12.202298993Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\" returns image reference \"sha256:62c496efa595c8eb7d098e43430b2b94ad66812214759a7ea9daaaa1ed901fc7\"" May 14 17:50:12.203573 containerd[1528]: time="2025-05-14T17:50:12.203506351Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 14 17:50:12.204494 containerd[1528]: time="2025-05-14T17:50:12.204463271Z" level=info msg="CreateContainer within sandbox \"16b644444cdcb6a557e11b9afc460165abe34855cfbbe2e5c38fb80f36066548\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 14 17:50:12.212430 containerd[1528]: time="2025-05-14T17:50:12.211010903Z" level=info msg="Container fc89aff40fa068e382ec104db972b83294a809292f1813952c85c60d168cabf9: CDI devices from CRI Config.CDIDevices: []" May 14 17:50:12.218364 containerd[1528]: time="2025-05-14T17:50:12.218324421Z" level=info msg="CreateContainer within sandbox \"16b644444cdcb6a557e11b9afc460165abe34855cfbbe2e5c38fb80f36066548\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"fc89aff40fa068e382ec104db972b83294a809292f1813952c85c60d168cabf9\"" May 14 17:50:12.218944 containerd[1528]: time="2025-05-14T17:50:12.218848247Z" level=info msg="StartContainer for \"fc89aff40fa068e382ec104db972b83294a809292f1813952c85c60d168cabf9\"" May 14 17:50:12.220674 containerd[1528]: time="2025-05-14T17:50:12.220639430Z" level=info msg="connecting to shim fc89aff40fa068e382ec104db972b83294a809292f1813952c85c60d168cabf9" address="unix:///run/containerd/s/7ed20e7b2d5138a98fd91906c931d392aee5547290dcbffcb79a06375839b643" protocol=ttrpc version=3 May 14 17:50:12.242187 systemd[1]: Started cri-containerd-fc89aff40fa068e382ec104db972b83294a809292f1813952c85c60d168cabf9.scope - libcontainer container fc89aff40fa068e382ec104db972b83294a809292f1813952c85c60d168cabf9. May 14 17:50:12.273078 containerd[1528]: time="2025-05-14T17:50:12.273013615Z" level=info msg="StartContainer for \"fc89aff40fa068e382ec104db972b83294a809292f1813952c85c60d168cabf9\" returns successfully" May 14 17:50:12.669117 kubelet[1812]: E0514 17:50:12.668996 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:13.407308 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2757546080.mount: Deactivated successfully. May 14 17:50:13.435672 containerd[1528]: time="2025-05-14T17:50:13.435147207Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:13.435672 containerd[1528]: time="2025-05-14T17:50:13.435582289Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673529" May 14 17:50:13.436508 containerd[1528]: time="2025-05-14T17:50:13.436481546Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:13.438546 containerd[1528]: time="2025-05-14T17:50:13.438513319Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:13.439334 containerd[1528]: time="2025-05-14T17:50:13.439084884Z" 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.235549142s" May 14 17:50:13.439334 containerd[1528]: time="2025-05-14T17:50:13.439110678Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" May 14 17:50:13.440885 containerd[1528]: time="2025-05-14T17:50:13.440855815Z" level=info msg="CreateContainer within sandbox \"468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 14 17:50:13.449169 containerd[1528]: time="2025-05-14T17:50:13.448471080Z" level=info msg="Container 3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058: CDI devices from CRI Config.CDIDevices: []" May 14 17:50:13.455817 containerd[1528]: time="2025-05-14T17:50:13.455785242Z" level=info msg="CreateContainer within sandbox \"468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058\"" May 14 17:50:13.456296 containerd[1528]: time="2025-05-14T17:50:13.456264719Z" level=info msg="StartContainer for \"3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058\"" May 14 17:50:13.456952 containerd[1528]: time="2025-05-14T17:50:13.456928967Z" level=info msg="connecting to shim 3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058" address="unix:///run/containerd/s/aa022154b7e9966e01ca7b95963e61d82cb0fd8348c326c6b3153e64fefad6ff" protocol=ttrpc version=3 May 14 17:50:13.479183 systemd[1]: Started cri-containerd-3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058.scope - libcontainer container 3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058. May 14 17:50:13.501849 containerd[1528]: time="2025-05-14T17:50:13.501749919Z" level=info msg="StartContainer for \"3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058\" returns successfully" May 14 17:50:13.502589 systemd[1]: cri-containerd-3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058.scope: Deactivated successfully. May 14 17:50:13.504418 containerd[1528]: time="2025-05-14T17:50:13.504295866Z" level=info msg="received exit event container_id:\"3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058\" id:\"3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058\" pid:2144 exited_at:{seconds:1747245013 nanos:503931012}" May 14 17:50:13.504418 containerd[1528]: time="2025-05-14T17:50:13.504314188Z" level=info msg="TaskExit event in podsandbox handler container_id:\"3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058\" id:\"3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058\" pid:2144 exited_at:{seconds:1747245013 nanos:503931012}" May 14 17:50:13.669917 kubelet[1812]: E0514 17:50:13.669787 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:13.828145 containerd[1528]: time="2025-05-14T17:50:13.828082682Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 14 17:50:13.838349 kubelet[1812]: I0514 17:50:13.838169 1812 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-kxtrs" podStartSLOduration=5.403312573 podStartE2EDuration="6.838149541s" podCreationTimestamp="2025-05-14 17:50:07 +0000 UTC" firstStartedPulling="2025-05-14 17:50:10.768270676 +0000 UTC m=+4.122196773" lastFinishedPulling="2025-05-14 17:50:12.203107604 +0000 UTC m=+5.557033741" observedRunningTime="2025-05-14 17:50:12.834599305 +0000 UTC m=+6.188525402" watchObservedRunningTime="2025-05-14 17:50:13.838149541 +0000 UTC m=+7.192075638" May 14 17:50:14.356277 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3938c41d25ee6f7f898e42c3acc23f41909e83fdf2127537e40351dace86e058-rootfs.mount: Deactivated successfully. May 14 17:50:14.670957 kubelet[1812]: E0514 17:50:14.670845 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:14.974437 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3576995102.mount: Deactivated successfully. May 14 17:50:15.437786 containerd[1528]: time="2025-05-14T17:50:15.437682304Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:15.438281 containerd[1528]: time="2025-05-14T17:50:15.438244460Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874260" May 14 17:50:15.439322 containerd[1528]: time="2025-05-14T17:50:15.439283114Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:15.441680 containerd[1528]: time="2025-05-14T17:50:15.441655252Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:15.442670 containerd[1528]: time="2025-05-14T17:50:15.442638657Z" 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.614516218s" May 14 17:50:15.442711 containerd[1528]: time="2025-05-14T17:50:15.442670142Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" May 14 17:50:15.444691 containerd[1528]: time="2025-05-14T17:50:15.444662268Z" level=info msg="CreateContainer within sandbox \"468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 14 17:50:15.453498 containerd[1528]: time="2025-05-14T17:50:15.452920427Z" level=info msg="Container a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c: CDI devices from CRI Config.CDIDevices: []" May 14 17:50:15.458653 containerd[1528]: time="2025-05-14T17:50:15.458613478Z" level=info msg="CreateContainer within sandbox \"468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c\"" May 14 17:50:15.459239 containerd[1528]: time="2025-05-14T17:50:15.459211936Z" level=info msg="StartContainer for \"a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c\"" May 14 17:50:15.460013 containerd[1528]: time="2025-05-14T17:50:15.459984737Z" level=info msg="connecting to shim a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c" address="unix:///run/containerd/s/aa022154b7e9966e01ca7b95963e61d82cb0fd8348c326c6b3153e64fefad6ff" protocol=ttrpc version=3 May 14 17:50:15.488198 systemd[1]: Started cri-containerd-a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c.scope - libcontainer container a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c. May 14 17:50:15.518174 containerd[1528]: time="2025-05-14T17:50:15.517967033Z" level=info msg="StartContainer for \"a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c\" returns successfully" May 14 17:50:15.518416 systemd[1]: cri-containerd-a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c.scope: Deactivated successfully. May 14 17:50:15.520140 containerd[1528]: time="2025-05-14T17:50:15.520096284Z" level=info msg="received exit event container_id:\"a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c\" id:\"a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c\" pid:2216 exited_at:{seconds:1747245015 nanos:519930300}" May 14 17:50:15.520374 containerd[1528]: time="2025-05-14T17:50:15.520313815Z" level=info msg="TaskExit event in podsandbox handler container_id:\"a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c\" id:\"a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c\" pid:2216 exited_at:{seconds:1747245015 nanos:519930300}" May 14 17:50:15.535542 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a90f1c5f365747d2cc3896f1ebbfecde59533dd43a8bd2472ca775c25175ca8c-rootfs.mount: Deactivated successfully. May 14 17:50:15.566577 kubelet[1812]: I0514 17:50:15.566553 1812 kubelet_node_status.go:502] "Fast updating node status as it just became ready" May 14 17:50:15.671389 kubelet[1812]: E0514 17:50:15.671343 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:15.835152 containerd[1528]: time="2025-05-14T17:50:15.834395624Z" level=info msg="CreateContainer within sandbox \"468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 14 17:50:15.841682 containerd[1528]: time="2025-05-14T17:50:15.841639650Z" level=info msg="Container b1a662036872545c776b49cf5f56247152ba0562f314735c9891b01a17ea389c: CDI devices from CRI Config.CDIDevices: []" May 14 17:50:15.847331 containerd[1528]: time="2025-05-14T17:50:15.847291304Z" level=info msg="CreateContainer within sandbox \"468e49030b60c24a4eb3b639fb177b85bc4e3b22fc24ff00b2653394b0a8dc64\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"b1a662036872545c776b49cf5f56247152ba0562f314735c9891b01a17ea389c\"" May 14 17:50:15.848071 containerd[1528]: time="2025-05-14T17:50:15.847742247Z" level=info msg="StartContainer for \"b1a662036872545c776b49cf5f56247152ba0562f314735c9891b01a17ea389c\"" May 14 17:50:15.848586 containerd[1528]: time="2025-05-14T17:50:15.848560862Z" level=info msg="connecting to shim b1a662036872545c776b49cf5f56247152ba0562f314735c9891b01a17ea389c" address="unix:///run/containerd/s/aa022154b7e9966e01ca7b95963e61d82cb0fd8348c326c6b3153e64fefad6ff" protocol=ttrpc version=3 May 14 17:50:15.874189 systemd[1]: Started cri-containerd-b1a662036872545c776b49cf5f56247152ba0562f314735c9891b01a17ea389c.scope - libcontainer container b1a662036872545c776b49cf5f56247152ba0562f314735c9891b01a17ea389c. May 14 17:50:15.906849 containerd[1528]: time="2025-05-14T17:50:15.906803558Z" level=info msg="StartContainer for \"b1a662036872545c776b49cf5f56247152ba0562f314735c9891b01a17ea389c\" returns successfully" May 14 17:50:16.672402 kubelet[1812]: E0514 17:50:16.672350 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:16.848581 kubelet[1812]: I0514 17:50:16.848492 1812 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-tvfxb" podStartSLOduration=5.176741536 podStartE2EDuration="9.848472601s" podCreationTimestamp="2025-05-14 17:50:07 +0000 UTC" firstStartedPulling="2025-05-14 17:50:10.771741293 +0000 UTC m=+4.125667390" lastFinishedPulling="2025-05-14 17:50:15.443472358 +0000 UTC m=+8.797398455" observedRunningTime="2025-05-14 17:50:16.848112031 +0000 UTC m=+10.202038128" watchObservedRunningTime="2025-05-14 17:50:16.848472601 +0000 UTC m=+10.202398698" May 14 17:50:17.007389 systemd-networkd[1428]: flannel.1: Link UP May 14 17:50:17.007395 systemd-networkd[1428]: flannel.1: Gained carrier May 14 17:50:17.672908 kubelet[1812]: E0514 17:50:17.672835 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:18.590271 systemd-networkd[1428]: flannel.1: Gained IPv6LL May 14 17:50:18.673350 kubelet[1812]: E0514 17:50:18.673292 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:19.040242 systemd[1]: Created slice kubepods-besteffort-pod980ba1c6_7b86_49c4_a529_f0ad6b457326.slice - libcontainer container kubepods-besteffort-pod980ba1c6_7b86_49c4_a529_f0ad6b457326.slice. May 14 17:50:19.143307 kubelet[1812]: I0514 17:50:19.143267 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-2j8wm\" (UniqueName: \"kubernetes.io/projected/980ba1c6-7b86-49c4-a529-f0ad6b457326-kube-api-access-2j8wm\") pod \"nginx-deployment-7fcdb87857-mxhpn\" (UID: \"980ba1c6-7b86-49c4-a529-f0ad6b457326\") " pod="default/nginx-deployment-7fcdb87857-mxhpn" May 14 17:50:19.342712 containerd[1528]: time="2025-05-14T17:50:19.342580955Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-mxhpn,Uid:980ba1c6-7b86-49c4-a529-f0ad6b457326,Namespace:default,Attempt:0,}" May 14 17:50:19.374689 systemd-networkd[1428]: cni0: Link UP May 14 17:50:19.374696 systemd-networkd[1428]: cni0: Gained carrier May 14 17:50:19.375660 systemd-networkd[1428]: cni0: Lost carrier May 14 17:50:19.386997 systemd-networkd[1428]: veth29805cdb: Link UP May 14 17:50:19.388075 kernel: cni0: port 1(veth29805cdb) entered blocking state May 14 17:50:19.388193 kernel: cni0: port 1(veth29805cdb) entered disabled state May 14 17:50:19.389603 kernel: veth29805cdb: entered allmulticast mode May 14 17:50:19.389666 kernel: veth29805cdb: entered promiscuous mode May 14 17:50:19.401172 kernel: cni0: port 1(veth29805cdb) entered blocking state May 14 17:50:19.401295 kernel: cni0: port 1(veth29805cdb) entered forwarding state May 14 17:50:19.401435 systemd-networkd[1428]: veth29805cdb: Gained carrier May 14 17:50:19.401666 systemd-networkd[1428]: cni0: Gained carrier May 14 17:50:19.403856 containerd[1528]: 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 14 17:50:19.403856 containerd[1528]: delegateAdd: netconf sent to delegate plugin: May 14 17:50:19.435053 containerd[1528]: {"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-14T17:50:19.434781342Z" level=info msg="connecting to shim 571e3857d00f4a9dc98f6cc289605e54f1ec36ae909f87522a453b4de4be0594" address="unix:///run/containerd/s/8d078fbbc311ed32b0bde4b0c2b4e065c861bcec955c03701d08f1874e5046c3" namespace=k8s.io protocol=ttrpc version=3 May 14 17:50:19.462209 systemd[1]: Started cri-containerd-571e3857d00f4a9dc98f6cc289605e54f1ec36ae909f87522a453b4de4be0594.scope - libcontainer container 571e3857d00f4a9dc98f6cc289605e54f1ec36ae909f87522a453b4de4be0594. May 14 17:50:19.475758 systemd-resolved[1353]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 14 17:50:19.496334 containerd[1528]: time="2025-05-14T17:50:19.496290399Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-mxhpn,Uid:980ba1c6-7b86-49c4-a529-f0ad6b457326,Namespace:default,Attempt:0,} returns sandbox id \"571e3857d00f4a9dc98f6cc289605e54f1ec36ae909f87522a453b4de4be0594\"" May 14 17:50:19.497535 containerd[1528]: time="2025-05-14T17:50:19.497447112Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 14 17:50:19.673665 kubelet[1812]: E0514 17:50:19.673540 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:20.673809 kubelet[1812]: E0514 17:50:20.673760 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:20.702158 systemd-networkd[1428]: cni0: Gained IPv6LL May 14 17:50:21.342257 systemd-networkd[1428]: veth29805cdb: Gained IPv6LL May 14 17:50:21.616423 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3017108793.mount: Deactivated successfully. May 14 17:50:21.674067 kubelet[1812]: E0514 17:50:21.674023 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:22.390832 containerd[1528]: time="2025-05-14T17:50:22.390783212Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:22.391729 containerd[1528]: time="2025-05-14T17:50:22.391522309Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948859" May 14 17:50:22.392383 containerd[1528]: time="2025-05-14T17:50:22.392348120Z" level=info msg="ImageCreate event name:\"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:22.394892 containerd[1528]: time="2025-05-14T17:50:22.394855533Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:22.396172 containerd[1528]: time="2025-05-14T17:50:22.395994182Z" 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.898511085s" May 14 17:50:22.396172 containerd[1528]: time="2025-05-14T17:50:22.396046122Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 14 17:50:22.401151 containerd[1528]: time="2025-05-14T17:50:22.401081309Z" level=info msg="CreateContainer within sandbox \"571e3857d00f4a9dc98f6cc289605e54f1ec36ae909f87522a453b4de4be0594\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 14 17:50:22.410165 containerd[1528]: time="2025-05-14T17:50:22.410125979Z" level=info msg="Container c720dd548e323025838a85425aa038857dad42d7c98880b1dc9c0a80430deb45: CDI devices from CRI Config.CDIDevices: []" May 14 17:50:22.411324 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1219369510.mount: Deactivated successfully. May 14 17:50:22.416677 containerd[1528]: time="2025-05-14T17:50:22.416582551Z" level=info msg="CreateContainer within sandbox \"571e3857d00f4a9dc98f6cc289605e54f1ec36ae909f87522a453b4de4be0594\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"c720dd548e323025838a85425aa038857dad42d7c98880b1dc9c0a80430deb45\"" May 14 17:50:22.417220 containerd[1528]: time="2025-05-14T17:50:22.417186389Z" level=info msg="StartContainer for \"c720dd548e323025838a85425aa038857dad42d7c98880b1dc9c0a80430deb45\"" May 14 17:50:22.418006 containerd[1528]: time="2025-05-14T17:50:22.417978983Z" level=info msg="connecting to shim c720dd548e323025838a85425aa038857dad42d7c98880b1dc9c0a80430deb45" address="unix:///run/containerd/s/8d078fbbc311ed32b0bde4b0c2b4e065c861bcec955c03701d08f1874e5046c3" protocol=ttrpc version=3 May 14 17:50:22.438223 systemd[1]: Started cri-containerd-c720dd548e323025838a85425aa038857dad42d7c98880b1dc9c0a80430deb45.scope - libcontainer container c720dd548e323025838a85425aa038857dad42d7c98880b1dc9c0a80430deb45. May 14 17:50:22.463699 containerd[1528]: time="2025-05-14T17:50:22.463659413Z" level=info msg="StartContainer for \"c720dd548e323025838a85425aa038857dad42d7c98880b1dc9c0a80430deb45\" returns successfully" May 14 17:50:22.675024 kubelet[1812]: E0514 17:50:22.674852 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:22.857681 kubelet[1812]: I0514 17:50:22.857615 1812 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-mxhpn" podStartSLOduration=0.957444219 podStartE2EDuration="3.857598159s" podCreationTimestamp="2025-05-14 17:50:19 +0000 UTC" firstStartedPulling="2025-05-14 17:50:19.49707247 +0000 UTC m=+12.850998567" lastFinishedPulling="2025-05-14 17:50:22.39722645 +0000 UTC m=+15.751152507" observedRunningTime="2025-05-14 17:50:22.857539712 +0000 UTC m=+16.211465849" watchObservedRunningTime="2025-05-14 17:50:22.857598159 +0000 UTC m=+16.211524256" May 14 17:50:23.675176 kubelet[1812]: E0514 17:50:23.675126 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:24.675711 kubelet[1812]: E0514 17:50:24.675665 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:25.662000 systemd[1]: Created slice kubepods-besteffort-pod1d4c038d_e89b_48c9_af47_584b3e4b5de9.slice - libcontainer container kubepods-besteffort-pod1d4c038d_e89b_48c9_af47_584b3e4b5de9.slice. May 14 17:50:25.676566 kubelet[1812]: E0514 17:50:25.676528 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:25.677620 kubelet[1812]: I0514 17:50:25.677579 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/1d4c038d-e89b-48c9-af47-584b3e4b5de9-data\") pod \"nfs-server-provisioner-0\" (UID: \"1d4c038d-e89b-48c9-af47-584b3e4b5de9\") " pod="default/nfs-server-provisioner-0" May 14 17:50:25.677658 kubelet[1812]: I0514 17:50:25.677626 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cfpd6\" (UniqueName: \"kubernetes.io/projected/1d4c038d-e89b-48c9-af47-584b3e4b5de9-kube-api-access-cfpd6\") pod \"nfs-server-provisioner-0\" (UID: \"1d4c038d-e89b-48c9-af47-584b3e4b5de9\") " pod="default/nfs-server-provisioner-0" May 14 17:50:25.965977 containerd[1528]: time="2025-05-14T17:50:25.965865572Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1d4c038d-e89b-48c9-af47-584b3e4b5de9,Namespace:default,Attempt:0,}" May 14 17:50:25.982284 systemd-networkd[1428]: veth2864394f: Link UP May 14 17:50:25.984076 kernel: cni0: port 2(veth2864394f) entered blocking state May 14 17:50:25.984125 kernel: cni0: port 2(veth2864394f) entered disabled state May 14 17:50:25.985354 kernel: veth2864394f: entered allmulticast mode May 14 17:50:25.986079 kernel: veth2864394f: entered promiscuous mode May 14 17:50:25.993826 systemd-networkd[1428]: veth2864394f: Gained carrier May 14 17:50:25.994082 kernel: cni0: port 2(veth2864394f) entered blocking state May 14 17:50:25.994124 kernel: cni0: port 2(veth2864394f) entered forwarding state May 14 17:50:25.995780 containerd[1528]: 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)(0x40001268e8), "name":"cbr0", "type":"bridge"} May 14 17:50:25.995780 containerd[1528]: delegateAdd: netconf sent to delegate plugin: May 14 17:50:26.018477 containerd[1528]: {"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-14T17:50:26.018436350Z" level=info msg="connecting to shim e481c75f282885afbec87de9b4f26057d163555f2a077d5c0724faed1a78e0f0" address="unix:///run/containerd/s/d7fdfe91fb74a73bb5955fc80e27360444cba91d27a06beb8cec80e024e8dbb5" namespace=k8s.io protocol=ttrpc version=3 May 14 17:50:26.048246 systemd[1]: Started cri-containerd-e481c75f282885afbec87de9b4f26057d163555f2a077d5c0724faed1a78e0f0.scope - libcontainer container e481c75f282885afbec87de9b4f26057d163555f2a077d5c0724faed1a78e0f0. May 14 17:50:26.058439 systemd-resolved[1353]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 14 17:50:26.076734 containerd[1528]: time="2025-05-14T17:50:26.076697384Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1d4c038d-e89b-48c9-af47-584b3e4b5de9,Namespace:default,Attempt:0,} returns sandbox id \"e481c75f282885afbec87de9b4f26057d163555f2a077d5c0724faed1a78e0f0\"" May 14 17:50:26.077968 containerd[1528]: time="2025-05-14T17:50:26.077944618Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 14 17:50:26.677115 kubelet[1812]: E0514 17:50:26.677073 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:27.294927 systemd-networkd[1428]: veth2864394f: Gained IPv6LL May 14 17:50:27.667223 kubelet[1812]: E0514 17:50:27.666342 1812 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:27.677224 kubelet[1812]: E0514 17:50:27.677198 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:28.274208 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2440667417.mount: Deactivated successfully. May 14 17:50:28.678218 kubelet[1812]: E0514 17:50:28.678115 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:29.577826 containerd[1528]: time="2025-05-14T17:50:29.577776626Z" 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 14 17:50:29.578594 containerd[1528]: time="2025-05-14T17:50:29.578255345Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" May 14 17:50:29.579208 containerd[1528]: time="2025-05-14T17:50:29.579177049Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:29.581625 containerd[1528]: time="2025-05-14T17:50:29.581600259Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:29.582520 containerd[1528]: time="2025-05-14T17:50:29.582483672Z" 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.504507207s" May 14 17:50:29.582621 containerd[1528]: time="2025-05-14T17:50:29.582582398Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" May 14 17:50:29.584534 containerd[1528]: time="2025-05-14T17:50:29.584500749Z" level=info msg="CreateContainer within sandbox \"e481c75f282885afbec87de9b4f26057d163555f2a077d5c0724faed1a78e0f0\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 14 17:50:29.591054 containerd[1528]: time="2025-05-14T17:50:29.590767658Z" level=info msg="Container 34f71adf2589ee0ccb0c9e329c1562e2cf1d01d5603b82cb601a19dc75a4b783: CDI devices from CRI Config.CDIDevices: []" May 14 17:50:29.593009 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1380968595.mount: Deactivated successfully. May 14 17:50:29.596985 containerd[1528]: time="2025-05-14T17:50:29.596952068Z" level=info msg="CreateContainer within sandbox \"e481c75f282885afbec87de9b4f26057d163555f2a077d5c0724faed1a78e0f0\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"34f71adf2589ee0ccb0c9e329c1562e2cf1d01d5603b82cb601a19dc75a4b783\"" May 14 17:50:29.598083 containerd[1528]: time="2025-05-14T17:50:29.597560769Z" level=info msg="StartContainer for \"34f71adf2589ee0ccb0c9e329c1562e2cf1d01d5603b82cb601a19dc75a4b783\"" May 14 17:50:29.598573 containerd[1528]: time="2025-05-14T17:50:29.598545265Z" level=info msg="connecting to shim 34f71adf2589ee0ccb0c9e329c1562e2cf1d01d5603b82cb601a19dc75a4b783" address="unix:///run/containerd/s/d7fdfe91fb74a73bb5955fc80e27360444cba91d27a06beb8cec80e024e8dbb5" protocol=ttrpc version=3 May 14 17:50:29.620191 systemd[1]: Started cri-containerd-34f71adf2589ee0ccb0c9e329c1562e2cf1d01d5603b82cb601a19dc75a4b783.scope - libcontainer container 34f71adf2589ee0ccb0c9e329c1562e2cf1d01d5603b82cb601a19dc75a4b783. May 14 17:50:29.646999 containerd[1528]: time="2025-05-14T17:50:29.645518640Z" level=info msg="StartContainer for \"34f71adf2589ee0ccb0c9e329c1562e2cf1d01d5603b82cb601a19dc75a4b783\" returns successfully" May 14 17:50:29.678967 kubelet[1812]: E0514 17:50:29.678925 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:29.873001 kubelet[1812]: I0514 17:50:29.872824 1812 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.367090913 podStartE2EDuration="4.872808911s" podCreationTimestamp="2025-05-14 17:50:25 +0000 UTC" firstStartedPulling="2025-05-14 17:50:26.077714038 +0000 UTC m=+19.431640135" lastFinishedPulling="2025-05-14 17:50:29.583432076 +0000 UTC m=+22.937358133" observedRunningTime="2025-05-14 17:50:29.872656666 +0000 UTC m=+23.226582723" watchObservedRunningTime="2025-05-14 17:50:29.872808911 +0000 UTC m=+23.226735008" May 14 17:50:30.679822 kubelet[1812]: E0514 17:50:30.679770 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:31.680439 kubelet[1812]: E0514 17:50:31.680397 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:32.681430 kubelet[1812]: E0514 17:50:32.681341 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:33.681671 kubelet[1812]: E0514 17:50:33.681633 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:34.682185 kubelet[1812]: E0514 17:50:34.682142 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:35.682730 kubelet[1812]: E0514 17:50:35.682647 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:36.683503 kubelet[1812]: E0514 17:50:36.683451 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:37.683761 kubelet[1812]: E0514 17:50:37.683732 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:38.684052 kubelet[1812]: E0514 17:50:38.683982 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:39.553633 systemd[1]: Created slice kubepods-besteffort-pod38c27a7c_e042_4bce_b171_5bee9742a51e.slice - libcontainer container kubepods-besteffort-pod38c27a7c_e042_4bce_b171_5bee9742a51e.slice. May 14 17:50:39.657435 kubelet[1812]: I0514 17:50:39.657345 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-d932c9a7-258f-454f-b6ce-0ad6e921f0c1\" (UniqueName: \"kubernetes.io/nfs/38c27a7c-e042-4bce-b171-5bee9742a51e-pvc-d932c9a7-258f-454f-b6ce-0ad6e921f0c1\") pod \"test-pod-1\" (UID: \"38c27a7c-e042-4bce-b171-5bee9742a51e\") " pod="default/test-pod-1" May 14 17:50:39.657435 kubelet[1812]: I0514 17:50:39.657393 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9hrg4\" (UniqueName: \"kubernetes.io/projected/38c27a7c-e042-4bce-b171-5bee9742a51e-kube-api-access-9hrg4\") pod \"test-pod-1\" (UID: \"38c27a7c-e042-4bce-b171-5bee9742a51e\") " pod="default/test-pod-1" May 14 17:50:39.685135 kubelet[1812]: E0514 17:50:39.685069 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:39.788628 kernel: netfs: FS-Cache loaded May 14 17:50:39.826432 kernel: RPC: Registered named UNIX socket transport module. May 14 17:50:39.826540 kernel: RPC: Registered udp transport module. May 14 17:50:39.826558 kernel: RPC: Registered tcp transport module. May 14 17:50:39.826573 kernel: RPC: Registered tcp-with-tls transport module. May 14 17:50:39.827189 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 14 17:50:40.009139 kernel: NFS: Registering the id_resolver key type May 14 17:50:40.009256 kernel: Key type id_resolver registered May 14 17:50:40.009288 kernel: Key type id_legacy registered May 14 17:50:40.067815 nfsidmap[2824]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf May 14 17:50:40.068547 nfsidmap[2824]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 14 17:50:40.069882 nfsidmap[2825]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf May 14 17:50:40.070018 nfsidmap[2825]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 14 17:50:40.079219 nfsrahead[2830]: setting /var/lib/kubelet/pods/38c27a7c-e042-4bce-b171-5bee9742a51e/volumes/kubernetes.io~nfs/pvc-d932c9a7-258f-454f-b6ce-0ad6e921f0c1 readahead to 128 May 14 17:50:40.158504 containerd[1528]: time="2025-05-14T17:50:40.158465789Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:38c27a7c-e042-4bce-b171-5bee9742a51e,Namespace:default,Attempt:0,}" May 14 17:50:40.171806 systemd-networkd[1428]: veth18f91ac0: Link UP May 14 17:50:40.174495 kernel: cni0: port 3(veth18f91ac0) entered blocking state May 14 17:50:40.174585 kernel: cni0: port 3(veth18f91ac0) entered disabled state May 14 17:50:40.174601 kernel: veth18f91ac0: entered allmulticast mode May 14 17:50:40.174616 kernel: veth18f91ac0: entered promiscuous mode May 14 17:50:40.185943 systemd-networkd[1428]: veth18f91ac0: Gained carrier May 14 17:50:40.186553 kernel: cni0: port 3(veth18f91ac0) entered blocking state May 14 17:50:40.186586 kernel: cni0: port 3(veth18f91ac0) entered forwarding state May 14 17:50:40.187539 containerd[1528]: 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 14 17:50:40.187539 containerd[1528]: delegateAdd: netconf sent to delegate plugin: May 14 17:50:40.218590 containerd[1528]: {"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-14T17:50:40.218544594Z" level=info msg="connecting to shim 74cc4937283230a676cbc25f701af50d5353c98ad94c5a4d997e43310ace01c0" address="unix:///run/containerd/s/2d7d970df48a27a59d9a742461f4f69b2ebdac97a3727b0f9e1044b243c7ad50" namespace=k8s.io protocol=ttrpc version=3 May 14 17:50:40.247266 systemd[1]: Started cri-containerd-74cc4937283230a676cbc25f701af50d5353c98ad94c5a4d997e43310ace01c0.scope - libcontainer container 74cc4937283230a676cbc25f701af50d5353c98ad94c5a4d997e43310ace01c0. May 14 17:50:40.258394 systemd-resolved[1353]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 14 17:50:40.277375 containerd[1528]: time="2025-05-14T17:50:40.277335266Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:38c27a7c-e042-4bce-b171-5bee9742a51e,Namespace:default,Attempt:0,} returns sandbox id \"74cc4937283230a676cbc25f701af50d5353c98ad94c5a4d997e43310ace01c0\"" May 14 17:50:40.278566 containerd[1528]: time="2025-05-14T17:50:40.278523739Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 14 17:50:40.686073 kubelet[1812]: E0514 17:50:40.685995 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:41.153383 containerd[1528]: time="2025-05-14T17:50:41.153302123Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 14 17:50:41.155440 containerd[1528]: time="2025-05-14T17:50:41.155391791Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 14 17:50:41.157459 containerd[1528]: time="2025-05-14T17:50:41.157422369Z" 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 878.8491ms" May 14 17:50:41.157459 containerd[1528]: time="2025-05-14T17:50:41.157456015Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 14 17:50:41.160384 containerd[1528]: time="2025-05-14T17:50:41.160353273Z" level=info msg="CreateContainer within sandbox \"74cc4937283230a676cbc25f701af50d5353c98ad94c5a4d997e43310ace01c0\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 14 17:50:41.166824 containerd[1528]: time="2025-05-14T17:50:41.166788069Z" level=info msg="Container eb6902745244d517149292b5d653ef196165bbab41c2f87b2c374e91df4f1fd8: CDI devices from CRI Config.CDIDevices: []" May 14 17:50:41.174321 containerd[1528]: time="2025-05-14T17:50:41.174273220Z" level=info msg="CreateContainer within sandbox \"74cc4937283230a676cbc25f701af50d5353c98ad94c5a4d997e43310ace01c0\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"eb6902745244d517149292b5d653ef196165bbab41c2f87b2c374e91df4f1fd8\"" May 14 17:50:41.174924 containerd[1528]: time="2025-05-14T17:50:41.174843846Z" level=info msg="StartContainer for \"eb6902745244d517149292b5d653ef196165bbab41c2f87b2c374e91df4f1fd8\"" May 14 17:50:41.176113 containerd[1528]: time="2025-05-14T17:50:41.176074195Z" level=info msg="connecting to shim eb6902745244d517149292b5d653ef196165bbab41c2f87b2c374e91df4f1fd8" address="unix:///run/containerd/s/2d7d970df48a27a59d9a742461f4f69b2ebdac97a3727b0f9e1044b243c7ad50" protocol=ttrpc version=3 May 14 17:50:41.194211 systemd[1]: Started cri-containerd-eb6902745244d517149292b5d653ef196165bbab41c2f87b2c374e91df4f1fd8.scope - libcontainer container eb6902745244d517149292b5d653ef196165bbab41c2f87b2c374e91df4f1fd8. May 14 17:50:41.224946 containerd[1528]: time="2025-05-14T17:50:41.224908109Z" level=info msg="StartContainer for \"eb6902745244d517149292b5d653ef196165bbab41c2f87b2c374e91df4f1fd8\" returns successfully" May 14 17:50:41.567014 systemd-networkd[1428]: veth18f91ac0: Gained IPv6LL May 14 17:50:41.687107 kubelet[1812]: E0514 17:50:41.687052 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:42.687668 kubelet[1812]: E0514 17:50:42.687624 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:43.441402 update_engine[1511]: I20250514 17:50:43.441304 1511 update_attempter.cc:509] Updating boot flags... May 14 17:50:43.688015 kubelet[1812]: E0514 17:50:43.687967 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:44.688359 kubelet[1812]: E0514 17:50:44.688265 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:45.689207 kubelet[1812]: E0514 17:50:45.689137 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:46.690066 kubelet[1812]: E0514 17:50:46.690006 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:47.666586 kubelet[1812]: E0514 17:50:47.666547 1812 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:47.691128 kubelet[1812]: E0514 17:50:47.691085 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 14 17:50:48.691617 kubelet[1812]: E0514 17:50:48.691575 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"