May 12 13:31:34.872293 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 12 13:31:34.872314 kernel: Linux version 6.6.89-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 Mon May 12 12:12:07 -00 2025 May 12 13:31:34.872324 kernel: KASLR enabled May 12 13:31:34.872329 kernel: efi: EFI v2.7 by EDK II May 12 13:31:34.872335 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 May 12 13:31:34.872340 kernel: random: crng init done May 12 13:31:34.872347 kernel: secureboot: Secure boot disabled May 12 13:31:34.872352 kernel: ACPI: Early table checksum verification disabled May 12 13:31:34.872358 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) May 12 13:31:34.872365 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) May 12 13:31:34.872371 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) May 12 13:31:34.872376 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 12 13:31:34.872382 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) May 12 13:31:34.872388 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) May 12 13:31:34.872395 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 12 13:31:34.872402 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 12 13:31:34.872408 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) May 12 13:31:34.872414 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) May 12 13:31:34.872420 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 12 13:31:34.872426 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 May 12 13:31:34.872431 kernel: NUMA: Failed to initialise from firmware May 12 13:31:34.872437 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] May 12 13:31:34.872443 kernel: NUMA: NODE_DATA [mem 0xdc954e00-0xdc95bfff] May 12 13:31:34.872449 kernel: Zone ranges: May 12 13:31:34.872455 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] May 12 13:31:34.872462 kernel: DMA32 empty May 12 13:31:34.872468 kernel: Normal empty May 12 13:31:34.872474 kernel: Device empty May 12 13:31:34.872480 kernel: Movable zone start for each node May 12 13:31:34.872485 kernel: Early memory node ranges May 12 13:31:34.872491 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] May 12 13:31:34.872497 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] May 12 13:31:34.872503 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] May 12 13:31:34.872509 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] May 12 13:31:34.872515 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] May 12 13:31:34.872521 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] May 12 13:31:34.872527 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] May 12 13:31:34.872532 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] May 12 13:31:34.872540 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] May 12 13:31:34.872546 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] May 12 13:31:34.872554 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] May 12 13:31:34.872561 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] May 12 13:31:34.872567 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] May 12 13:31:34.872575 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] May 12 13:31:34.872581 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges May 12 13:31:34.872587 kernel: psci: probing for conduit method from ACPI. May 12 13:31:34.872594 kernel: psci: PSCIv1.1 detected in firmware. May 12 13:31:34.872600 kernel: psci: Using standard PSCI v0.2 function IDs May 12 13:31:34.872606 kernel: psci: Trusted OS migration not required May 12 13:31:34.872612 kernel: psci: SMC Calling Convention v1.1 May 12 13:31:34.872619 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) May 12 13:31:34.872625 kernel: percpu: Embedded 31 pages/cpu s87016 r8192 d31768 u126976 May 12 13:31:34.872631 kernel: pcpu-alloc: s87016 r8192 d31768 u126976 alloc=31*4096 May 12 13:31:34.872638 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 12 13:31:34.872645 kernel: Detected PIPT I-cache on CPU0 May 12 13:31:34.872652 kernel: CPU features: detected: GIC system register CPU interface May 12 13:31:34.872658 kernel: CPU features: detected: Hardware dirty bit management May 12 13:31:34.872664 kernel: CPU features: detected: Spectre-v4 May 12 13:31:34.872671 kernel: CPU features: detected: Spectre-BHB May 12 13:31:34.872677 kernel: CPU features: kernel page table isolation forced ON by KASLR May 12 13:31:34.872683 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 12 13:31:34.872690 kernel: CPU features: detected: ARM erratum 1418040 May 12 13:31:34.872696 kernel: CPU features: detected: SSBS not fully self-synchronizing May 12 13:31:34.872702 kernel: alternatives: applying boot alternatives May 12 13:31:34.872710 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=653a96bf2da883847e3396e932e31f09e53181a834ffc22434c3993d29b70a16 May 12 13:31:34.872718 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 12 13:31:34.872724 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 12 13:31:34.872731 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 12 13:31:34.872737 kernel: Fallback order for Node 0: 0 May 12 13:31:34.872743 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 May 12 13:31:34.872750 kernel: Policy zone: DMA May 12 13:31:34.872756 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 12 13:31:34.872762 kernel: software IO TLB: area num 4. May 12 13:31:34.872769 kernel: software IO TLB: mapped [mem 0x00000000d5000000-0x00000000d9000000] (64MB) May 12 13:31:34.872775 kernel: Memory: 2386496K/2572288K available (10432K kernel code, 2202K rwdata, 8168K rodata, 39040K init, 993K bss, 185792K reserved, 0K cma-reserved) May 12 13:31:34.872782 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 12 13:31:34.872789 kernel: rcu: Preemptible hierarchical RCU implementation. May 12 13:31:34.872805 kernel: rcu: RCU event tracing is enabled. May 12 13:31:34.872812 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 12 13:31:34.872818 kernel: Trampoline variant of Tasks RCU enabled. May 12 13:31:34.872825 kernel: Tracing variant of Tasks RCU enabled. May 12 13:31:34.872831 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 12 13:31:34.872838 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 12 13:31:34.872844 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 12 13:31:34.872851 kernel: GICv3: 256 SPIs implemented May 12 13:31:34.872857 kernel: GICv3: 0 Extended SPIs implemented May 12 13:31:34.872874 kernel: Root IRQ handler: gic_handle_irq May 12 13:31:34.872881 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 12 13:31:34.872889 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 May 12 13:31:34.872895 kernel: ITS [mem 0x08080000-0x0809ffff] May 12 13:31:34.872902 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) May 12 13:31:34.872908 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) May 12 13:31:34.872915 kernel: GICv3: using LPI property table @0x00000000400f0000 May 12 13:31:34.872921 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 May 12 13:31:34.872928 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 12 13:31:34.872934 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 12 13:31:34.872941 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 12 13:31:34.872947 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 12 13:31:34.872954 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 12 13:31:34.872961 kernel: arm-pv: using stolen time PV May 12 13:31:34.872968 kernel: Console: colour dummy device 80x25 May 12 13:31:34.872975 kernel: ACPI: Core revision 20230628 May 12 13:31:34.872982 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 12 13:31:34.872989 kernel: pid_max: default: 32768 minimum: 301 May 12 13:31:34.872995 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 12 13:31:34.873002 kernel: landlock: Up and running. May 12 13:31:34.873008 kernel: SELinux: Initializing. May 12 13:31:34.873015 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 12 13:31:34.873023 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 12 13:31:34.873029 kernel: ACPI PPTT: PPTT table found, but unable to locate core 3 (3) May 12 13:31:34.873036 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 12 13:31:34.873043 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 12 13:31:34.873049 kernel: rcu: Hierarchical SRCU implementation. May 12 13:31:34.873056 kernel: rcu: Max phase no-delay instances is 400. May 12 13:31:34.873062 kernel: Platform MSI: ITS@0x8080000 domain created May 12 13:31:34.873069 kernel: PCI/MSI: ITS@0x8080000 domain created May 12 13:31:34.873075 kernel: Remapping and enabling EFI services. May 12 13:31:34.873083 kernel: smp: Bringing up secondary CPUs ... May 12 13:31:34.873094 kernel: Detected PIPT I-cache on CPU1 May 12 13:31:34.873101 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 May 12 13:31:34.873109 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 May 12 13:31:34.873116 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 12 13:31:34.873127 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 12 13:31:34.873136 kernel: Detected PIPT I-cache on CPU2 May 12 13:31:34.873145 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 May 12 13:31:34.873153 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 May 12 13:31:34.873163 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 12 13:31:34.873171 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] May 12 13:31:34.873181 kernel: Detected PIPT I-cache on CPU3 May 12 13:31:34.873189 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 May 12 13:31:34.873196 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 May 12 13:31:34.873203 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 12 13:31:34.873209 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] May 12 13:31:34.873216 kernel: smp: Brought up 1 node, 4 CPUs May 12 13:31:34.873223 kernel: SMP: Total of 4 processors activated. May 12 13:31:34.873231 kernel: CPU features: detected: 32-bit EL0 Support May 12 13:31:34.873238 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 12 13:31:34.873245 kernel: CPU features: detected: Common not Private translations May 12 13:31:34.873252 kernel: CPU features: detected: CRC32 instructions May 12 13:31:34.873259 kernel: CPU features: detected: Enhanced Virtualization Traps May 12 13:31:34.873266 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 12 13:31:34.873273 kernel: CPU features: detected: LSE atomic instructions May 12 13:31:34.873279 kernel: CPU features: detected: Privileged Access Never May 12 13:31:34.873286 kernel: CPU features: detected: RAS Extension Support May 12 13:31:34.873294 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 12 13:31:34.873301 kernel: CPU: All CPU(s) started at EL1 May 12 13:31:34.873308 kernel: alternatives: applying system-wide alternatives May 12 13:31:34.873315 kernel: devtmpfs: initialized May 12 13:31:34.873322 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 12 13:31:34.873329 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 12 13:31:34.873335 kernel: pinctrl core: initialized pinctrl subsystem May 12 13:31:34.873342 kernel: SMBIOS 3.0.0 present. May 12 13:31:34.873349 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 May 12 13:31:34.873356 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 12 13:31:34.873363 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 12 13:31:34.873370 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 12 13:31:34.873377 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 12 13:31:34.873384 kernel: audit: initializing netlink subsys (disabled) May 12 13:31:34.873391 kernel: audit: type=2000 audit(0.018:1): state=initialized audit_enabled=0 res=1 May 12 13:31:34.873398 kernel: thermal_sys: Registered thermal governor 'step_wise' May 12 13:31:34.873405 kernel: cpuidle: using governor menu May 12 13:31:34.873412 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 12 13:31:34.873420 kernel: ASID allocator initialised with 32768 entries May 12 13:31:34.873427 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 12 13:31:34.873433 kernel: Serial: AMBA PL011 UART driver May 12 13:31:34.873441 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 12 13:31:34.873447 kernel: Modules: 0 pages in range for non-PLT usage May 12 13:31:34.873454 kernel: Modules: 509024 pages in range for PLT usage May 12 13:31:34.873461 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 12 13:31:34.873468 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 12 13:31:34.873475 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 12 13:31:34.873483 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 12 13:31:34.873489 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 12 13:31:34.873496 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 12 13:31:34.873503 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 12 13:31:34.873510 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 12 13:31:34.873517 kernel: ACPI: Added _OSI(Module Device) May 12 13:31:34.873523 kernel: ACPI: Added _OSI(Processor Device) May 12 13:31:34.873530 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 12 13:31:34.873537 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 12 13:31:34.873545 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 12 13:31:34.873552 kernel: ACPI: Interpreter enabled May 12 13:31:34.873559 kernel: ACPI: Using GIC for interrupt routing May 12 13:31:34.873565 kernel: ACPI: MCFG table detected, 1 entries May 12 13:31:34.873572 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA May 12 13:31:34.873579 kernel: printk: console [ttyAMA0] enabled May 12 13:31:34.873586 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 12 13:31:34.873711 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 12 13:31:34.873784 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] May 12 13:31:34.873859 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] May 12 13:31:34.873943 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 May 12 13:31:34.874005 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] May 12 13:31:34.874014 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] May 12 13:31:34.874021 kernel: PCI host bridge to bus 0000:00 May 12 13:31:34.874089 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] May 12 13:31:34.874150 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] May 12 13:31:34.874206 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] May 12 13:31:34.874261 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 12 13:31:34.874337 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 May 12 13:31:34.874410 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 May 12 13:31:34.874479 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] May 12 13:31:34.874560 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] May 12 13:31:34.874632 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] May 12 13:31:34.874701 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] May 12 13:31:34.874764 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] May 12 13:31:34.874838 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] May 12 13:31:34.874949 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] May 12 13:31:34.875006 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] May 12 13:31:34.875060 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] May 12 13:31:34.875073 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 May 12 13:31:34.875081 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 May 12 13:31:34.875088 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 May 12 13:31:34.875095 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 May 12 13:31:34.875102 kernel: iommu: Default domain type: Translated May 12 13:31:34.875109 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 12 13:31:34.875116 kernel: efivars: Registered efivars operations May 12 13:31:34.875122 kernel: vgaarb: loaded May 12 13:31:34.875129 kernel: clocksource: Switched to clocksource arch_sys_counter May 12 13:31:34.875137 kernel: VFS: Disk quotas dquot_6.6.0 May 12 13:31:34.875144 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 12 13:31:34.875151 kernel: pnp: PnP ACPI init May 12 13:31:34.875227 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved May 12 13:31:34.875237 kernel: pnp: PnP ACPI: found 1 devices May 12 13:31:34.875244 kernel: NET: Registered PF_INET protocol family May 12 13:31:34.875251 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 12 13:31:34.875258 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 12 13:31:34.875267 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 12 13:31:34.875275 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 12 13:31:34.875282 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 12 13:31:34.875289 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 12 13:31:34.875296 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 12 13:31:34.875317 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 12 13:31:34.875324 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 12 13:31:34.875331 kernel: PCI: CLS 0 bytes, default 64 May 12 13:31:34.875338 kernel: kvm [1]: HYP mode not available May 12 13:31:34.875346 kernel: Initialise system trusted keyrings May 12 13:31:34.875353 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 12 13:31:34.875360 kernel: Key type asymmetric registered May 12 13:31:34.875367 kernel: Asymmetric key parser 'x509' registered May 12 13:31:34.875374 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 12 13:31:34.875381 kernel: io scheduler mq-deadline registered May 12 13:31:34.875388 kernel: io scheduler kyber registered May 12 13:31:34.875395 kernel: io scheduler bfq registered May 12 13:31:34.875402 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 May 12 13:31:34.875410 kernel: ACPI: button: Power Button [PWRB] May 12 13:31:34.875417 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 May 12 13:31:34.875481 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) May 12 13:31:34.875491 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 12 13:31:34.875498 kernel: thunder_xcv, ver 1.0 May 12 13:31:34.875505 kernel: thunder_bgx, ver 1.0 May 12 13:31:34.875511 kernel: nicpf, ver 1.0 May 12 13:31:34.875518 kernel: nicvf, ver 1.0 May 12 13:31:34.875586 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 12 13:31:34.875647 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-12T13:31:34 UTC (1747056694) May 12 13:31:34.875656 kernel: hid: raw HID events driver (C) Jiri Kosina May 12 13:31:34.875663 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available May 12 13:31:34.875670 kernel: watchdog: Delayed init of the lockup detector failed: -19 May 12 13:31:34.875677 kernel: watchdog: Hard watchdog permanently disabled May 12 13:31:34.875684 kernel: NET: Registered PF_INET6 protocol family May 12 13:31:34.875691 kernel: Segment Routing with IPv6 May 12 13:31:34.875698 kernel: In-situ OAM (IOAM) with IPv6 May 12 13:31:34.875707 kernel: NET: Registered PF_PACKET protocol family May 12 13:31:34.875714 kernel: Key type dns_resolver registered May 12 13:31:34.875720 kernel: registered taskstats version 1 May 12 13:31:34.875727 kernel: Loading compiled-in X.509 certificates May 12 13:31:34.875734 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: 8a19376c4ffd0604cd5425566348a3f0eeb277da' May 12 13:31:34.875741 kernel: Key type .fscrypt registered May 12 13:31:34.875747 kernel: Key type fscrypt-provisioning registered May 12 13:31:34.875754 kernel: ima: No TPM chip found, activating TPM-bypass! May 12 13:31:34.875762 kernel: ima: Allocated hash algorithm: sha1 May 12 13:31:34.875769 kernel: ima: No architecture policies found May 12 13:31:34.875776 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 12 13:31:34.875783 kernel: clk: Disabling unused clocks May 12 13:31:34.875790 kernel: Warning: unable to open an initial console. May 12 13:31:34.875805 kernel: Freeing unused kernel memory: 39040K May 12 13:31:34.875812 kernel: Run /init as init process May 12 13:31:34.875819 kernel: with arguments: May 12 13:31:34.875826 kernel: /init May 12 13:31:34.875832 kernel: with environment: May 12 13:31:34.875841 kernel: HOME=/ May 12 13:31:34.875848 kernel: TERM=linux May 12 13:31:34.875855 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 12 13:31:34.875872 systemd[1]: Successfully made /usr/ read-only. May 12 13:31:34.875883 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 12 13:31:34.875891 systemd[1]: Detected virtualization kvm. May 12 13:31:34.875898 systemd[1]: Detected architecture arm64. May 12 13:31:34.875908 systemd[1]: Running in initrd. May 12 13:31:34.875915 systemd[1]: No hostname configured, using default hostname. May 12 13:31:34.875923 systemd[1]: Hostname set to . May 12 13:31:34.875930 systemd[1]: Initializing machine ID from VM UUID. May 12 13:31:34.875937 systemd[1]: Queued start job for default target initrd.target. May 12 13:31:34.875944 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 12 13:31:34.875952 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 12 13:31:34.875960 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 12 13:31:34.875968 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 12 13:31:34.875976 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 12 13:31:34.875984 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 12 13:31:34.875992 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 12 13:31:34.876000 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 12 13:31:34.876013 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 12 13:31:34.876021 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 12 13:31:34.876029 systemd[1]: Reached target paths.target - Path Units. May 12 13:31:34.876037 systemd[1]: Reached target slices.target - Slice Units. May 12 13:31:34.876044 systemd[1]: Reached target swap.target - Swaps. May 12 13:31:34.876051 systemd[1]: Reached target timers.target - Timer Units. May 12 13:31:34.876059 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 12 13:31:34.876066 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 12 13:31:34.876074 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 12 13:31:34.876081 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 12 13:31:34.876089 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 12 13:31:34.876097 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 12 13:31:34.876105 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 12 13:31:34.876112 systemd[1]: Reached target sockets.target - Socket Units. May 12 13:31:34.876119 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 12 13:31:34.876127 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 12 13:31:34.876134 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 12 13:31:34.876142 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 12 13:31:34.876149 systemd[1]: Starting systemd-fsck-usr.service... May 12 13:31:34.876158 systemd[1]: Starting systemd-journald.service - Journal Service... May 12 13:31:34.876165 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 12 13:31:34.876173 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 12 13:31:34.876180 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 12 13:31:34.876188 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 12 13:31:34.876197 systemd[1]: Finished systemd-fsck-usr.service. May 12 13:31:34.876204 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 12 13:31:34.876228 systemd-journald[239]: Collecting audit messages is disabled. May 12 13:31:34.876248 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 12 13:31:34.876256 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 12 13:31:34.876263 kernel: Bridge firewalling registered May 12 13:31:34.876271 systemd-journald[239]: Journal started May 12 13:31:34.876289 systemd-journald[239]: Runtime Journal (/run/log/journal/3fc0e8538aa141c591d75c99aa9b0e80) is 5.9M, max 47.3M, 41.4M free. May 12 13:31:34.857965 systemd-modules-load[240]: Inserted module 'overlay' May 12 13:31:34.874612 systemd-modules-load[240]: Inserted module 'br_netfilter' May 12 13:31:34.880126 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 12 13:31:34.880144 systemd[1]: Started systemd-journald.service - Journal Service. May 12 13:31:34.881047 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 12 13:31:34.884229 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 12 13:31:34.885612 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 12 13:31:34.887986 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 12 13:31:34.896112 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 12 13:31:34.901926 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 12 13:31:34.903067 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 12 13:31:34.904429 systemd-tmpfiles[265]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 12 13:31:34.907442 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 12 13:31:34.909633 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 12 13:31:34.912345 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 12 13:31:34.914515 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 12 13:31:34.933765 dracut-cmdline[281]: 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=653a96bf2da883847e3396e932e31f09e53181a834ffc22434c3993d29b70a16 May 12 13:31:34.947471 systemd-resolved[278]: Positive Trust Anchors: May 12 13:31:34.947487 systemd-resolved[278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 12 13:31:34.947517 systemd-resolved[278]: 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 12 13:31:34.952233 systemd-resolved[278]: Defaulting to hostname 'linux'. May 12 13:31:34.953226 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 12 13:31:34.954654 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 12 13:31:35.007888 kernel: SCSI subsystem initialized May 12 13:31:35.011884 kernel: Loading iSCSI transport class v2.0-870. May 12 13:31:35.018879 kernel: iscsi: registered transport (tcp) May 12 13:31:35.033054 kernel: iscsi: registered transport (qla4xxx) May 12 13:31:35.033085 kernel: QLogic iSCSI HBA Driver May 12 13:31:35.050265 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 12 13:31:35.067838 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 12 13:31:35.069605 systemd[1]: Reached target network-pre.target - Preparation for Network. May 12 13:31:35.112286 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 12 13:31:35.114193 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 12 13:31:35.173908 kernel: raid6: neonx8 gen() 15793 MB/s May 12 13:31:35.190895 kernel: raid6: neonx4 gen() 15815 MB/s May 12 13:31:35.207891 kernel: raid6: neonx2 gen() 13234 MB/s May 12 13:31:35.224888 kernel: raid6: neonx1 gen() 10524 MB/s May 12 13:31:35.241889 kernel: raid6: int64x8 gen() 6795 MB/s May 12 13:31:35.258887 kernel: raid6: int64x4 gen() 7349 MB/s May 12 13:31:35.275906 kernel: raid6: int64x2 gen() 6105 MB/s May 12 13:31:35.292906 kernel: raid6: int64x1 gen() 5052 MB/s May 12 13:31:35.292937 kernel: raid6: using algorithm neonx4 gen() 15815 MB/s May 12 13:31:35.309901 kernel: raid6: .... xor() 12457 MB/s, rmw enabled May 12 13:31:35.309928 kernel: raid6: using neon recovery algorithm May 12 13:31:35.315128 kernel: xor: measuring software checksum speed May 12 13:31:35.315152 kernel: 8regs : 21636 MB/sec May 12 13:31:35.315169 kernel: 32regs : 21704 MB/sec May 12 13:31:35.316050 kernel: arm64_neon : 27993 MB/sec May 12 13:31:35.316061 kernel: xor: using function: arm64_neon (27993 MB/sec) May 12 13:31:35.364888 kernel: Btrfs loaded, zoned=no, fsverity=no May 12 13:31:35.371400 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 12 13:31:35.373557 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 12 13:31:35.402936 systemd-udevd[492]: Using default interface naming scheme 'v255'. May 12 13:31:35.407021 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 12 13:31:35.410449 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 12 13:31:35.429223 dracut-pre-trigger[499]: rd.md=0: removing MD RAID activation May 12 13:31:35.451498 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 12 13:31:35.453618 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 12 13:31:35.505902 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 12 13:31:35.509047 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 12 13:31:35.549912 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues May 12 13:31:35.556868 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 12 13:31:35.559950 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 12 13:31:35.559984 kernel: GPT:9289727 != 19775487 May 12 13:31:35.559994 kernel: GPT:Alternate GPT header not at the end of the disk. May 12 13:31:35.560003 kernel: GPT:9289727 != 19775487 May 12 13:31:35.560953 kernel: GPT: Use GNU Parted to correct GPT errors. May 12 13:31:35.560987 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 12 13:31:35.563314 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 12 13:31:35.563435 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 12 13:31:35.567359 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 12 13:31:35.569509 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 12 13:31:35.585908 kernel: BTRFS: device fsid 883e681e-770a-479b-951e-bb0dc342f721 devid 1 transid 42 /dev/vda3 scanned by (udev-worker) (541) May 12 13:31:35.586901 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (546) May 12 13:31:35.593926 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 12 13:31:35.594991 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 12 13:31:35.608689 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 12 13:31:35.610564 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 12 13:31:35.621328 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 12 13:31:35.622245 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 12 13:31:35.630224 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 12 13:31:35.631185 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 12 13:31:35.632738 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 12 13:31:35.634323 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 12 13:31:35.636403 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 12 13:31:35.637878 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 12 13:31:35.654755 disk-uuid[586]: Primary Header is updated. May 12 13:31:35.654755 disk-uuid[586]: Secondary Entries is updated. May 12 13:31:35.654755 disk-uuid[586]: Secondary Header is updated. May 12 13:31:35.657886 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 12 13:31:35.658922 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 12 13:31:36.671886 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 12 13:31:36.672014 disk-uuid[590]: The operation has completed successfully. May 12 13:31:36.697086 systemd[1]: disk-uuid.service: Deactivated successfully. May 12 13:31:36.697197 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 12 13:31:36.720941 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 12 13:31:36.736464 sh[607]: Success May 12 13:31:36.748847 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 12 13:31:36.748896 kernel: device-mapper: uevent: version 1.0.3 May 12 13:31:36.748908 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 12 13:31:36.762904 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 12 13:31:36.787767 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 12 13:31:36.789247 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 12 13:31:36.802942 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 12 13:31:36.808138 kernel: BTRFS info (device dm-0): first mount of filesystem 883e681e-770a-479b-951e-bb0dc342f721 May 12 13:31:36.808162 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 12 13:31:36.808973 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 12 13:31:36.808986 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 12 13:31:36.809977 kernel: BTRFS info (device dm-0): using free space tree May 12 13:31:36.813829 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 12 13:31:36.814600 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 12 13:31:36.815774 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 12 13:31:36.816386 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 12 13:31:36.817694 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 12 13:31:36.838266 kernel: BTRFS info (device vda6): first mount of filesystem c2183054-24ef-4008-8a3e-033aff1dab63 May 12 13:31:36.838301 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 12 13:31:36.838311 kernel: BTRFS info (device vda6): using free space tree May 12 13:31:36.840924 kernel: BTRFS info (device vda6): auto enabling async discard May 12 13:31:36.843897 kernel: BTRFS info (device vda6): last unmount of filesystem c2183054-24ef-4008-8a3e-033aff1dab63 May 12 13:31:36.846697 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 12 13:31:36.848992 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 12 13:31:36.916751 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 12 13:31:36.920017 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 12 13:31:36.970276 systemd-networkd[794]: lo: Link UP May 12 13:31:36.971009 systemd-networkd[794]: lo: Gained carrier May 12 13:31:36.972560 systemd-networkd[794]: Enumeration completed May 12 13:31:36.972831 systemd[1]: Started systemd-networkd.service - Network Configuration. May 12 13:31:36.972979 systemd-networkd[794]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 12 13:31:36.972983 systemd-networkd[794]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 12 13:31:36.973548 systemd-networkd[794]: eth0: Link UP May 12 13:31:36.973551 systemd-networkd[794]: eth0: Gained carrier May 12 13:31:36.973558 systemd-networkd[794]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 12 13:31:36.974036 systemd[1]: Reached target network.target - Network. May 12 13:31:36.993325 ignition[698]: Ignition 2.21.0 May 12 13:31:36.993342 ignition[698]: Stage: fetch-offline May 12 13:31:36.993371 ignition[698]: no configs at "/usr/lib/ignition/base.d" May 12 13:31:36.993379 ignition[698]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 12 13:31:36.993838 ignition[698]: parsed url from cmdline: "" May 12 13:31:36.993842 ignition[698]: no config URL provided May 12 13:31:36.993848 ignition[698]: reading system config file "/usr/lib/ignition/user.ign" May 12 13:31:36.993860 ignition[698]: no config at "/usr/lib/ignition/user.ign" May 12 13:31:36.997613 systemd-networkd[794]: eth0: DHCPv4 address 10.0.0.91/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 12 13:31:36.993902 ignition[698]: op(1): [started] loading QEMU firmware config module May 12 13:31:36.993907 ignition[698]: op(1): executing: "modprobe" "qemu_fw_cfg" May 12 13:31:37.002313 ignition[698]: op(1): [finished] loading QEMU firmware config module May 12 13:31:37.007994 ignition[698]: parsing config with SHA512: a200fd7bdabb0b60be8df221d0e95f6b22520c3694d201c88ce47ac41977468c578975c5afcfb279a3df01f7692096ba7cc3bb0fa87c0dae1ff66910226a5496 May 12 13:31:37.012958 unknown[698]: fetched base config from "system" May 12 13:31:37.012971 unknown[698]: fetched user config from "qemu" May 12 13:31:37.013211 ignition[698]: fetch-offline: fetch-offline passed May 12 13:31:37.013260 ignition[698]: Ignition finished successfully May 12 13:31:37.015589 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 12 13:31:37.016886 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 12 13:31:37.017588 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 12 13:31:37.041326 ignition[808]: Ignition 2.21.0 May 12 13:31:37.041345 ignition[808]: Stage: kargs May 12 13:31:37.041467 ignition[808]: no configs at "/usr/lib/ignition/base.d" May 12 13:31:37.041477 ignition[808]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 12 13:31:37.042043 ignition[808]: kargs: kargs passed May 12 13:31:37.042083 ignition[808]: Ignition finished successfully May 12 13:31:37.045173 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 12 13:31:37.046718 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 12 13:31:37.075299 ignition[816]: Ignition 2.21.0 May 12 13:31:37.075319 ignition[816]: Stage: disks May 12 13:31:37.075439 ignition[816]: no configs at "/usr/lib/ignition/base.d" May 12 13:31:37.075449 ignition[816]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 12 13:31:37.076025 ignition[816]: disks: disks passed May 12 13:31:37.076068 ignition[816]: Ignition finished successfully May 12 13:31:37.078512 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 12 13:31:37.079638 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 12 13:31:37.080792 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 12 13:31:37.082334 systemd[1]: Reached target local-fs.target - Local File Systems. May 12 13:31:37.083702 systemd[1]: Reached target sysinit.target - System Initialization. May 12 13:31:37.084979 systemd[1]: Reached target basic.target - Basic System. May 12 13:31:37.087041 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 12 13:31:37.108914 systemd-fsck[827]: ROOT: clean, 15/553520 files, 52789/553472 blocks May 12 13:31:37.112097 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 12 13:31:37.113747 systemd[1]: Mounting sysroot.mount - /sysroot... May 12 13:31:37.169881 kernel: EXT4-fs (vda9): mounted filesystem bc1f18c3-3425-4388-a617-b7347003d935 r/w with ordered data mode. Quota mode: none. May 12 13:31:37.170619 systemd[1]: Mounted sysroot.mount - /sysroot. May 12 13:31:37.171651 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 12 13:31:37.173525 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 12 13:31:37.174893 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 12 13:31:37.175651 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 12 13:31:37.175688 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 12 13:31:37.175709 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 12 13:31:37.190127 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 12 13:31:37.192313 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 12 13:31:37.196981 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (835) May 12 13:31:37.197009 kernel: BTRFS info (device vda6): first mount of filesystem c2183054-24ef-4008-8a3e-033aff1dab63 May 12 13:31:37.197019 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 12 13:31:37.197028 kernel: BTRFS info (device vda6): using free space tree May 12 13:31:37.197037 kernel: BTRFS info (device vda6): auto enabling async discard May 12 13:31:37.206987 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 12 13:31:37.250918 initrd-setup-root[859]: cut: /sysroot/etc/passwd: No such file or directory May 12 13:31:37.253960 initrd-setup-root[866]: cut: /sysroot/etc/group: No such file or directory May 12 13:31:37.257592 initrd-setup-root[873]: cut: /sysroot/etc/shadow: No such file or directory May 12 13:31:37.261117 initrd-setup-root[880]: cut: /sysroot/etc/gshadow: No such file or directory May 12 13:31:37.327746 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 12 13:31:37.329484 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 12 13:31:37.330777 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 12 13:31:37.347899 kernel: BTRFS info (device vda6): last unmount of filesystem c2183054-24ef-4008-8a3e-033aff1dab63 May 12 13:31:37.360060 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 12 13:31:37.371817 ignition[949]: INFO : Ignition 2.21.0 May 12 13:31:37.371817 ignition[949]: INFO : Stage: mount May 12 13:31:37.373216 ignition[949]: INFO : no configs at "/usr/lib/ignition/base.d" May 12 13:31:37.373216 ignition[949]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 12 13:31:37.373216 ignition[949]: INFO : mount: mount passed May 12 13:31:37.373216 ignition[949]: INFO : Ignition finished successfully May 12 13:31:37.375450 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 12 13:31:37.377497 systemd[1]: Starting ignition-files.service - Ignition (files)... May 12 13:31:37.942573 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 12 13:31:37.945037 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 12 13:31:37.958878 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (961) May 12 13:31:37.960454 kernel: BTRFS info (device vda6): first mount of filesystem c2183054-24ef-4008-8a3e-033aff1dab63 May 12 13:31:37.960478 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm May 12 13:31:37.960489 kernel: BTRFS info (device vda6): using free space tree May 12 13:31:37.962881 kernel: BTRFS info (device vda6): auto enabling async discard May 12 13:31:37.963853 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 12 13:31:37.996354 ignition[978]: INFO : Ignition 2.21.0 May 12 13:31:37.996354 ignition[978]: INFO : Stage: files May 12 13:31:37.998690 ignition[978]: INFO : no configs at "/usr/lib/ignition/base.d" May 12 13:31:37.998690 ignition[978]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 12 13:31:37.998690 ignition[978]: DEBUG : files: compiled without relabeling support, skipping May 12 13:31:37.998690 ignition[978]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 12 13:31:37.998690 ignition[978]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 12 13:31:38.003152 ignition[978]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 12 13:31:38.003152 ignition[978]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 12 13:31:38.003152 ignition[978]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 12 13:31:38.003152 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 12 13:31:38.003152 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 12 13:31:38.003152 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 12 13:31:38.003152 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 12 13:31:38.003152 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 12 13:31:38.003152 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 12 13:31:38.003152 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 12 13:31:38.003152 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-arm64.raw: attempt #1 May 12 13:31:38.000775 unknown[978]: wrote ssh authorized keys file for user: core May 12 13:31:38.269007 systemd-networkd[794]: eth0: Gained IPv6LL May 12 13:31:38.353892 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 12 13:31:38.573330 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-arm64.raw" May 12 13:31:38.573330 ignition[978]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 12 13:31:38.576079 ignition[978]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 12 13:31:38.576079 ignition[978]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 12 13:31:38.576079 ignition[978]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 12 13:31:38.576079 ignition[978]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" May 12 13:31:38.589222 ignition[978]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" May 12 13:31:38.592340 ignition[978]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 12 13:31:38.594757 ignition[978]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" May 12 13:31:38.594757 ignition[978]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 12 13:31:38.594757 ignition[978]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 12 13:31:38.594757 ignition[978]: INFO : files: files passed May 12 13:31:38.594757 ignition[978]: INFO : Ignition finished successfully May 12 13:31:38.595388 systemd[1]: Finished ignition-files.service - Ignition (files). May 12 13:31:38.597989 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 12 13:31:38.600317 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 12 13:31:38.610929 systemd[1]: ignition-quench.service: Deactivated successfully. May 12 13:31:38.611695 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 12 13:31:38.613315 initrd-setup-root-after-ignition[1006]: grep: /sysroot/oem/oem-release: No such file or directory May 12 13:31:38.615130 initrd-setup-root-after-ignition[1009]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 12 13:31:38.615130 initrd-setup-root-after-ignition[1009]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 12 13:31:38.617313 initrd-setup-root-after-ignition[1013]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 12 13:31:38.617921 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 12 13:31:38.619369 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 12 13:31:38.621384 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 12 13:31:38.657558 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 12 13:31:38.657668 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 12 13:31:38.659271 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 12 13:31:38.660608 systemd[1]: Reached target initrd.target - Initrd Default Target. May 12 13:31:38.661931 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 12 13:31:38.662570 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 12 13:31:38.685559 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 12 13:31:38.687573 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 12 13:31:38.705069 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 12 13:31:38.705982 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 12 13:31:38.707589 systemd[1]: Stopped target timers.target - Timer Units. May 12 13:31:38.708928 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 12 13:31:38.709022 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 12 13:31:38.711007 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 12 13:31:38.712435 systemd[1]: Stopped target basic.target - Basic System. May 12 13:31:38.713600 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 12 13:31:38.715003 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 12 13:31:38.716425 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 12 13:31:38.717832 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 12 13:31:38.719334 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 12 13:31:38.720659 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 12 13:31:38.722192 systemd[1]: Stopped target sysinit.target - System Initialization. May 12 13:31:38.723623 systemd[1]: Stopped target local-fs.target - Local File Systems. May 12 13:31:38.724884 systemd[1]: Stopped target swap.target - Swaps. May 12 13:31:38.726006 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 12 13:31:38.726106 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 12 13:31:38.727805 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 12 13:31:38.729218 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 12 13:31:38.730739 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 12 13:31:38.733920 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 12 13:31:38.734926 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 12 13:31:38.735026 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 12 13:31:38.737238 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 12 13:31:38.737348 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 12 13:31:38.738794 systemd[1]: Stopped target paths.target - Path Units. May 12 13:31:38.739931 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 12 13:31:38.740019 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 12 13:31:38.741536 systemd[1]: Stopped target slices.target - Slice Units. May 12 13:31:38.742718 systemd[1]: Stopped target sockets.target - Socket Units. May 12 13:31:38.743994 systemd[1]: iscsid.socket: Deactivated successfully. May 12 13:31:38.744068 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 12 13:31:38.745651 systemd[1]: iscsiuio.socket: Deactivated successfully. May 12 13:31:38.745722 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 12 13:31:38.746855 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 12 13:31:38.746967 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 12 13:31:38.748291 systemd[1]: ignition-files.service: Deactivated successfully. May 12 13:31:38.748383 systemd[1]: Stopped ignition-files.service - Ignition (files). May 12 13:31:38.750121 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 12 13:31:38.751172 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 12 13:31:38.751287 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 12 13:31:38.764374 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 12 13:31:38.765033 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 12 13:31:38.765140 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 12 13:31:38.766494 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 12 13:31:38.766577 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 12 13:31:38.772363 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 12 13:31:38.772449 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 12 13:31:38.777219 ignition[1033]: INFO : Ignition 2.21.0 May 12 13:31:38.777219 ignition[1033]: INFO : Stage: umount May 12 13:31:38.778751 ignition[1033]: INFO : no configs at "/usr/lib/ignition/base.d" May 12 13:31:38.778751 ignition[1033]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 12 13:31:38.778751 ignition[1033]: INFO : umount: umount passed May 12 13:31:38.778751 ignition[1033]: INFO : Ignition finished successfully May 12 13:31:38.777705 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 12 13:31:38.780067 systemd[1]: ignition-mount.service: Deactivated successfully. May 12 13:31:38.780924 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 12 13:31:38.782196 systemd[1]: Stopped target network.target - Network. May 12 13:31:38.783202 systemd[1]: ignition-disks.service: Deactivated successfully. May 12 13:31:38.783258 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 12 13:31:38.784464 systemd[1]: ignition-kargs.service: Deactivated successfully. May 12 13:31:38.784509 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 12 13:31:38.785772 systemd[1]: ignition-setup.service: Deactivated successfully. May 12 13:31:38.785821 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 12 13:31:38.787069 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 12 13:31:38.787106 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 12 13:31:38.789058 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 12 13:31:38.790247 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 12 13:31:38.793617 systemd[1]: systemd-resolved.service: Deactivated successfully. May 12 13:31:38.793751 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 12 13:31:38.797020 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 12 13:31:38.797283 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 12 13:31:38.797319 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 12 13:31:38.799587 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 12 13:31:38.799805 systemd[1]: systemd-networkd.service: Deactivated successfully. May 12 13:31:38.799936 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 12 13:31:38.801601 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 12 13:31:38.802483 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 12 13:31:38.802522 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 12 13:31:38.804166 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 12 13:31:38.805487 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 12 13:31:38.805538 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 12 13:31:38.807216 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 12 13:31:38.807258 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 12 13:31:38.809533 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 12 13:31:38.809575 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 12 13:31:38.810999 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 12 13:31:38.831233 systemd[1]: sysroot-boot.service: Deactivated successfully. May 12 13:31:38.831355 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 12 13:31:38.832832 systemd[1]: systemd-udevd.service: Deactivated successfully. May 12 13:31:38.832995 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 12 13:31:38.834357 systemd[1]: network-cleanup.service: Deactivated successfully. May 12 13:31:38.836223 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 12 13:31:38.838022 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 12 13:31:38.838077 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 12 13:31:38.839622 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 12 13:31:38.839652 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 12 13:31:38.841067 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 12 13:31:38.841109 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 12 13:31:38.843031 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 12 13:31:38.843073 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 12 13:31:38.845005 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 12 13:31:38.845048 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 12 13:31:38.847158 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 12 13:31:38.847196 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 12 13:31:38.849160 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 12 13:31:38.850044 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 12 13:31:38.850092 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 12 13:31:38.852174 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 12 13:31:38.852211 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 12 13:31:38.854432 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 12 13:31:38.854473 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 12 13:31:38.862569 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 12 13:31:38.862676 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 12 13:31:38.864338 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 12 13:31:38.867120 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 12 13:31:38.880286 systemd[1]: Switching root. May 12 13:31:38.910449 systemd-journald[239]: Journal stopped May 12 13:31:39.611527 systemd-journald[239]: Received SIGTERM from PID 1 (systemd). May 12 13:31:39.611578 kernel: SELinux: policy capability network_peer_controls=1 May 12 13:31:39.611592 kernel: SELinux: policy capability open_perms=1 May 12 13:31:39.611602 kernel: SELinux: policy capability extended_socket_class=1 May 12 13:31:39.611611 kernel: SELinux: policy capability always_check_network=0 May 12 13:31:39.611621 kernel: SELinux: policy capability cgroup_seclabel=1 May 12 13:31:39.611639 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 12 13:31:39.611648 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 12 13:31:39.611657 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 12 13:31:39.611668 kernel: audit: type=1403 audit(1747056699.033:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 12 13:31:39.611680 systemd[1]: Successfully loaded SELinux policy in 32.469ms. May 12 13:31:39.611696 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.630ms. May 12 13:31:39.611709 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 12 13:31:39.611720 systemd[1]: Detected virtualization kvm. May 12 13:31:39.611731 systemd[1]: Detected architecture arm64. May 12 13:31:39.611741 systemd[1]: Detected first boot. May 12 13:31:39.611752 systemd[1]: Initializing machine ID from VM UUID. May 12 13:31:39.611762 zram_generator::config[1079]: No configuration found. May 12 13:31:39.611789 kernel: NET: Registered PF_VSOCK protocol family May 12 13:31:39.611802 systemd[1]: Populated /etc with preset unit settings. May 12 13:31:39.611813 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 12 13:31:39.611824 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 12 13:31:39.611834 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 12 13:31:39.611844 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 12 13:31:39.611854 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 12 13:31:39.611876 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 12 13:31:39.611891 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 12 13:31:39.611902 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 12 13:31:39.611912 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 12 13:31:39.611923 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 12 13:31:39.611933 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 12 13:31:39.611943 systemd[1]: Created slice user.slice - User and Session Slice. May 12 13:31:39.611953 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 12 13:31:39.611964 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 12 13:31:39.611974 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 12 13:31:39.611986 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 12 13:31:39.611996 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 12 13:31:39.612007 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 12 13:31:39.612017 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 12 13:31:39.612027 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 12 13:31:39.612038 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 12 13:31:39.612048 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 12 13:31:39.612060 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 12 13:31:39.612070 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 12 13:31:39.612080 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 12 13:31:39.612090 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 12 13:31:39.612101 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 12 13:31:39.612111 systemd[1]: Reached target slices.target - Slice Units. May 12 13:31:39.612121 systemd[1]: Reached target swap.target - Swaps. May 12 13:31:39.612131 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 12 13:31:39.612141 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 12 13:31:39.612151 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 12 13:31:39.612163 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 12 13:31:39.612173 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 12 13:31:39.612183 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 12 13:31:39.612193 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 12 13:31:39.612204 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 12 13:31:39.612214 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 12 13:31:39.612224 systemd[1]: Mounting media.mount - External Media Directory... May 12 13:31:39.612234 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 12 13:31:39.612244 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 12 13:31:39.612256 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 12 13:31:39.612267 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 12 13:31:39.612277 systemd[1]: Reached target machines.target - Containers. May 12 13:31:39.612287 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 12 13:31:39.612297 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 12 13:31:39.612308 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 12 13:31:39.612318 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 12 13:31:39.612328 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 12 13:31:39.612342 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 12 13:31:39.612352 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 12 13:31:39.612363 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 12 13:31:39.612373 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 12 13:31:39.612384 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 12 13:31:39.612394 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 12 13:31:39.612405 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 12 13:31:39.612416 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 12 13:31:39.612427 systemd[1]: Stopped systemd-fsck-usr.service. May 12 13:31:39.612439 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 12 13:31:39.612449 kernel: fuse: init (API version 7.39) May 12 13:31:39.612459 systemd[1]: Starting systemd-journald.service - Journal Service... May 12 13:31:39.612468 kernel: loop: module loaded May 12 13:31:39.612478 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 12 13:31:39.612488 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 12 13:31:39.612499 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 12 13:31:39.612509 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 12 13:31:39.612521 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 12 13:31:39.612532 systemd[1]: verity-setup.service: Deactivated successfully. May 12 13:31:39.612542 systemd[1]: Stopped verity-setup.service. May 12 13:31:39.612553 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 12 13:31:39.612564 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 12 13:31:39.612575 kernel: ACPI: bus type drm_connector registered May 12 13:31:39.612586 systemd[1]: Mounted media.mount - External Media Directory. May 12 13:31:39.612596 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 12 13:31:39.612606 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 12 13:31:39.612616 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 12 13:31:39.612626 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 12 13:31:39.612637 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 12 13:31:39.612648 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 12 13:31:39.612658 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 12 13:31:39.612670 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 12 13:31:39.612682 systemd[1]: modprobe@drm.service: Deactivated successfully. May 12 13:31:39.612692 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 12 13:31:39.612702 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 12 13:31:39.612713 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 12 13:31:39.612724 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 12 13:31:39.612755 systemd-journald[1144]: Collecting audit messages is disabled. May 12 13:31:39.612785 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 12 13:31:39.612797 systemd[1]: modprobe@loop.service: Deactivated successfully. May 12 13:31:39.612807 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 12 13:31:39.612818 systemd-journald[1144]: Journal started May 12 13:31:39.612843 systemd-journald[1144]: Runtime Journal (/run/log/journal/3fc0e8538aa141c591d75c99aa9b0e80) is 5.9M, max 47.3M, 41.4M free. May 12 13:31:39.410315 systemd[1]: Queued start job for default target multi-user.target. May 12 13:31:39.427658 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 12 13:31:39.428049 systemd[1]: systemd-journald.service: Deactivated successfully. May 12 13:31:39.615892 systemd[1]: Started systemd-journald.service - Journal Service. May 12 13:31:39.618081 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 12 13:31:39.619163 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 12 13:31:39.621410 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 12 13:31:39.622616 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 12 13:31:39.623843 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 12 13:31:39.636033 systemd[1]: Reached target network-pre.target - Preparation for Network. May 12 13:31:39.638022 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 12 13:31:39.639689 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 12 13:31:39.640545 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 12 13:31:39.640572 systemd[1]: Reached target local-fs.target - Local File Systems. May 12 13:31:39.642231 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 12 13:31:39.645569 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 12 13:31:39.646448 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 12 13:31:39.647415 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 12 13:31:39.649987 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 12 13:31:39.650952 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 12 13:31:39.651907 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 12 13:31:39.652746 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 12 13:31:39.656038 systemd-journald[1144]: Time spent on flushing to /var/log/journal/3fc0e8538aa141c591d75c99aa9b0e80 is 23.608ms for 856 entries. May 12 13:31:39.656038 systemd-journald[1144]: System Journal (/var/log/journal/3fc0e8538aa141c591d75c99aa9b0e80) is 8M, max 195.6M, 187.6M free. May 12 13:31:39.688176 systemd-journald[1144]: Received client request to flush runtime journal. May 12 13:31:39.688209 kernel: loop0: detected capacity change from 0 to 138376 May 12 13:31:39.655990 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 12 13:31:39.659077 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 12 13:31:39.660801 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 12 13:31:39.664894 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 12 13:31:39.666144 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 12 13:31:39.667217 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 12 13:31:39.669910 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 12 13:31:39.672687 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 12 13:31:39.678007 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 12 13:31:39.691301 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 12 13:31:39.694868 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 12 13:31:39.703757 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 12 13:31:39.706229 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 12 13:31:39.709343 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 12 13:31:39.719137 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 12 13:31:39.728917 kernel: loop1: detected capacity change from 0 to 107312 May 12 13:31:39.733722 systemd-tmpfiles[1211]: ACLs are not supported, ignoring. May 12 13:31:39.733739 systemd-tmpfiles[1211]: ACLs are not supported, ignoring. May 12 13:31:39.741708 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 12 13:31:39.767941 kernel: loop2: detected capacity change from 0 to 189592 May 12 13:31:39.809894 kernel: loop3: detected capacity change from 0 to 138376 May 12 13:31:39.818889 kernel: loop4: detected capacity change from 0 to 107312 May 12 13:31:39.823887 kernel: loop5: detected capacity change from 0 to 189592 May 12 13:31:39.827975 (sd-merge)[1217]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. May 12 13:31:39.828349 (sd-merge)[1217]: Merged extensions into '/usr'. May 12 13:31:39.834590 systemd[1]: Reload requested from client PID 1195 ('systemd-sysext') (unit systemd-sysext.service)... May 12 13:31:39.834612 systemd[1]: Reloading... May 12 13:31:39.879011 zram_generator::config[1240]: No configuration found. May 12 13:31:39.916216 ldconfig[1190]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 12 13:31:39.971439 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 12 13:31:40.033937 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 12 13:31:40.034219 systemd[1]: Reloading finished in 199 ms. May 12 13:31:40.053362 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 12 13:31:40.055868 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 12 13:31:40.067230 systemd[1]: Starting ensure-sysext.service... May 12 13:31:40.068759 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 12 13:31:40.079530 systemd[1]: Reload requested from client PID 1277 ('systemctl') (unit ensure-sysext.service)... May 12 13:31:40.079548 systemd[1]: Reloading... May 12 13:31:40.092512 systemd-tmpfiles[1278]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 12 13:31:40.092537 systemd-tmpfiles[1278]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 12 13:31:40.092758 systemd-tmpfiles[1278]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 12 13:31:40.092977 systemd-tmpfiles[1278]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 12 13:31:40.093586 systemd-tmpfiles[1278]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 12 13:31:40.093801 systemd-tmpfiles[1278]: ACLs are not supported, ignoring. May 12 13:31:40.093850 systemd-tmpfiles[1278]: ACLs are not supported, ignoring. May 12 13:31:40.096345 systemd-tmpfiles[1278]: Detected autofs mount point /boot during canonicalization of boot. May 12 13:31:40.096360 systemd-tmpfiles[1278]: Skipping /boot May 12 13:31:40.105520 systemd-tmpfiles[1278]: Detected autofs mount point /boot during canonicalization of boot. May 12 13:31:40.105541 systemd-tmpfiles[1278]: Skipping /boot May 12 13:31:40.128936 zram_generator::config[1308]: No configuration found. May 12 13:31:40.197624 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 12 13:31:40.261158 systemd[1]: Reloading finished in 181 ms. May 12 13:31:40.274461 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 12 13:31:40.280900 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 12 13:31:40.291510 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 12 13:31:40.293514 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 12 13:31:40.297008 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 12 13:31:40.304022 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 12 13:31:40.318831 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 12 13:31:40.320678 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 12 13:31:40.336370 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 12 13:31:40.338783 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 12 13:31:40.340981 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 12 13:31:40.343357 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 12 13:31:40.346510 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 12 13:31:40.348347 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 12 13:31:40.348469 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 12 13:31:40.349680 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 12 13:31:40.351553 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 12 13:31:40.352163 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 12 13:31:40.353565 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 12 13:31:40.353752 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 12 13:31:40.355350 systemd[1]: modprobe@loop.service: Deactivated successfully. May 12 13:31:40.355526 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 12 13:31:40.364228 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 12 13:31:40.365754 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 12 13:31:40.367917 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 12 13:31:40.368000 systemd-udevd[1351]: Using default interface naming scheme 'v255'. May 12 13:31:40.373140 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 12 13:31:40.374146 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 12 13:31:40.374323 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 12 13:31:40.381244 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 12 13:31:40.383386 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 12 13:31:40.386101 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 12 13:31:40.387770 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 12 13:31:40.387968 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 12 13:31:40.394376 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 12 13:31:40.394550 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 12 13:31:40.397330 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 12 13:31:40.399276 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 12 13:31:40.403151 augenrules[1389]: No rules May 12 13:31:40.403872 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 12 13:31:40.404906 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 12 13:31:40.405027 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 12 13:31:40.405770 systemd[1]: audit-rules.service: Deactivated successfully. May 12 13:31:40.407187 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 12 13:31:40.415019 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 12 13:31:40.416723 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 12 13:31:40.418074 systemd[1]: modprobe@loop.service: Deactivated successfully. May 12 13:31:40.418225 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 12 13:31:40.419484 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 12 13:31:40.420840 systemd[1]: modprobe@drm.service: Deactivated successfully. May 12 13:31:40.421020 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 12 13:31:40.422175 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 12 13:31:40.422320 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 12 13:31:40.432136 systemd[1]: Finished ensure-sysext.service. May 12 13:31:40.444481 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 12 13:31:40.445249 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 12 13:31:40.445318 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 12 13:31:40.451011 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 12 13:31:40.451811 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 12 13:31:40.483489 systemd-resolved[1345]: Positive Trust Anchors: May 12 13:31:40.483507 systemd-resolved[1345]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 12 13:31:40.483539 systemd-resolved[1345]: 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 12 13:31:40.486287 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 12 13:31:40.492566 systemd-resolved[1345]: Defaulting to hostname 'linux'. May 12 13:31:40.503971 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 42 scanned by (udev-worker) (1400) May 12 13:31:40.508279 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 12 13:31:40.515661 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 12 13:31:40.548818 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 12 13:31:40.551345 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 12 13:31:40.575397 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 12 13:31:40.576643 systemd[1]: Reached target sysinit.target - System Initialization. May 12 13:31:40.577858 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 12 13:31:40.578681 systemd-networkd[1427]: lo: Link UP May 12 13:31:40.578696 systemd-networkd[1427]: lo: Gained carrier May 12 13:31:40.578819 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 12 13:31:40.579715 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 12 13:31:40.581172 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 12 13:31:40.581203 systemd[1]: Reached target paths.target - Path Units. May 12 13:31:40.581616 systemd-networkd[1427]: Enumeration completed May 12 13:31:40.582095 systemd-networkd[1427]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 12 13:31:40.582104 systemd-networkd[1427]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 12 13:31:40.582436 systemd[1]: Reached target time-set.target - System Time Set. May 12 13:31:40.582559 systemd-networkd[1427]: eth0: Link UP May 12 13:31:40.582565 systemd-networkd[1427]: eth0: Gained carrier May 12 13:31:40.582580 systemd-networkd[1427]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 12 13:31:40.583361 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 12 13:31:40.584639 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 12 13:31:40.585659 systemd[1]: Reached target timers.target - Timer Units. May 12 13:31:40.587082 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 12 13:31:40.589354 systemd[1]: Starting docker.socket - Docker Socket for the API... May 12 13:31:40.593159 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 12 13:31:40.594708 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 12 13:31:40.596008 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 12 13:31:40.596921 systemd-networkd[1427]: eth0: DHCPv4 address 10.0.0.91/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 12 13:31:40.597393 systemd-timesyncd[1428]: Network configuration changed, trying to establish connection. May 12 13:31:40.599252 systemd-timesyncd[1428]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 12 13:31:40.599296 systemd-timesyncd[1428]: Initial clock synchronization to Mon 2025-05-12 13:31:40.603905 UTC. May 12 13:31:40.601457 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 12 13:31:40.602713 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 12 13:31:40.604574 systemd[1]: Started systemd-networkd.service - Network Configuration. May 12 13:31:40.606908 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 12 13:31:40.607951 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 12 13:31:40.610324 systemd[1]: Reached target network.target - Network. May 12 13:31:40.611074 systemd[1]: Reached target sockets.target - Socket Units. May 12 13:31:40.611795 systemd[1]: Reached target basic.target - Basic System. May 12 13:31:40.612625 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 12 13:31:40.612656 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 12 13:31:40.613994 systemd[1]: Starting containerd.service - containerd container runtime... May 12 13:31:40.616089 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 12 13:31:40.619762 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 12 13:31:40.623295 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 12 13:31:40.629411 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 12 13:31:40.630202 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 12 13:31:40.633826 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 12 13:31:40.637981 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 12 13:31:40.640060 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 12 13:31:40.643812 jq[1460]: false May 12 13:31:40.644154 systemd[1]: Starting systemd-logind.service - User Login Management... May 12 13:31:40.646484 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 12 13:31:40.656511 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 12 13:31:40.658461 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 12 13:31:40.658886 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 12 13:31:40.659436 systemd[1]: Starting update-engine.service - Update Engine... May 12 13:31:40.661987 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 12 13:31:40.670356 jq[1479]: true May 12 13:31:40.670968 extend-filesystems[1462]: Found loop3 May 12 13:31:40.671697 extend-filesystems[1462]: Found loop4 May 12 13:31:40.671697 extend-filesystems[1462]: Found loop5 May 12 13:31:40.671697 extend-filesystems[1462]: Found vda May 12 13:31:40.671697 extend-filesystems[1462]: Found vda1 May 12 13:31:40.671697 extend-filesystems[1462]: Found vda2 May 12 13:31:40.671697 extend-filesystems[1462]: Found vda3 May 12 13:31:40.671697 extend-filesystems[1462]: Found usr May 12 13:31:40.671697 extend-filesystems[1462]: Found vda4 May 12 13:31:40.671697 extend-filesystems[1462]: Found vda6 May 12 13:31:40.671697 extend-filesystems[1462]: Found vda7 May 12 13:31:40.671697 extend-filesystems[1462]: Found vda9 May 12 13:31:40.671697 extend-filesystems[1462]: Checking size of /dev/vda9 May 12 13:31:40.672783 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 12 13:31:40.678070 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 12 13:31:40.678815 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 12 13:31:40.679130 systemd[1]: motdgen.service: Deactivated successfully. May 12 13:31:40.680901 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 12 13:31:40.681914 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 12 13:31:40.682066 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 12 13:31:40.703982 jq[1482]: true May 12 13:31:40.720735 update_engine[1477]: I20250512 13:31:40.720435 1477 main.cc:92] Flatcar Update Engine starting May 12 13:31:40.730476 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 12 13:31:40.730004 dbus-daemon[1458]: [system] SELinux support is enabled May 12 13:31:40.725219 (ntainerd)[1491]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 12 13:31:40.730908 extend-filesystems[1462]: Resized partition /dev/vda9 May 12 13:31:40.725879 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 12 13:31:40.732794 extend-filesystems[1497]: resize2fs 1.47.2 (1-Jan-2025) May 12 13:31:40.742689 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 42 scanned by (udev-worker) (1421) May 12 13:31:40.731932 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 12 13:31:40.742759 update_engine[1477]: I20250512 13:31:40.741766 1477 update_check_scheduler.cc:74] Next update check in 3m48s May 12 13:31:40.738326 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 12 13:31:40.738347 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 12 13:31:40.743154 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 12 13:31:40.745959 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 12 13:31:40.745980 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 12 13:31:40.754246 systemd-logind[1467]: Watching system buttons on /dev/input/event0 (Power Button) May 12 13:31:40.756016 systemd-logind[1467]: New seat seat0. May 12 13:31:40.756934 systemd[1]: Started update-engine.service - Update Engine. May 12 13:31:40.760538 systemd[1]: Started systemd-logind.service - User Login Management. May 12 13:31:40.769565 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 12 13:31:40.770225 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 12 13:31:40.788140 extend-filesystems[1497]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 12 13:31:40.788140 extend-filesystems[1497]: old_desc_blocks = 1, new_desc_blocks = 1 May 12 13:31:40.788140 extend-filesystems[1497]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 12 13:31:40.786461 systemd[1]: extend-filesystems.service: Deactivated successfully. May 12 13:31:40.792017 extend-filesystems[1462]: Resized filesystem in /dev/vda9 May 12 13:31:40.786650 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 12 13:31:40.827338 bash[1518]: Updated "/home/core/.ssh/authorized_keys" May 12 13:31:40.830253 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 12 13:31:40.832291 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 12 13:31:40.839144 locksmithd[1505]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 12 13:31:40.850910 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 12 13:31:40.955229 containerd[1491]: time="2025-05-12T13:31:40Z" 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 12 13:31:40.955853 containerd[1491]: time="2025-05-12T13:31:40.955807640Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 12 13:31:40.964563 containerd[1491]: time="2025-05-12T13:31:40.964521320Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.84µs" May 12 13:31:40.964563 containerd[1491]: time="2025-05-12T13:31:40.964559240Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 12 13:31:40.964606 containerd[1491]: time="2025-05-12T13:31:40.964582240Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 12 13:31:40.964746 containerd[1491]: time="2025-05-12T13:31:40.964719160Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 12 13:31:40.964784 containerd[1491]: time="2025-05-12T13:31:40.964744440Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 12 13:31:40.964808 containerd[1491]: time="2025-05-12T13:31:40.964781200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 12 13:31:40.964876 containerd[1491]: time="2025-05-12T13:31:40.964839640Z" 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 12 13:31:40.965092 containerd[1491]: time="2025-05-12T13:31:40.965047040Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 12 13:31:40.965368 containerd[1491]: time="2025-05-12T13:31:40.965335680Z" 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 12 13:31:40.965397 containerd[1491]: time="2025-05-12T13:31:40.965365800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 12 13:31:40.965397 containerd[1491]: time="2025-05-12T13:31:40.965378800Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 12 13:31:40.965397 containerd[1491]: time="2025-05-12T13:31:40.965386680Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 12 13:31:40.965474 containerd[1491]: time="2025-05-12T13:31:40.965459440Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 12 13:31:40.965667 containerd[1491]: time="2025-05-12T13:31:40.965642800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 12 13:31:40.965691 containerd[1491]: time="2025-05-12T13:31:40.965679240Z" 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 12 13:31:40.965710 containerd[1491]: time="2025-05-12T13:31:40.965690320Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 12 13:31:40.966284 containerd[1491]: time="2025-05-12T13:31:40.966248000Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 12 13:31:40.966527 containerd[1491]: time="2025-05-12T13:31:40.966509960Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 12 13:31:40.966603 containerd[1491]: time="2025-05-12T13:31:40.966587720Z" level=info msg="metadata content store policy set" policy=shared May 12 13:31:40.969563 containerd[1491]: time="2025-05-12T13:31:40.969535040Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 12 13:31:40.969612 containerd[1491]: time="2025-05-12T13:31:40.969586960Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 12 13:31:40.969632 containerd[1491]: time="2025-05-12T13:31:40.969610760Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 12 13:31:40.969632 containerd[1491]: time="2025-05-12T13:31:40.969625920Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 12 13:31:40.969674 containerd[1491]: time="2025-05-12T13:31:40.969666160Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 12 13:31:40.969692 containerd[1491]: time="2025-05-12T13:31:40.969678840Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 12 13:31:40.969714 containerd[1491]: time="2025-05-12T13:31:40.969690840Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 12 13:31:40.969714 containerd[1491]: time="2025-05-12T13:31:40.969707760Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 12 13:31:40.969747 containerd[1491]: time="2025-05-12T13:31:40.969719280Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 12 13:31:40.969747 containerd[1491]: time="2025-05-12T13:31:40.969730480Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 12 13:31:40.969747 containerd[1491]: time="2025-05-12T13:31:40.969739480Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 12 13:31:40.969810 containerd[1491]: time="2025-05-12T13:31:40.969753040Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 12 13:31:40.969909 containerd[1491]: time="2025-05-12T13:31:40.969885280Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 12 13:31:40.969931 containerd[1491]: time="2025-05-12T13:31:40.969914920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 12 13:31:40.969955 containerd[1491]: time="2025-05-12T13:31:40.969932360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 12 13:31:40.969955 containerd[1491]: time="2025-05-12T13:31:40.969944240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 12 13:31:40.969990 containerd[1491]: time="2025-05-12T13:31:40.969954360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 12 13:31:40.969990 containerd[1491]: time="2025-05-12T13:31:40.969964960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 12 13:31:40.969990 containerd[1491]: time="2025-05-12T13:31:40.969975880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 12 13:31:40.969990 containerd[1491]: time="2025-05-12T13:31:40.969986560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 12 13:31:40.970058 containerd[1491]: time="2025-05-12T13:31:40.969997680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 12 13:31:40.970058 containerd[1491]: time="2025-05-12T13:31:40.970008440Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 12 13:31:40.970058 containerd[1491]: time="2025-05-12T13:31:40.970018400Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 12 13:31:40.972044 containerd[1491]: time="2025-05-12T13:31:40.972019760Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 12 13:31:40.972069 containerd[1491]: time="2025-05-12T13:31:40.972043640Z" level=info msg="Start snapshots syncer" May 12 13:31:40.972095 containerd[1491]: time="2025-05-12T13:31:40.972086880Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 12 13:31:40.972445 containerd[1491]: time="2025-05-12T13:31:40.972401560Z" 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 12 13:31:40.972530 containerd[1491]: time="2025-05-12T13:31:40.972459400Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 12 13:31:40.972551 containerd[1491]: time="2025-05-12T13:31:40.972538320Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 12 13:31:40.972715 containerd[1491]: time="2025-05-12T13:31:40.972691680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 12 13:31:40.972748 containerd[1491]: time="2025-05-12T13:31:40.972722840Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 12 13:31:40.972748 containerd[1491]: time="2025-05-12T13:31:40.972741040Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 12 13:31:40.972800 containerd[1491]: time="2025-05-12T13:31:40.972753840Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 12 13:31:40.972800 containerd[1491]: time="2025-05-12T13:31:40.972766680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 12 13:31:40.972800 containerd[1491]: time="2025-05-12T13:31:40.972789120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 12 13:31:40.972800 containerd[1491]: time="2025-05-12T13:31:40.972799920Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 12 13:31:40.972878 containerd[1491]: time="2025-05-12T13:31:40.972829200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 12 13:31:40.972878 containerd[1491]: time="2025-05-12T13:31:40.972840320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 12 13:31:40.972878 containerd[1491]: time="2025-05-12T13:31:40.972850400Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 12 13:31:40.972950 containerd[1491]: time="2025-05-12T13:31:40.972905880Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 12 13:31:40.972950 containerd[1491]: time="2025-05-12T13:31:40.972920600Z" 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 12 13:31:40.972950 containerd[1491]: time="2025-05-12T13:31:40.972928680Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 12 13:31:40.972950 containerd[1491]: time="2025-05-12T13:31:40.972937560Z" 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 12 13:31:40.972950 containerd[1491]: time="2025-05-12T13:31:40.972944800Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 12 13:31:40.973034 containerd[1491]: time="2025-05-12T13:31:40.972954920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 12 13:31:40.973034 containerd[1491]: time="2025-05-12T13:31:40.972966160Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 12 13:31:40.973067 containerd[1491]: time="2025-05-12T13:31:40.973044680Z" level=info msg="runtime interface created" May 12 13:31:40.973067 containerd[1491]: time="2025-05-12T13:31:40.973050160Z" level=info msg="created NRI interface" May 12 13:31:40.973067 containerd[1491]: time="2025-05-12T13:31:40.973057880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 12 13:31:40.973117 containerd[1491]: time="2025-05-12T13:31:40.973068280Z" level=info msg="Connect containerd service" May 12 13:31:40.973117 containerd[1491]: time="2025-05-12T13:31:40.973093720Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 12 13:31:40.973765 containerd[1491]: time="2025-05-12T13:31:40.973730680Z" 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 12 13:31:41.074738 containerd[1491]: time="2025-05-12T13:31:41.074677001Z" level=info msg="Start subscribing containerd event" May 12 13:31:41.074837 containerd[1491]: time="2025-05-12T13:31:41.074750452Z" level=info msg="Start recovering state" May 12 13:31:41.074860 containerd[1491]: time="2025-05-12T13:31:41.074845186Z" level=info msg="Start event monitor" May 12 13:31:41.074889 containerd[1491]: time="2025-05-12T13:31:41.074873670Z" level=info msg="Start cni network conf syncer for default" May 12 13:31:41.074889 containerd[1491]: time="2025-05-12T13:31:41.074884592Z" level=info msg="Start streaming server" May 12 13:31:41.074944 containerd[1491]: time="2025-05-12T13:31:41.074893273Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 12 13:31:41.074944 containerd[1491]: time="2025-05-12T13:31:41.074900354Z" level=info msg="runtime interface starting up..." May 12 13:31:41.074944 containerd[1491]: time="2025-05-12T13:31:41.074906555Z" level=info msg="starting plugins..." May 12 13:31:41.074944 containerd[1491]: time="2025-05-12T13:31:41.074919637Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 12 13:31:41.075062 containerd[1491]: time="2025-05-12T13:31:41.075032933Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 12 13:31:41.075091 containerd[1491]: time="2025-05-12T13:31:41.075084741Z" level=info msg=serving... address=/run/containerd/containerd.sock May 12 13:31:41.075187 containerd[1491]: time="2025-05-12T13:31:41.075171314Z" level=info msg="containerd successfully booted in 0.120662s" May 12 13:31:41.077967 systemd[1]: Started containerd.service - containerd container runtime. May 12 13:31:41.239952 sshd_keygen[1478]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 12 13:31:41.258276 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 12 13:31:41.260670 systemd[1]: Starting issuegen.service - Generate /run/issue... May 12 13:31:41.280828 systemd[1]: issuegen.service: Deactivated successfully. May 12 13:31:41.281032 systemd[1]: Finished issuegen.service - Generate /run/issue. May 12 13:31:41.283169 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 12 13:31:41.306716 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 12 13:31:41.308966 systemd[1]: Started getty@tty1.service - Getty on tty1. May 12 13:31:41.310656 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 12 13:31:41.311699 systemd[1]: Reached target getty.target - Login Prompts. May 12 13:31:42.429106 systemd-networkd[1427]: eth0: Gained IPv6LL May 12 13:31:42.431388 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 12 13:31:42.432801 systemd[1]: Reached target network-online.target - Network is Online. May 12 13:31:42.434943 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 12 13:31:42.437094 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 12 13:31:42.438811 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 12 13:31:42.459886 systemd[1]: coreos-metadata.service: Deactivated successfully. May 12 13:31:42.460912 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 12 13:31:42.462186 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 12 13:31:42.464190 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 12 13:31:42.922470 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 12 13:31:42.923731 systemd[1]: Reached target multi-user.target - Multi-User System. May 12 13:31:42.925794 (kubelet)[1590]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 12 13:31:42.928963 systemd[1]: Startup finished in 2.113s (kernel) + 4.341s (initrd) + 3.931s (userspace) = 10.387s. May 12 13:31:43.344912 kubelet[1590]: E0512 13:31:43.344795 1590 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 12 13:31:43.347495 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 12 13:31:43.347633 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 12 13:31:43.347938 systemd[1]: kubelet.service: Consumed 776ms CPU time, 233.9M memory peak. May 12 13:31:47.030402 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 12 13:31:47.031502 systemd[1]: Started sshd@0-10.0.0.91:22-10.0.0.1:47868.service - OpenSSH per-connection server daemon (10.0.0.1:47868). May 12 13:31:47.098640 sshd[1604]: Accepted publickey for core from 10.0.0.1 port 47868 ssh2: RSA SHA256:jEPoW5jmVqQGUqKP3XswdpHQkuwhsPJWJAB8YbEjhZ8 May 12 13:31:47.100108 sshd-session[1604]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 12 13:31:47.107480 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 12 13:31:47.108348 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 12 13:31:47.113443 systemd-logind[1467]: New session 1 of user core. May 12 13:31:47.132463 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 12 13:31:47.134685 systemd[1]: Starting user@500.service - User Manager for UID 500... May 12 13:31:47.155766 (systemd)[1608]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 12 13:31:47.157627 systemd-logind[1467]: New session c1 of user core. May 12 13:31:47.268822 systemd[1608]: Queued start job for default target default.target. May 12 13:31:47.277741 systemd[1608]: Created slice app.slice - User Application Slice. May 12 13:31:47.277770 systemd[1608]: Reached target paths.target - Paths. May 12 13:31:47.277803 systemd[1608]: Reached target timers.target - Timers. May 12 13:31:47.278969 systemd[1608]: Starting dbus.socket - D-Bus User Message Bus Socket... May 12 13:31:47.287051 systemd[1608]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 12 13:31:47.287115 systemd[1608]: Reached target sockets.target - Sockets. May 12 13:31:47.287152 systemd[1608]: Reached target basic.target - Basic System. May 12 13:31:47.287183 systemd[1608]: Reached target default.target - Main User Target. May 12 13:31:47.287207 systemd[1608]: Startup finished in 124ms. May 12 13:31:47.287315 systemd[1]: Started user@500.service - User Manager for UID 500. May 12 13:31:47.288581 systemd[1]: Started session-1.scope - Session 1 of User core. May 12 13:31:47.351825 systemd[1]: Started sshd@1-10.0.0.91:22-10.0.0.1:47878.service - OpenSSH per-connection server daemon (10.0.0.1:47878). May 12 13:31:47.390181 sshd[1620]: Accepted publickey for core from 10.0.0.1 port 47878 ssh2: RSA SHA256:jEPoW5jmVqQGUqKP3XswdpHQkuwhsPJWJAB8YbEjhZ8 May 12 13:31:47.391259 sshd-session[1620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 12 13:31:47.395347 systemd-logind[1467]: New session 2 of user core. May 12 13:31:47.410050 systemd[1]: Started session-2.scope - Session 2 of User core. May 12 13:31:47.460876 sshd[1622]: Connection closed by 10.0.0.1 port 47878 May 12 13:31:47.461286 sshd-session[1620]: pam_unix(sshd:session): session closed for user core May 12 13:31:47.471463 systemd[1]: sshd@1-10.0.0.91:22-10.0.0.1:47878.service: Deactivated successfully. May 12 13:31:47.472909 systemd[1]: session-2.scope: Deactivated successfully. May 12 13:31:47.474008 systemd-logind[1467]: Session 2 logged out. Waiting for processes to exit. May 12 13:31:47.475045 systemd[1]: Started sshd@2-10.0.0.91:22-10.0.0.1:47892.service - OpenSSH per-connection server daemon (10.0.0.1:47892). May 12 13:31:47.475858 systemd-logind[1467]: Removed session 2. May 12 13:31:47.513214 sshd[1627]: Accepted publickey for core from 10.0.0.1 port 47892 ssh2: RSA SHA256:jEPoW5jmVqQGUqKP3XswdpHQkuwhsPJWJAB8YbEjhZ8 May 12 13:31:47.514262 sshd-session[1627]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 12 13:31:47.518468 systemd-logind[1467]: New session 3 of user core. May 12 13:31:47.528038 systemd[1]: Started session-3.scope - Session 3 of User core. May 12 13:31:47.575724 sshd[1630]: Connection closed by 10.0.0.1 port 47892 May 12 13:31:47.576040 sshd-session[1627]: pam_unix(sshd:session): session closed for user core May 12 13:31:47.584885 systemd[1]: sshd@2-10.0.0.91:22-10.0.0.1:47892.service: Deactivated successfully. May 12 13:31:47.586132 systemd[1]: session-3.scope: Deactivated successfully. May 12 13:31:47.586732 systemd-logind[1467]: Session 3 logged out. Waiting for processes to exit. May 12 13:31:47.588265 systemd[1]: Started sshd@3-10.0.0.91:22-10.0.0.1:47908.service - OpenSSH per-connection server daemon (10.0.0.1:47908). May 12 13:31:47.590100 systemd-logind[1467]: Removed session 3. May 12 13:31:47.634517 sshd[1635]: Accepted publickey for core from 10.0.0.1 port 47908 ssh2: RSA SHA256:jEPoW5jmVqQGUqKP3XswdpHQkuwhsPJWJAB8YbEjhZ8 May 12 13:31:47.635525 sshd-session[1635]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 12 13:31:47.639223 systemd-logind[1467]: New session 4 of user core. May 12 13:31:47.649002 systemd[1]: Started session-4.scope - Session 4 of User core. May 12 13:31:47.699587 sshd[1638]: Connection closed by 10.0.0.1 port 47908 May 12 13:31:47.699485 sshd-session[1635]: pam_unix(sshd:session): session closed for user core May 12 13:31:47.710011 systemd[1]: sshd@3-10.0.0.91:22-10.0.0.1:47908.service: Deactivated successfully. May 12 13:31:47.712124 systemd[1]: session-4.scope: Deactivated successfully. May 12 13:31:47.713552 systemd-logind[1467]: Session 4 logged out. Waiting for processes to exit. May 12 13:31:47.714214 systemd[1]: Started sshd@4-10.0.0.91:22-10.0.0.1:47914.service - OpenSSH per-connection server daemon (10.0.0.1:47914). May 12 13:31:47.714987 systemd-logind[1467]: Removed session 4. May 12 13:31:47.762423 sshd[1643]: Accepted publickey for core from 10.0.0.1 port 47914 ssh2: RSA SHA256:jEPoW5jmVqQGUqKP3XswdpHQkuwhsPJWJAB8YbEjhZ8 May 12 13:31:47.763413 sshd-session[1643]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 12 13:31:47.766915 systemd-logind[1467]: New session 5 of user core. May 12 13:31:47.776996 systemd[1]: Started session-5.scope - Session 5 of User core. May 12 13:31:47.835262 sudo[1647]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 12 13:31:47.837770 sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 12 13:31:47.847730 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 12 13:31:47.872401 systemd[1]: coreos-metadata.service: Deactivated successfully. May 12 13:31:47.872620 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 12 13:31:48.273419 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 12 13:31:48.273577 systemd[1]: kubelet.service: Consumed 776ms CPU time, 233.9M memory peak. May 12 13:31:48.275520 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 12 13:31:48.297952 systemd[1]: Reload requested from client PID 1687 ('systemctl') (unit session-5.scope)... May 12 13:31:48.297975 systemd[1]: Reloading... May 12 13:31:48.371912 zram_generator::config[1729]: No configuration found. May 12 13:31:48.530539 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 12 13:31:48.615360 systemd[1]: Reloading finished in 317 ms. May 12 13:31:48.660463 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM May 12 13:31:48.660522 systemd[1]: kubelet.service: Failed with result 'signal'. May 12 13:31:48.660772 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 12 13:31:48.660812 systemd[1]: kubelet.service: Consumed 81ms CPU time, 82.4M memory peak. May 12 13:31:48.663071 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 12 13:31:48.761230 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 12 13:31:48.764409 (kubelet)[1774]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 12 13:31:48.797220 kubelet[1774]: 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 12 13:31:48.797220 kubelet[1774]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. May 12 13:31:48.797220 kubelet[1774]: 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 12 13:31:48.797481 kubelet[1774]: I0512 13:31:48.797329 1774 server.go:206] "--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 12 13:31:49.394909 kubelet[1774]: I0512 13:31:49.394246 1774 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" May 12 13:31:49.394909 kubelet[1774]: I0512 13:31:49.394280 1774 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 12 13:31:49.394909 kubelet[1774]: I0512 13:31:49.394526 1774 server.go:929] "Client rotation is on, will bootstrap in background" May 12 13:31:49.452409 kubelet[1774]: I0512 13:31:49.452372 1774 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 12 13:31:49.466004 kubelet[1774]: I0512 13:31:49.465975 1774 server.go:1426] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" May 12 13:31:49.471305 kubelet[1774]: I0512 13:31:49.471268 1774 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 12 13:31:49.472143 kubelet[1774]: I0512 13:31:49.472120 1774 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" May 12 13:31:49.472285 kubelet[1774]: I0512 13:31:49.472252 1774 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 12 13:31:49.472459 kubelet[1774]: I0512 13:31:49.472287 1774 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.91","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 12 13:31:49.472606 kubelet[1774]: I0512 13:31:49.472590 1774 topology_manager.go:138] "Creating topology manager with none policy" May 12 13:31:49.472606 kubelet[1774]: I0512 13:31:49.472602 1774 container_manager_linux.go:300] "Creating device plugin manager" May 12 13:31:49.472791 kubelet[1774]: I0512 13:31:49.472776 1774 state_mem.go:36] "Initialized new in-memory state store" May 12 13:31:49.473824 kubelet[1774]: I0512 13:31:49.473797 1774 kubelet.go:408] "Attempting to sync node with API server" May 12 13:31:49.473824 kubelet[1774]: I0512 13:31:49.473824 1774 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" May 12 13:31:49.473932 kubelet[1774]: I0512 13:31:49.473920 1774 kubelet.go:314] "Adding apiserver pod source" May 12 13:31:49.473932 kubelet[1774]: I0512 13:31:49.473931 1774 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 12 13:31:49.476302 kubelet[1774]: E0512 13:31:49.474264 1774 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:49.476302 kubelet[1774]: E0512 13:31:49.474305 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:49.480346 kubelet[1774]: I0512 13:31:49.480315 1774 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" May 12 13:31:49.482082 kubelet[1774]: I0512 13:31:49.481974 1774 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 12 13:31:49.482232 kubelet[1774]: W0512 13:31:49.482219 1774 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 12 13:31:49.482916 kubelet[1774]: I0512 13:31:49.482893 1774 server.go:1269] "Started kubelet" May 12 13:31:49.482961 kubelet[1774]: W0512 13:31:49.482933 1774 reflector.go:561] 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 12 13:31:49.482983 kubelet[1774]: E0512 13:31:49.482965 1774 reflector.go:158] "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 12 13:31:49.485057 kubelet[1774]: I0512 13:31:49.483102 1774 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 May 12 13:31:49.485057 kubelet[1774]: W0512 13:31:49.483159 1774 reflector.go:561] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.91" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope May 12 13:31:49.485057 kubelet[1774]: E0512 13:31:49.483224 1774 reflector.go:158] "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.91\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" May 12 13:31:49.485057 kubelet[1774]: I0512 13:31:49.483477 1774 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 12 13:31:49.485057 kubelet[1774]: I0512 13:31:49.483783 1774 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 12 13:31:49.485057 kubelet[1774]: I0512 13:31:49.484299 1774 server.go:460] "Adding debug handlers to kubelet server" May 12 13:31:49.485708 kubelet[1774]: I0512 13:31:49.485261 1774 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 12 13:31:49.485708 kubelet[1774]: I0512 13:31:49.485312 1774 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 12 13:31:49.485708 kubelet[1774]: I0512 13:31:49.485598 1774 volume_manager.go:289] "Starting Kubelet Volume Manager" May 12 13:31:49.485708 kubelet[1774]: I0512 13:31:49.485689 1774 desired_state_of_world_populator.go:146] "Desired state populator starts to run" May 12 13:31:49.485813 kubelet[1774]: I0512 13:31:49.485761 1774 reconciler.go:26] "Reconciler: start to sync state" May 12 13:31:49.486701 kubelet[1774]: E0512 13:31:49.486668 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:49.487539 kubelet[1774]: I0512 13:31:49.487509 1774 factory.go:221] Registration of the systemd container factory successfully May 12 13:31:49.487646 kubelet[1774]: I0512 13:31:49.487605 1774 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 12 13:31:49.492234 kubelet[1774]: E0512 13:31:49.492207 1774 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 12 13:31:49.492789 kubelet[1774]: I0512 13:31:49.492765 1774 factory.go:221] Registration of the containerd container factory successfully May 12 13:31:49.502262 kubelet[1774]: E0512 13:31:49.502215 1774 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.91\" not found" node="10.0.0.91" May 12 13:31:49.504881 kubelet[1774]: I0512 13:31:49.504723 1774 cpu_manager.go:214] "Starting CPU manager" policy="none" May 12 13:31:49.504881 kubelet[1774]: I0512 13:31:49.504742 1774 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" May 12 13:31:49.504881 kubelet[1774]: I0512 13:31:49.504761 1774 state_mem.go:36] "Initialized new in-memory state store" May 12 13:31:49.566748 kubelet[1774]: I0512 13:31:49.566704 1774 policy_none.go:49] "None policy: Start" May 12 13:31:49.567468 kubelet[1774]: I0512 13:31:49.567421 1774 memory_manager.go:170] "Starting memorymanager" policy="None" May 12 13:31:49.567468 kubelet[1774]: I0512 13:31:49.567450 1774 state_mem.go:35] "Initializing new in-memory state store" May 12 13:31:49.575389 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 12 13:31:49.586514 kubelet[1774]: I0512 13:31:49.586388 1774 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 12 13:31:49.586812 kubelet[1774]: E0512 13:31:49.586789 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:49.587742 kubelet[1774]: I0512 13:31:49.587723 1774 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 12 13:31:49.587817 kubelet[1774]: I0512 13:31:49.587807 1774 status_manager.go:217] "Starting to sync pod status with apiserver" May 12 13:31:49.587892 kubelet[1774]: I0512 13:31:49.587882 1774 kubelet.go:2321] "Starting kubelet main sync loop" May 12 13:31:49.587990 kubelet[1774]: E0512 13:31:49.587965 1774 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" May 12 13:31:49.591793 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 12 13:31:49.594827 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 12 13:31:49.612685 kubelet[1774]: I0512 13:31:49.612662 1774 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 12 13:31:49.613114 kubelet[1774]: I0512 13:31:49.612971 1774 eviction_manager.go:189] "Eviction manager: starting control loop" May 12 13:31:49.613114 kubelet[1774]: I0512 13:31:49.612988 1774 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 12 13:31:49.613181 kubelet[1774]: I0512 13:31:49.613159 1774 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 12 13:31:49.614756 kubelet[1774]: E0512 13:31:49.614702 1774 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.91\" not found" May 12 13:31:49.714947 kubelet[1774]: I0512 13:31:49.714313 1774 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.91" May 12 13:31:49.718273 kubelet[1774]: I0512 13:31:49.718232 1774 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.91" May 12 13:31:49.718273 kubelet[1774]: E0512 13:31:49.718263 1774 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"10.0.0.91\": node \"10.0.0.91\" not found" May 12 13:31:49.727759 kubelet[1774]: E0512 13:31:49.727719 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:49.788264 sudo[1647]: pam_unix(sudo:session): session closed for user root May 12 13:31:49.789505 sshd[1646]: Connection closed by 10.0.0.1 port 47914 May 12 13:31:49.789859 sshd-session[1643]: pam_unix(sshd:session): session closed for user core May 12 13:31:49.792554 systemd[1]: sshd@4-10.0.0.91:22-10.0.0.1:47914.service: Deactivated successfully. May 12 13:31:49.794763 systemd[1]: session-5.scope: Deactivated successfully. May 12 13:31:49.795038 systemd[1]: session-5.scope: Consumed 405ms CPU time, 74.4M memory peak. May 12 13:31:49.796910 systemd-logind[1467]: Session 5 logged out. Waiting for processes to exit. May 12 13:31:49.797974 systemd-logind[1467]: Removed session 5. May 12 13:31:49.828844 kubelet[1774]: E0512 13:31:49.828803 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:49.929533 kubelet[1774]: E0512 13:31:49.929481 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:50.030551 kubelet[1774]: E0512 13:31:50.030441 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:50.131045 kubelet[1774]: E0512 13:31:50.131000 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:50.231556 kubelet[1774]: E0512 13:31:50.231514 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:50.332120 kubelet[1774]: E0512 13:31:50.332033 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:50.396584 kubelet[1774]: I0512 13:31:50.396550 1774 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 12 13:31:50.396775 kubelet[1774]: W0512 13:31:50.396741 1774 reflector.go:484] 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 12 13:31:50.396832 kubelet[1774]: W0512 13:31:50.396805 1774 reflector.go:484] 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 12 13:31:50.432745 kubelet[1774]: E0512 13:31:50.432700 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:50.475079 kubelet[1774]: E0512 13:31:50.475039 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:50.532977 kubelet[1774]: E0512 13:31:50.532918 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:50.633620 kubelet[1774]: E0512 13:31:50.633519 1774 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.91\" not found" May 12 13:31:50.734368 kubelet[1774]: I0512 13:31:50.734335 1774 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 12 13:31:50.734614 containerd[1491]: time="2025-05-12T13:31:50.734570060Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 12 13:31:50.734886 kubelet[1774]: I0512 13:31:50.734730 1774 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 12 13:31:51.475662 kubelet[1774]: I0512 13:31:51.475634 1774 apiserver.go:52] "Watching apiserver" May 12 13:31:51.475662 kubelet[1774]: E0512 13:31:51.475657 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:51.487926 kubelet[1774]: I0512 13:31:51.487897 1774 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" May 12 13:31:51.488318 systemd[1]: Created slice kubepods-besteffort-podb98ac9aa_2610_4be1_9352_6be3c56207a9.slice - libcontainer container kubepods-besteffort-podb98ac9aa_2610_4be1_9352_6be3c56207a9.slice. May 12 13:31:51.495178 kubelet[1774]: I0512 13:31:51.495148 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b98ac9aa-2610-4be1-9352-6be3c56207a9-kube-proxy\") pod \"kube-proxy-5qqmg\" (UID: \"b98ac9aa-2610-4be1-9352-6be3c56207a9\") " pod="kube-system/kube-proxy-5qqmg" May 12 13:31:51.495252 kubelet[1774]: I0512 13:31:51.495183 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/8c996e25-8c12-431f-bdb4-e5ed9d23cbc0-run\") pod \"kube-flannel-ds-npsd8\" (UID: \"8c996e25-8c12-431f-bdb4-e5ed9d23cbc0\") " pod="kube-flannel/kube-flannel-ds-npsd8" May 12 13:31:51.495252 kubelet[1774]: I0512 13:31:51.495203 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/8c996e25-8c12-431f-bdb4-e5ed9d23cbc0-cni-plugin\") pod \"kube-flannel-ds-npsd8\" (UID: \"8c996e25-8c12-431f-bdb4-e5ed9d23cbc0\") " pod="kube-flannel/kube-flannel-ds-npsd8" May 12 13:31:51.495252 kubelet[1774]: I0512 13:31:51.495217 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/8c996e25-8c12-431f-bdb4-e5ed9d23cbc0-xtables-lock\") pod \"kube-flannel-ds-npsd8\" (UID: \"8c996e25-8c12-431f-bdb4-e5ed9d23cbc0\") " pod="kube-flannel/kube-flannel-ds-npsd8" May 12 13:31:51.495252 kubelet[1774]: I0512 13:31:51.495231 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b98ac9aa-2610-4be1-9352-6be3c56207a9-xtables-lock\") pod \"kube-proxy-5qqmg\" (UID: \"b98ac9aa-2610-4be1-9352-6be3c56207a9\") " pod="kube-system/kube-proxy-5qqmg" May 12 13:31:51.495252 kubelet[1774]: I0512 13:31:51.495246 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b98ac9aa-2610-4be1-9352-6be3c56207a9-lib-modules\") pod \"kube-proxy-5qqmg\" (UID: \"b98ac9aa-2610-4be1-9352-6be3c56207a9\") " pod="kube-system/kube-proxy-5qqmg" May 12 13:31:51.495373 kubelet[1774]: I0512 13:31:51.495270 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-c4nph\" (UniqueName: \"kubernetes.io/projected/b98ac9aa-2610-4be1-9352-6be3c56207a9-kube-api-access-c4nph\") pod \"kube-proxy-5qqmg\" (UID: \"b98ac9aa-2610-4be1-9352-6be3c56207a9\") " pod="kube-system/kube-proxy-5qqmg" May 12 13:31:51.495373 kubelet[1774]: I0512 13:31:51.495287 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/8c996e25-8c12-431f-bdb4-e5ed9d23cbc0-cni\") pod \"kube-flannel-ds-npsd8\" (UID: \"8c996e25-8c12-431f-bdb4-e5ed9d23cbc0\") " pod="kube-flannel/kube-flannel-ds-npsd8" May 12 13:31:51.495373 kubelet[1774]: I0512 13:31:51.495303 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/8c996e25-8c12-431f-bdb4-e5ed9d23cbc0-flannel-cfg\") pod \"kube-flannel-ds-npsd8\" (UID: \"8c996e25-8c12-431f-bdb4-e5ed9d23cbc0\") " pod="kube-flannel/kube-flannel-ds-npsd8" May 12 13:31:51.495373 kubelet[1774]: I0512 13:31:51.495317 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bcg24\" (UniqueName: \"kubernetes.io/projected/8c996e25-8c12-431f-bdb4-e5ed9d23cbc0-kube-api-access-bcg24\") pod \"kube-flannel-ds-npsd8\" (UID: \"8c996e25-8c12-431f-bdb4-e5ed9d23cbc0\") " pod="kube-flannel/kube-flannel-ds-npsd8" May 12 13:31:51.500840 systemd[1]: Created slice kubepods-burstable-pod8c996e25_8c12_431f_bdb4_e5ed9d23cbc0.slice - libcontainer container kubepods-burstable-pod8c996e25_8c12_431f_bdb4_e5ed9d23cbc0.slice. May 12 13:31:51.797269 containerd[1491]: time="2025-05-12T13:31:51.796802238Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5qqmg,Uid:b98ac9aa-2610-4be1-9352-6be3c56207a9,Namespace:kube-system,Attempt:0,}" May 12 13:31:51.810307 containerd[1491]: time="2025-05-12T13:31:51.810263883Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-npsd8,Uid:8c996e25-8c12-431f-bdb4-e5ed9d23cbc0,Namespace:kube-flannel,Attempt:0,}" May 12 13:31:52.357567 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2006028791.mount: Deactivated successfully. May 12 13:31:52.362478 containerd[1491]: time="2025-05-12T13:31:52.362430380Z" 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 12 13:31:52.363621 containerd[1491]: time="2025-05-12T13:31:52.363583020Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" May 12 13:31:52.364669 containerd[1491]: time="2025-05-12T13:31:52.364604046Z" 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 12 13:31:52.365729 containerd[1491]: time="2025-05-12T13:31:52.365702560Z" 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 12 13:31:52.367033 containerd[1491]: time="2025-05-12T13:31:52.367006496Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" May 12 13:31:52.368457 containerd[1491]: time="2025-05-12T13:31:52.368427923Z" 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 12 13:31:52.369133 containerd[1491]: time="2025-05-12T13:31:52.369112795Z" 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 565.306085ms" May 12 13:31:52.369889 containerd[1491]: time="2025-05-12T13:31:52.369840350Z" 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 556.040487ms" May 12 13:31:52.384234 containerd[1491]: time="2025-05-12T13:31:52.384186722Z" level=info msg="connecting to shim 7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531" address="unix:///run/containerd/s/8f16ac70af382300110897625de9dd93a2de561205c732991236a198292237c9" namespace=k8s.io protocol=ttrpc version=3 May 12 13:31:52.384784 containerd[1491]: time="2025-05-12T13:31:52.384194763Z" level=info msg="connecting to shim 317ae27c3b31a68fa9d6c8213b21ab179cf9c0553b3cb97e5684282affa0ac3c" address="unix:///run/containerd/s/747b7a6b94cff8ca43fdabb510db5ed19038e1e9f6dc5ea5c83f2d7926f4a7f5" namespace=k8s.io protocol=ttrpc version=3 May 12 13:31:52.406094 systemd[1]: Started cri-containerd-317ae27c3b31a68fa9d6c8213b21ab179cf9c0553b3cb97e5684282affa0ac3c.scope - libcontainer container 317ae27c3b31a68fa9d6c8213b21ab179cf9c0553b3cb97e5684282affa0ac3c. May 12 13:31:52.407585 systemd[1]: Started cri-containerd-7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531.scope - libcontainer container 7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531. May 12 13:31:52.431357 containerd[1491]: time="2025-05-12T13:31:52.431303660Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5qqmg,Uid:b98ac9aa-2610-4be1-9352-6be3c56207a9,Namespace:kube-system,Attempt:0,} returns sandbox id \"317ae27c3b31a68fa9d6c8213b21ab179cf9c0553b3cb97e5684282affa0ac3c\"" May 12 13:31:52.433510 containerd[1491]: time="2025-05-12T13:31:52.433422041Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\"" May 12 13:31:52.438603 containerd[1491]: time="2025-05-12T13:31:52.438574696Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-npsd8,Uid:8c996e25-8c12-431f-bdb4-e5ed9d23cbc0,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531\"" May 12 13:31:52.476323 kubelet[1774]: E0512 13:31:52.476293 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:53.267920 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1877609014.mount: Deactivated successfully. May 12 13:31:53.476694 kubelet[1774]: E0512 13:31:53.476586 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:53.483731 containerd[1491]: time="2025-05-12T13:31:53.483691586Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:31:53.484477 containerd[1491]: time="2025-05-12T13:31:53.484090906Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.8: active requests=0, bytes read=26871919" May 12 13:31:53.484823 containerd[1491]: time="2025-05-12T13:31:53.484783416Z" level=info msg="ImageCreate event name:\"sha256:c5361ece77e80334cd5fb082c0b678cb3244f5834ecacea1719ae6b38b465581\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:31:53.487019 containerd[1491]: time="2025-05-12T13:31:53.486983998Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:31:53.487767 containerd[1491]: time="2025-05-12T13:31:53.487733953Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.8\" with image id \"sha256:c5361ece77e80334cd5fb082c0b678cb3244f5834ecacea1719ae6b38b465581\", repo tag \"registry.k8s.io/kube-proxy:v1.31.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\", size \"26870936\" in 1.054275748s" May 12 13:31:53.487802 containerd[1491]: time="2025-05-12T13:31:53.487768437Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\" returns image reference \"sha256:c5361ece77e80334cd5fb082c0b678cb3244f5834ecacea1719ae6b38b465581\"" May 12 13:31:53.488839 containerd[1491]: time="2025-05-12T13:31:53.488653326Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 12 13:31:53.490189 containerd[1491]: time="2025-05-12T13:31:53.490141396Z" level=info msg="CreateContainer within sandbox \"317ae27c3b31a68fa9d6c8213b21ab179cf9c0553b3cb97e5684282affa0ac3c\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 12 13:31:53.498023 containerd[1491]: time="2025-05-12T13:31:53.496707777Z" level=info msg="Container f66c8e4abd30bb3fc415386670ebb4310db6c4dfa6e632c3ecd4be57b048c159: CDI devices from CRI Config.CDIDevices: []" May 12 13:31:53.503252 containerd[1491]: time="2025-05-12T13:31:53.503129664Z" level=info msg="CreateContainer within sandbox \"317ae27c3b31a68fa9d6c8213b21ab179cf9c0553b3cb97e5684282affa0ac3c\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"f66c8e4abd30bb3fc415386670ebb4310db6c4dfa6e632c3ecd4be57b048c159\"" May 12 13:31:53.503811 containerd[1491]: time="2025-05-12T13:31:53.503772609Z" level=info msg="StartContainer for \"f66c8e4abd30bb3fc415386670ebb4310db6c4dfa6e632c3ecd4be57b048c159\"" May 12 13:31:53.505132 containerd[1491]: time="2025-05-12T13:31:53.505105823Z" level=info msg="connecting to shim f66c8e4abd30bb3fc415386670ebb4310db6c4dfa6e632c3ecd4be57b048c159" address="unix:///run/containerd/s/747b7a6b94cff8ca43fdabb510db5ed19038e1e9f6dc5ea5c83f2d7926f4a7f5" protocol=ttrpc version=3 May 12 13:31:53.526051 systemd[1]: Started cri-containerd-f66c8e4abd30bb3fc415386670ebb4310db6c4dfa6e632c3ecd4be57b048c159.scope - libcontainer container f66c8e4abd30bb3fc415386670ebb4310db6c4dfa6e632c3ecd4be57b048c159. May 12 13:31:53.560590 containerd[1491]: time="2025-05-12T13:31:53.560410713Z" level=info msg="StartContainer for \"f66c8e4abd30bb3fc415386670ebb4310db6c4dfa6e632c3ecd4be57b048c159\" returns successfully" May 12 13:31:54.477250 kubelet[1774]: E0512 13:31:54.477197 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:54.587510 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1615991798.mount: Deactivated successfully. May 12 13:31:54.611176 containerd[1491]: time="2025-05-12T13:31:54.611126099Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:31:54.611787 containerd[1491]: time="2025-05-12T13:31:54.611738199Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" May 12 13:31:54.612271 containerd[1491]: time="2025-05-12T13:31:54.612247849Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:31:54.614411 containerd[1491]: time="2025-05-12T13:31:54.614356135Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:31:54.614794 containerd[1491]: time="2025-05-12T13:31:54.614753453Z" 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.126068244s" May 12 13:31:54.614794 containerd[1491]: time="2025-05-12T13:31:54.614786937Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" May 12 13:31:54.616314 containerd[1491]: time="2025-05-12T13:31:54.616281482Z" level=info msg="CreateContainer within sandbox \"7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 12 13:31:54.621305 containerd[1491]: time="2025-05-12T13:31:54.621274410Z" level=info msg="Container b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51: CDI devices from CRI Config.CDIDevices: []" May 12 13:31:54.625920 containerd[1491]: time="2025-05-12T13:31:54.625846696Z" level=info msg="CreateContainer within sandbox \"7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51\"" May 12 13:31:54.626296 containerd[1491]: time="2025-05-12T13:31:54.626253615Z" level=info msg="StartContainer for \"b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51\"" May 12 13:31:54.627145 containerd[1491]: time="2025-05-12T13:31:54.627117020Z" level=info msg="connecting to shim b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51" address="unix:///run/containerd/s/8f16ac70af382300110897625de9dd93a2de561205c732991236a198292237c9" protocol=ttrpc version=3 May 12 13:31:54.649043 systemd[1]: Started cri-containerd-b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51.scope - libcontainer container b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51. May 12 13:31:54.675548 containerd[1491]: time="2025-05-12T13:31:54.675515622Z" level=info msg="StartContainer for \"b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51\" returns successfully" May 12 13:31:54.680181 systemd[1]: cri-containerd-b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51.scope: Deactivated successfully. May 12 13:31:54.681627 containerd[1491]: time="2025-05-12T13:31:54.681352432Z" level=info msg="received exit event container_id:\"b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51\" id:\"b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51\" pid:2104 exited_at:{seconds:1747056714 nanos:681066724}" May 12 13:31:54.681947 containerd[1491]: time="2025-05-12T13:31:54.681917527Z" level=info msg="TaskExit event in podsandbox handler container_id:\"b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51\" id:\"b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51\" pid:2104 exited_at:{seconds:1747056714 nanos:681066724}" May 12 13:31:54.696320 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-b64a5a9bbc72774df4426b0d2624b77e56ed911f176891a92fe3ee241f48ef51-rootfs.mount: Deactivated successfully. May 12 13:31:55.478015 kubelet[1774]: E0512 13:31:55.477966 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:55.602636 containerd[1491]: time="2025-05-12T13:31:55.602597645Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 12 13:31:55.612757 kubelet[1774]: I0512 13:31:55.612447 1774 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-5qqmg" podStartSLOduration=5.556707918 podStartE2EDuration="6.612431694s" podCreationTimestamp="2025-05-12 13:31:49 +0000 UTC" firstStartedPulling="2025-05-12 13:31:52.432815218 +0000 UTC m=+3.665747280" lastFinishedPulling="2025-05-12 13:31:53.488538994 +0000 UTC m=+4.721471056" observedRunningTime="2025-05-12 13:31:53.606966162 +0000 UTC m=+4.839898224" watchObservedRunningTime="2025-05-12 13:31:55.612431694 +0000 UTC m=+6.845363756" May 12 13:31:56.478895 kubelet[1774]: E0512 13:31:56.478826 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:56.668775 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1100638220.mount: Deactivated successfully. May 12 13:31:57.108337 containerd[1491]: time="2025-05-12T13:31:57.108237057Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:31:57.109144 containerd[1491]: time="2025-05-12T13:31:57.109122016Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" May 12 13:31:57.110089 containerd[1491]: time="2025-05-12T13:31:57.110065620Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:31:57.113355 containerd[1491]: time="2025-05-12T13:31:57.113323269Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:31:57.114262 containerd[1491]: time="2025-05-12T13:31:57.114224389Z" 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.5115841s" May 12 13:31:57.114321 containerd[1491]: time="2025-05-12T13:31:57.114264072Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" May 12 13:31:57.116588 containerd[1491]: time="2025-05-12T13:31:57.116557756Z" level=info msg="CreateContainer within sandbox \"7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 12 13:31:57.122201 containerd[1491]: time="2025-05-12T13:31:57.122165413Z" level=info msg="Container 892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71: CDI devices from CRI Config.CDIDevices: []" May 12 13:31:57.128145 containerd[1491]: time="2025-05-12T13:31:57.128112701Z" level=info msg="CreateContainer within sandbox \"7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71\"" May 12 13:31:57.128514 containerd[1491]: time="2025-05-12T13:31:57.128458211Z" level=info msg="StartContainer for \"892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71\"" May 12 13:31:57.129196 containerd[1491]: time="2025-05-12T13:31:57.129173155Z" level=info msg="connecting to shim 892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71" address="unix:///run/containerd/s/8f16ac70af382300110897625de9dd93a2de561205c732991236a198292237c9" protocol=ttrpc version=3 May 12 13:31:57.148017 systemd[1]: Started cri-containerd-892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71.scope - libcontainer container 892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71. May 12 13:31:57.176583 containerd[1491]: time="2025-05-12T13:31:57.176550117Z" level=info msg="StartContainer for \"892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71\" returns successfully" May 12 13:31:57.183311 systemd[1]: cri-containerd-892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71.scope: Deactivated successfully. May 12 13:31:57.183973 containerd[1491]: time="2025-05-12T13:31:57.183667309Z" level=info msg="TaskExit event in podsandbox handler container_id:\"892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71\" id:\"892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71\" pid:2180 exited_at:{seconds:1747056717 nanos:183450049}" May 12 13:31:57.183973 containerd[1491]: time="2025-05-12T13:31:57.183739395Z" level=info msg="received exit event container_id:\"892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71\" id:\"892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71\" pid:2180 exited_at:{seconds:1747056717 nanos:183450049}" May 12 13:31:57.197967 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-892f2fcb186ff669379bbbff5225346c9c4c32a1932c7423f9888e8ae0b84e71-rootfs.mount: Deactivated successfully. May 12 13:31:57.237265 kubelet[1774]: I0512 13:31:57.237210 1774 kubelet_node_status.go:488] "Fast updating node status as it just became ready" May 12 13:31:57.479155 kubelet[1774]: E0512 13:31:57.479049 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:57.608375 containerd[1491]: time="2025-05-12T13:31:57.608332100Z" level=info msg="CreateContainer within sandbox \"7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 12 13:31:57.614292 containerd[1491]: time="2025-05-12T13:31:57.614252505Z" level=info msg="Container 5b0d445782afd3f901b70daeffbcd06f5f8dccfaeee00af2c23fbc61cae73408: CDI devices from CRI Config.CDIDevices: []" May 12 13:31:57.620813 containerd[1491]: time="2025-05-12T13:31:57.620720559Z" level=info msg="CreateContainer within sandbox \"7b60b701b432819cbd6f55a1019774837bf2012aa0bfe8c6a4748629da268531\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"5b0d445782afd3f901b70daeffbcd06f5f8dccfaeee00af2c23fbc61cae73408\"" May 12 13:31:57.621284 containerd[1491]: time="2025-05-12T13:31:57.621263247Z" level=info msg="StartContainer for \"5b0d445782afd3f901b70daeffbcd06f5f8dccfaeee00af2c23fbc61cae73408\"" May 12 13:31:57.622039 containerd[1491]: time="2025-05-12T13:31:57.622015274Z" level=info msg="connecting to shim 5b0d445782afd3f901b70daeffbcd06f5f8dccfaeee00af2c23fbc61cae73408" address="unix:///run/containerd/s/8f16ac70af382300110897625de9dd93a2de561205c732991236a198292237c9" protocol=ttrpc version=3 May 12 13:31:57.649054 systemd[1]: Started cri-containerd-5b0d445782afd3f901b70daeffbcd06f5f8dccfaeee00af2c23fbc61cae73408.scope - libcontainer container 5b0d445782afd3f901b70daeffbcd06f5f8dccfaeee00af2c23fbc61cae73408. May 12 13:31:57.671374 containerd[1491]: time="2025-05-12T13:31:57.670589623Z" level=info msg="StartContainer for \"5b0d445782afd3f901b70daeffbcd06f5f8dccfaeee00af2c23fbc61cae73408\" returns successfully" May 12 13:31:58.479986 kubelet[1774]: E0512 13:31:58.479942 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:31:58.621322 kubelet[1774]: I0512 13:31:58.621175 1774 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-npsd8" podStartSLOduration=4.945194338 podStartE2EDuration="9.621160844s" podCreationTimestamp="2025-05-12 13:31:49 +0000 UTC" firstStartedPulling="2025-05-12 13:31:52.439503273 +0000 UTC m=+3.672435335" lastFinishedPulling="2025-05-12 13:31:57.115469779 +0000 UTC m=+8.348401841" observedRunningTime="2025-05-12 13:31:58.621136202 +0000 UTC m=+9.854068264" watchObservedRunningTime="2025-05-12 13:31:58.621160844 +0000 UTC m=+9.854092906" May 12 13:31:58.740131 systemd-networkd[1427]: flannel.1: Link UP May 12 13:31:58.740142 systemd-networkd[1427]: flannel.1: Gained carrier May 12 13:31:59.481134 kubelet[1774]: E0512 13:31:59.481088 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:00.413050 systemd-networkd[1427]: flannel.1: Gained IPv6LL May 12 13:32:00.481605 kubelet[1774]: E0512 13:32:00.481561 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:00.610175 systemd[1]: Created slice kubepods-besteffort-pod243435c2_1156_4962_81e8_b30c0f779c56.slice - libcontainer container kubepods-besteffort-pod243435c2_1156_4962_81e8_b30c0f779c56.slice. May 12 13:32:00.648093 kubelet[1774]: I0512 13:32:00.648039 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-562zr\" (UniqueName: \"kubernetes.io/projected/243435c2-1156-4962-81e8-b30c0f779c56-kube-api-access-562zr\") pod \"nginx-deployment-8587fbcb89-7phgq\" (UID: \"243435c2-1156-4962-81e8-b30c0f779c56\") " pod="default/nginx-deployment-8587fbcb89-7phgq" May 12 13:32:00.913605 containerd[1491]: time="2025-05-12T13:32:00.913572861Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-7phgq,Uid:243435c2-1156-4962-81e8-b30c0f779c56,Namespace:default,Attempt:0,}" May 12 13:32:00.937702 systemd-networkd[1427]: cni0: Link UP May 12 13:32:00.937714 systemd-networkd[1427]: cni0: Gained carrier May 12 13:32:00.938001 systemd-networkd[1427]: cni0: Lost carrier May 12 13:32:00.944478 kernel: cni0: port 1(vethb4d0353e) entered blocking state May 12 13:32:00.944534 kernel: cni0: port 1(vethb4d0353e) entered disabled state May 12 13:32:00.944551 kernel: vethb4d0353e: entered allmulticast mode May 12 13:32:00.944640 systemd-networkd[1427]: vethb4d0353e: Link UP May 12 13:32:00.945252 kernel: vethb4d0353e: entered promiscuous mode May 12 13:32:00.945930 kernel: cni0: port 1(vethb4d0353e) entered blocking state May 12 13:32:00.945979 kernel: cni0: port 1(vethb4d0353e) entered forwarding state May 12 13:32:00.947059 kernel: cni0: port 1(vethb4d0353e) entered disabled state May 12 13:32:00.958883 kernel: cni0: port 1(vethb4d0353e) entered blocking state May 12 13:32:00.958971 kernel: cni0: port 1(vethb4d0353e) entered forwarding state May 12 13:32:00.959086 systemd-networkd[1427]: vethb4d0353e: Gained carrier May 12 13:32:00.959280 systemd-networkd[1427]: cni0: Gained carrier May 12 13:32:00.960422 containerd[1491]: 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)(0x4000012938), "name":"cbr0", "type":"bridge"} May 12 13:32:00.960422 containerd[1491]: delegateAdd: netconf sent to delegate plugin: May 12 13:32:00.983296 containerd[1491]: {"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-12T13:32:00.983211237Z" level=info msg="connecting to shim 2b12cae9c4b14aa64a11683fac8b62c2fb76a52b3905ca903c94a7fc3c2fc817" address="unix:///run/containerd/s/9f1be913ccf5995e051f38f9317aa4a3a94f905c60006e8ed3b74a00f5966fd1" namespace=k8s.io protocol=ttrpc version=3 May 12 13:32:01.006106 systemd[1]: Started cri-containerd-2b12cae9c4b14aa64a11683fac8b62c2fb76a52b3905ca903c94a7fc3c2fc817.scope - libcontainer container 2b12cae9c4b14aa64a11683fac8b62c2fb76a52b3905ca903c94a7fc3c2fc817. May 12 13:32:01.015274 systemd-resolved[1345]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 12 13:32:01.031595 containerd[1491]: time="2025-05-12T13:32:01.031560779Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-7phgq,Uid:243435c2-1156-4962-81e8-b30c0f779c56,Namespace:default,Attempt:0,} returns sandbox id \"2b12cae9c4b14aa64a11683fac8b62c2fb76a52b3905ca903c94a7fc3c2fc817\"" May 12 13:32:01.033925 containerd[1491]: time="2025-05-12T13:32:01.033508531Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 12 13:32:01.482139 kubelet[1774]: E0512 13:32:01.482080 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:02.397122 systemd-networkd[1427]: vethb4d0353e: Gained IPv6LL May 12 13:32:02.482957 kubelet[1774]: E0512 13:32:02.482911 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:02.653214 systemd-networkd[1427]: cni0: Gained IPv6LL May 12 13:32:03.096612 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2086241185.mount: Deactivated successfully. May 12 13:32:03.483222 kubelet[1774]: E0512 13:32:03.483123 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:03.745088 containerd[1491]: time="2025-05-12T13:32:03.744954493Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:32:03.745595 containerd[1491]: time="2025-05-12T13:32:03.745566017Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69948859" May 12 13:32:03.746166 containerd[1491]: time="2025-05-12T13:32:03.746141540Z" level=info msg="ImageCreate event name:\"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:32:03.748952 containerd[1491]: time="2025-05-12T13:32:03.748921704Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:32:03.749752 containerd[1491]: time="2025-05-12T13:32:03.749716642Z" 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.716177748s" May 12 13:32:03.749781 containerd[1491]: time="2025-05-12T13:32:03.749749804Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 12 13:32:03.751509 containerd[1491]: time="2025-05-12T13:32:03.751479691Z" level=info msg="CreateContainer within sandbox \"2b12cae9c4b14aa64a11683fac8b62c2fb76a52b3905ca903c94a7fc3c2fc817\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 12 13:32:03.759396 containerd[1491]: time="2025-05-12T13:32:03.759354308Z" level=info msg="Container 7e8086c4d26aa0c8d23515c69138f20a43a3c5cad53643ee9dc48d95c1c1f552: CDI devices from CRI Config.CDIDevices: []" May 12 13:32:03.760474 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1162884454.mount: Deactivated successfully. May 12 13:32:03.762253 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2278662903.mount: Deactivated successfully. May 12 13:32:03.764575 containerd[1491]: time="2025-05-12T13:32:03.764526088Z" level=info msg="CreateContainer within sandbox \"2b12cae9c4b14aa64a11683fac8b62c2fb76a52b3905ca903c94a7fc3c2fc817\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"7e8086c4d26aa0c8d23515c69138f20a43a3c5cad53643ee9dc48d95c1c1f552\"" May 12 13:32:03.764952 containerd[1491]: time="2025-05-12T13:32:03.764921157Z" level=info msg="StartContainer for \"7e8086c4d26aa0c8d23515c69138f20a43a3c5cad53643ee9dc48d95c1c1f552\"" May 12 13:32:03.765730 containerd[1491]: time="2025-05-12T13:32:03.765706614Z" level=info msg="connecting to shim 7e8086c4d26aa0c8d23515c69138f20a43a3c5cad53643ee9dc48d95c1c1f552" address="unix:///run/containerd/s/9f1be913ccf5995e051f38f9317aa4a3a94f905c60006e8ed3b74a00f5966fd1" protocol=ttrpc version=3 May 12 13:32:03.791014 systemd[1]: Started cri-containerd-7e8086c4d26aa0c8d23515c69138f20a43a3c5cad53643ee9dc48d95c1c1f552.scope - libcontainer container 7e8086c4d26aa0c8d23515c69138f20a43a3c5cad53643ee9dc48d95c1c1f552. May 12 13:32:03.820133 containerd[1491]: time="2025-05-12T13:32:03.820072200Z" level=info msg="StartContainer for \"7e8086c4d26aa0c8d23515c69138f20a43a3c5cad53643ee9dc48d95c1c1f552\" returns successfully" May 12 13:32:04.483946 kubelet[1774]: E0512 13:32:04.483907 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:04.628129 kubelet[1774]: I0512 13:32:04.628032 1774 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-7phgq" podStartSLOduration=1.910789624 podStartE2EDuration="4.62801961s" podCreationTimestamp="2025-05-12 13:32:00 +0000 UTC" firstStartedPulling="2025-05-12 13:32:01.033272673 +0000 UTC m=+12.266204735" lastFinishedPulling="2025-05-12 13:32:03.750502699 +0000 UTC m=+14.983434721" observedRunningTime="2025-05-12 13:32:04.627933444 +0000 UTC m=+15.860865546" watchObservedRunningTime="2025-05-12 13:32:04.62801961 +0000 UTC m=+15.860951673" May 12 13:32:05.484276 kubelet[1774]: E0512 13:32:05.484217 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:06.485116 kubelet[1774]: E0512 13:32:06.485065 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:07.486040 kubelet[1774]: E0512 13:32:07.485984 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:07.547767 systemd[1]: Created slice kubepods-besteffort-pod598e2335_5678_4e74_bc1a_d94738adf596.slice - libcontainer container kubepods-besteffort-pod598e2335_5678_4e74_bc1a_d94738adf596.slice. May 12 13:32:07.583781 kubelet[1774]: I0512 13:32:07.583728 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/598e2335-5678-4e74-bc1a-d94738adf596-data\") pod \"nfs-server-provisioner-0\" (UID: \"598e2335-5678-4e74-bc1a-d94738adf596\") " pod="default/nfs-server-provisioner-0" May 12 13:32:07.583781 kubelet[1774]: I0512 13:32:07.583768 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6nbqw\" (UniqueName: \"kubernetes.io/projected/598e2335-5678-4e74-bc1a-d94738adf596-kube-api-access-6nbqw\") pod \"nfs-server-provisioner-0\" (UID: \"598e2335-5678-4e74-bc1a-d94738adf596\") " pod="default/nfs-server-provisioner-0" May 12 13:32:07.851424 containerd[1491]: time="2025-05-12T13:32:07.851145237Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:598e2335-5678-4e74-bc1a-d94738adf596,Namespace:default,Attempt:0,}" May 12 13:32:07.864641 systemd-networkd[1427]: vethe771dfbd: Link UP May 12 13:32:07.866205 kernel: cni0: port 2(vethe771dfbd) entered blocking state May 12 13:32:07.866267 kernel: cni0: port 2(vethe771dfbd) entered disabled state May 12 13:32:07.866285 kernel: vethe771dfbd: entered allmulticast mode May 12 13:32:07.867892 kernel: vethe771dfbd: entered promiscuous mode May 12 13:32:07.872196 kernel: cni0: port 2(vethe771dfbd) entered blocking state May 12 13:32:07.872251 kernel: cni0: port 2(vethe771dfbd) entered forwarding state May 12 13:32:07.872271 systemd-networkd[1427]: vethe771dfbd: Gained carrier May 12 13:32:07.874067 containerd[1491]: 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)(0x4000012938), "name":"cbr0", "type":"bridge"} May 12 13:32:07.874067 containerd[1491]: delegateAdd: netconf sent to delegate plugin: May 12 13:32:07.891778 containerd[1491]: {"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-12T13:32:07.891741619Z" level=info msg="connecting to shim 745ac1f1d690822033eb86efd1f1ecdc838a986198601e0bf7ebdce940cd30ce" address="unix:///run/containerd/s/5b13c9652cb1709c30a4ccdb55e60874f43d8fc058b0b819a6b59919eba2f949" namespace=k8s.io protocol=ttrpc version=3 May 12 13:32:07.914988 systemd[1]: Started cri-containerd-745ac1f1d690822033eb86efd1f1ecdc838a986198601e0bf7ebdce940cd30ce.scope - libcontainer container 745ac1f1d690822033eb86efd1f1ecdc838a986198601e0bf7ebdce940cd30ce. May 12 13:32:07.924930 systemd-resolved[1345]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 12 13:32:07.941733 containerd[1491]: time="2025-05-12T13:32:07.941692805Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:598e2335-5678-4e74-bc1a-d94738adf596,Namespace:default,Attempt:0,} returns sandbox id \"745ac1f1d690822033eb86efd1f1ecdc838a986198601e0bf7ebdce940cd30ce\"" May 12 13:32:07.943309 containerd[1491]: time="2025-05-12T13:32:07.943286868Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 12 13:32:08.486588 kubelet[1774]: E0512 13:32:08.486534 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:09.181062 systemd-networkd[1427]: vethe771dfbd: Gained IPv6LL May 12 13:32:09.474851 kubelet[1774]: E0512 13:32:09.474742 1774 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:09.487390 kubelet[1774]: E0512 13:32:09.487318 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:09.636010 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2008611195.mount: Deactivated successfully. May 12 13:32:10.488158 kubelet[1774]: E0512 13:32:10.488113 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:10.898029 containerd[1491]: time="2025-05-12T13:32:10.897910747Z" 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 12 13:32:10.898656 containerd[1491]: time="2025-05-12T13:32:10.898583506Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" May 12 13:32:10.899400 containerd[1491]: time="2025-05-12T13:32:10.899353832Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:32:10.902034 containerd[1491]: time="2025-05-12T13:32:10.901990986Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:32:10.902674 containerd[1491]: time="2025-05-12T13:32:10.902521618Z" 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 2.959203468s" May 12 13:32:10.902674 containerd[1491]: time="2025-05-12T13:32:10.902571340Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" May 12 13:32:10.904677 containerd[1491]: time="2025-05-12T13:32:10.904645422Z" level=info msg="CreateContainer within sandbox \"745ac1f1d690822033eb86efd1f1ecdc838a986198601e0bf7ebdce940cd30ce\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 12 13:32:10.912244 containerd[1491]: time="2025-05-12T13:32:10.911950731Z" level=info msg="Container 789838d3faec730bcf0cf0a89a4d3a4991d5eaa81bde1ebb0ce131e7d6c0f501: CDI devices from CRI Config.CDIDevices: []" May 12 13:32:10.919087 containerd[1491]: time="2025-05-12T13:32:10.919046708Z" level=info msg="CreateContainer within sandbox \"745ac1f1d690822033eb86efd1f1ecdc838a986198601e0bf7ebdce940cd30ce\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"789838d3faec730bcf0cf0a89a4d3a4991d5eaa81bde1ebb0ce131e7d6c0f501\"" May 12 13:32:10.919570 containerd[1491]: time="2025-05-12T13:32:10.919536337Z" level=info msg="StartContainer for \"789838d3faec730bcf0cf0a89a4d3a4991d5eaa81bde1ebb0ce131e7d6c0f501\"" May 12 13:32:10.920365 containerd[1491]: time="2025-05-12T13:32:10.920339784Z" level=info msg="connecting to shim 789838d3faec730bcf0cf0a89a4d3a4991d5eaa81bde1ebb0ce131e7d6c0f501" address="unix:///run/containerd/s/5b13c9652cb1709c30a4ccdb55e60874f43d8fc058b0b819a6b59919eba2f949" protocol=ttrpc version=3 May 12 13:32:10.941008 systemd[1]: Started cri-containerd-789838d3faec730bcf0cf0a89a4d3a4991d5eaa81bde1ebb0ce131e7d6c0f501.scope - libcontainer container 789838d3faec730bcf0cf0a89a4d3a4991d5eaa81bde1ebb0ce131e7d6c0f501. May 12 13:32:10.966142 containerd[1491]: time="2025-05-12T13:32:10.966101550Z" level=info msg="StartContainer for \"789838d3faec730bcf0cf0a89a4d3a4991d5eaa81bde1ebb0ce131e7d6c0f501\" returns successfully" May 12 13:32:11.488698 kubelet[1774]: E0512 13:32:11.488657 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:11.643839 kubelet[1774]: I0512 13:32:11.643784 1774 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.683270454 podStartE2EDuration="4.6437718s" podCreationTimestamp="2025-05-12 13:32:07 +0000 UTC" firstStartedPulling="2025-05-12 13:32:07.942917524 +0000 UTC m=+19.175849586" lastFinishedPulling="2025-05-12 13:32:10.90341887 +0000 UTC m=+22.136350932" observedRunningTime="2025-05-12 13:32:11.64324157 +0000 UTC m=+22.876173712" watchObservedRunningTime="2025-05-12 13:32:11.6437718 +0000 UTC m=+22.876703862" May 12 13:32:12.488856 kubelet[1774]: E0512 13:32:12.488795 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:13.489413 kubelet[1774]: E0512 13:32:13.489352 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:14.490364 kubelet[1774]: E0512 13:32:14.490324 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:15.490651 kubelet[1774]: E0512 13:32:15.490607 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:16.491322 kubelet[1774]: E0512 13:32:16.491279 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:17.491646 kubelet[1774]: E0512 13:32:17.491606 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:18.492222 kubelet[1774]: E0512 13:32:18.492183 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:19.492490 kubelet[1774]: E0512 13:32:19.492447 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:20.492628 kubelet[1774]: E0512 13:32:20.492562 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:21.197934 systemd[1]: Created slice kubepods-besteffort-pode9261caa_370b_4bf3_bf09_874ef0ae27d9.slice - libcontainer container kubepods-besteffort-pode9261caa_370b_4bf3_bf09_874ef0ae27d9.slice. May 12 13:32:21.255422 kubelet[1774]: I0512 13:32:21.255365 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fvlh2\" (UniqueName: \"kubernetes.io/projected/e9261caa-370b-4bf3-bf09-874ef0ae27d9-kube-api-access-fvlh2\") pod \"test-pod-1\" (UID: \"e9261caa-370b-4bf3-bf09-874ef0ae27d9\") " pod="default/test-pod-1" May 12 13:32:21.255422 kubelet[1774]: I0512 13:32:21.255406 1774 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-258a2c57-6b38-4a8a-86e5-e84a8705e01b\" (UniqueName: \"kubernetes.io/nfs/e9261caa-370b-4bf3-bf09-874ef0ae27d9-pvc-258a2c57-6b38-4a8a-86e5-e84a8705e01b\") pod \"test-pod-1\" (UID: \"e9261caa-370b-4bf3-bf09-874ef0ae27d9\") " pod="default/test-pod-1" May 12 13:32:21.375889 kernel: FS-Cache: Loaded May 12 13:32:21.403416 kernel: RPC: Registered named UNIX socket transport module. May 12 13:32:21.403493 kernel: RPC: Registered udp transport module. May 12 13:32:21.403585 kernel: RPC: Registered tcp transport module. May 12 13:32:21.403615 kernel: RPC: Registered tcp-with-tls transport module. May 12 13:32:21.403639 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 12 13:32:21.493171 kubelet[1774]: E0512 13:32:21.493072 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:21.565916 kernel: NFS: Registering the id_resolver key type May 12 13:32:21.566017 kernel: Key type id_resolver registered May 12 13:32:21.566109 kernel: Key type id_legacy registered May 12 13:32:21.587303 nfsidmap[2778]: 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 12 13:32:21.591469 nfsidmap[2778]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 12 13:32:21.593292 nfsidmap[2779]: 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 12 13:32:21.593431 nfsidmap[2779]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 12 13:32:21.598195 nfsrahead[2781]: setting /var/lib/kubelet/pods/e9261caa-370b-4bf3-bf09-874ef0ae27d9/volumes/kubernetes.io~nfs/pvc-258a2c57-6b38-4a8a-86e5-e84a8705e01b readahead to 128 May 12 13:32:21.800511 containerd[1491]: time="2025-05-12T13:32:21.800431401Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e9261caa-370b-4bf3-bf09-874ef0ae27d9,Namespace:default,Attempt:0,}" May 12 13:32:21.808079 systemd-networkd[1427]: veth74aa2a5b: Link UP May 12 13:32:21.809174 kernel: cni0: port 3(veth74aa2a5b) entered blocking state May 12 13:32:21.809223 kernel: cni0: port 3(veth74aa2a5b) entered disabled state May 12 13:32:21.809250 kernel: veth74aa2a5b: entered allmulticast mode May 12 13:32:21.810045 kernel: veth74aa2a5b: entered promiscuous mode May 12 13:32:21.814422 kernel: cni0: port 3(veth74aa2a5b) entered blocking state May 12 13:32:21.814461 kernel: cni0: port 3(veth74aa2a5b) entered forwarding state May 12 13:32:21.814450 systemd-networkd[1427]: veth74aa2a5b: Gained carrier May 12 13:32:21.817652 containerd[1491]: 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)(0x400001a938), "name":"cbr0", "type":"bridge"} May 12 13:32:21.817652 containerd[1491]: delegateAdd: netconf sent to delegate plugin: May 12 13:32:21.833555 containerd[1491]: {"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-12T13:32:21.833519931Z" level=info msg="connecting to shim 75133281b168416f5b70246db28734b4bf6a38e125e1785d1ceb9ad1c13f913c" address="unix:///run/containerd/s/0ae1b4853ba46ba1e048de8be0d960bad40de5cede17d8f4334914d8b9dec745" namespace=k8s.io protocol=ttrpc version=3 May 12 13:32:21.855097 systemd[1]: Started cri-containerd-75133281b168416f5b70246db28734b4bf6a38e125e1785d1ceb9ad1c13f913c.scope - libcontainer container 75133281b168416f5b70246db28734b4bf6a38e125e1785d1ceb9ad1c13f913c. May 12 13:32:21.864969 systemd-resolved[1345]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 12 13:32:21.882394 containerd[1491]: time="2025-05-12T13:32:21.882357393Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e9261caa-370b-4bf3-bf09-874ef0ae27d9,Namespace:default,Attempt:0,} returns sandbox id \"75133281b168416f5b70246db28734b4bf6a38e125e1785d1ceb9ad1c13f913c\"" May 12 13:32:21.883850 containerd[1491]: time="2025-05-12T13:32:21.883800933Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 12 13:32:22.229779 containerd[1491]: time="2025-05-12T13:32:22.229524758Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 12 13:32:22.230424 containerd[1491]: time="2025-05-12T13:32:22.230293108Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 12 13:32:22.232897 containerd[1491]: time="2025-05-12T13:32:22.232868332Z" 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 349.005236ms" May 12 13:32:22.232958 containerd[1491]: time="2025-05-12T13:32:22.232900293Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e8b1cb61bd96acc3ff3c695318c9cc691213d532eee3731d038af92816fcb5f4\"" May 12 13:32:22.234898 containerd[1491]: time="2025-05-12T13:32:22.234614522Z" level=info msg="CreateContainer within sandbox \"75133281b168416f5b70246db28734b4bf6a38e125e1785d1ceb9ad1c13f913c\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 12 13:32:22.240360 containerd[1491]: time="2025-05-12T13:32:22.240321631Z" level=info msg="Container bd39489e023aa8d9f535d5ecebf52b75fed87a4c45cab6a40d551623fb9fed48: CDI devices from CRI Config.CDIDevices: []" May 12 13:32:22.245948 containerd[1491]: time="2025-05-12T13:32:22.245909695Z" level=info msg="CreateContainer within sandbox \"75133281b168416f5b70246db28734b4bf6a38e125e1785d1ceb9ad1c13f913c\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"bd39489e023aa8d9f535d5ecebf52b75fed87a4c45cab6a40d551623fb9fed48\"" May 12 13:32:22.246315 containerd[1491]: time="2025-05-12T13:32:22.246288990Z" level=info msg="StartContainer for \"bd39489e023aa8d9f535d5ecebf52b75fed87a4c45cab6a40d551623fb9fed48\"" May 12 13:32:22.247171 containerd[1491]: time="2025-05-12T13:32:22.247148905Z" level=info msg="connecting to shim bd39489e023aa8d9f535d5ecebf52b75fed87a4c45cab6a40d551623fb9fed48" address="unix:///run/containerd/s/0ae1b4853ba46ba1e048de8be0d960bad40de5cede17d8f4334914d8b9dec745" protocol=ttrpc version=3 May 12 13:32:22.264017 systemd[1]: Started cri-containerd-bd39489e023aa8d9f535d5ecebf52b75fed87a4c45cab6a40d551623fb9fed48.scope - libcontainer container bd39489e023aa8d9f535d5ecebf52b75fed87a4c45cab6a40d551623fb9fed48. May 12 13:32:22.288509 containerd[1491]: time="2025-05-12T13:32:22.288454841Z" level=info msg="StartContainer for \"bd39489e023aa8d9f535d5ecebf52b75fed87a4c45cab6a40d551623fb9fed48\" returns successfully" May 12 13:32:22.494009 kubelet[1774]: E0512 13:32:22.493848 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:23.069024 systemd-networkd[1427]: veth74aa2a5b: Gained IPv6LL May 12 13:32:23.495082 kubelet[1774]: E0512 13:32:23.494938 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 12 13:32:24.495834 kubelet[1774]: E0512 13:32:24.495790 1774 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"