Sep 9 23:25:34.778076 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 9 23:25:34.778097 kernel: Linux version 6.12.45-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 Tue Sep 9 22:08:34 -00 2025 Sep 9 23:25:34.778106 kernel: KASLR enabled Sep 9 23:25:34.778112 kernel: efi: EFI v2.7 by EDK II Sep 9 23:25:34.778117 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Sep 9 23:25:34.778123 kernel: random: crng init done Sep 9 23:25:34.778129 kernel: secureboot: Secure boot disabled Sep 9 23:25:34.778135 kernel: ACPI: Early table checksum verification disabled Sep 9 23:25:34.778140 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Sep 9 23:25:34.778147 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 9 23:25:34.778153 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:25:34.778159 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:25:34.778164 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:25:34.778170 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:25:34.778182 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:25:34.778189 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:25:34.778195 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:25:34.778201 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:25:34.778207 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 23:25:34.778213 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 9 23:25:34.778219 kernel: ACPI: Use ACPI SPCR as default console: No Sep 9 23:25:34.778225 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 9 23:25:34.778230 kernel: NODE_DATA(0) allocated [mem 0xdc964a00-0xdc96bfff] Sep 9 23:25:34.778236 kernel: Zone ranges: Sep 9 23:25:34.778242 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 9 23:25:34.778250 kernel: DMA32 empty Sep 9 23:25:34.778255 kernel: Normal empty Sep 9 23:25:34.778261 kernel: Device empty Sep 9 23:25:34.778267 kernel: Movable zone start for each node Sep 9 23:25:34.778273 kernel: Early memory node ranges Sep 9 23:25:34.778278 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Sep 9 23:25:34.778284 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Sep 9 23:25:34.778291 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Sep 9 23:25:34.778296 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Sep 9 23:25:34.778302 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Sep 9 23:25:34.778309 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Sep 9 23:25:34.778314 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Sep 9 23:25:34.778321 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Sep 9 23:25:34.778327 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Sep 9 23:25:34.778333 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 9 23:25:34.778341 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 9 23:25:34.778347 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 9 23:25:34.778354 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 9 23:25:34.778362 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 9 23:25:34.778368 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 9 23:25:34.778374 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Sep 9 23:25:34.778381 kernel: psci: probing for conduit method from ACPI. Sep 9 23:25:34.778387 kernel: psci: PSCIv1.1 detected in firmware. Sep 9 23:25:34.778393 kernel: psci: Using standard PSCI v0.2 function IDs Sep 9 23:25:34.778399 kernel: psci: Trusted OS migration not required Sep 9 23:25:34.778406 kernel: psci: SMC Calling Convention v1.1 Sep 9 23:25:34.778412 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 9 23:25:34.778418 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Sep 9 23:25:34.778435 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Sep 9 23:25:34.778442 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 9 23:25:34.778448 kernel: Detected PIPT I-cache on CPU0 Sep 9 23:25:34.778454 kernel: CPU features: detected: GIC system register CPU interface Sep 9 23:25:34.778460 kernel: CPU features: detected: Spectre-v4 Sep 9 23:25:34.778466 kernel: CPU features: detected: Spectre-BHB Sep 9 23:25:34.778473 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 9 23:25:34.778479 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 9 23:25:34.778485 kernel: CPU features: detected: ARM erratum 1418040 Sep 9 23:25:34.778492 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 9 23:25:34.778498 kernel: alternatives: applying boot alternatives Sep 9 23:25:34.778505 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=1a0303a4c67016bd8cbb391a5d1bb2355d0bb259dfb78ea746a1288c781f86ca Sep 9 23:25:34.778513 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 9 23:25:34.778520 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 9 23:25:34.778526 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 9 23:25:34.778532 kernel: Fallback order for Node 0: 0 Sep 9 23:25:34.778538 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Sep 9 23:25:34.778545 kernel: Policy zone: DMA Sep 9 23:25:34.778551 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 9 23:25:34.778557 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Sep 9 23:25:34.778563 kernel: software IO TLB: area num 4. Sep 9 23:25:34.778569 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Sep 9 23:25:34.778576 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Sep 9 23:25:34.778583 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 9 23:25:34.778590 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 9 23:25:34.778600 kernel: rcu: RCU event tracing is enabled. Sep 9 23:25:34.778610 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 9 23:25:34.778617 kernel: Trampoline variant of Tasks RCU enabled. Sep 9 23:25:34.778625 kernel: Tracing variant of Tasks RCU enabled. Sep 9 23:25:34.778632 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 9 23:25:34.778638 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 9 23:25:34.778645 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 9 23:25:34.778651 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 9 23:25:34.778662 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 9 23:25:34.778671 kernel: GICv3: 256 SPIs implemented Sep 9 23:25:34.778677 kernel: GICv3: 0 Extended SPIs implemented Sep 9 23:25:34.778683 kernel: Root IRQ handler: gic_handle_irq Sep 9 23:25:34.778690 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 9 23:25:34.778697 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Sep 9 23:25:34.778703 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 9 23:25:34.778709 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 9 23:25:34.778727 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Sep 9 23:25:34.778734 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Sep 9 23:25:34.778740 kernel: GICv3: using LPI property table @0x0000000040130000 Sep 9 23:25:34.778746 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Sep 9 23:25:34.778753 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 9 23:25:34.778760 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 23:25:34.778767 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 9 23:25:34.778773 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 9 23:25:34.778780 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 9 23:25:34.778786 kernel: arm-pv: using stolen time PV Sep 9 23:25:34.778793 kernel: Console: colour dummy device 80x25 Sep 9 23:25:34.778799 kernel: ACPI: Core revision 20240827 Sep 9 23:25:34.778806 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 9 23:25:34.778813 kernel: pid_max: default: 32768 minimum: 301 Sep 9 23:25:34.778819 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 9 23:25:34.778827 kernel: landlock: Up and running. Sep 9 23:25:34.778833 kernel: SELinux: Initializing. Sep 9 23:25:34.778839 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 23:25:34.778846 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 23:25:34.778852 kernel: rcu: Hierarchical SRCU implementation. Sep 9 23:25:34.778859 kernel: rcu: Max phase no-delay instances is 400. Sep 9 23:25:34.778865 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 9 23:25:34.778872 kernel: Remapping and enabling EFI services. Sep 9 23:25:34.778878 kernel: smp: Bringing up secondary CPUs ... Sep 9 23:25:34.778890 kernel: Detected PIPT I-cache on CPU1 Sep 9 23:25:34.778897 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 9 23:25:34.778904 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Sep 9 23:25:34.778912 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 23:25:34.778918 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 9 23:25:34.778925 kernel: Detected PIPT I-cache on CPU2 Sep 9 23:25:34.778932 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 9 23:25:34.778939 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Sep 9 23:25:34.778947 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 23:25:34.778954 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 9 23:25:34.778960 kernel: Detected PIPT I-cache on CPU3 Sep 9 23:25:34.778967 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 9 23:25:34.778974 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Sep 9 23:25:34.778981 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 23:25:34.778987 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 9 23:25:34.778994 kernel: smp: Brought up 1 node, 4 CPUs Sep 9 23:25:34.779001 kernel: SMP: Total of 4 processors activated. Sep 9 23:25:34.779009 kernel: CPU: All CPU(s) started at EL1 Sep 9 23:25:34.779016 kernel: CPU features: detected: 32-bit EL0 Support Sep 9 23:25:34.779023 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 9 23:25:34.779029 kernel: CPU features: detected: Common not Private translations Sep 9 23:25:34.779036 kernel: CPU features: detected: CRC32 instructions Sep 9 23:25:34.779043 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 9 23:25:34.779049 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 9 23:25:34.779056 kernel: CPU features: detected: LSE atomic instructions Sep 9 23:25:34.779063 kernel: CPU features: detected: Privileged Access Never Sep 9 23:25:34.779069 kernel: CPU features: detected: RAS Extension Support Sep 9 23:25:34.779077 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 9 23:25:34.779084 kernel: alternatives: applying system-wide alternatives Sep 9 23:25:34.779091 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Sep 9 23:25:34.779098 kernel: Memory: 2424476K/2572288K available (11136K kernel code, 2436K rwdata, 9076K rodata, 38976K init, 1038K bss, 125476K reserved, 16384K cma-reserved) Sep 9 23:25:34.779105 kernel: devtmpfs: initialized Sep 9 23:25:34.779112 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 9 23:25:34.779119 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 9 23:25:34.779126 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 9 23:25:34.779134 kernel: 0 pages in range for non-PLT usage Sep 9 23:25:34.779140 kernel: 508560 pages in range for PLT usage Sep 9 23:25:34.779147 kernel: pinctrl core: initialized pinctrl subsystem Sep 9 23:25:34.779154 kernel: SMBIOS 3.0.0 present. Sep 9 23:25:34.779160 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Sep 9 23:25:34.779167 kernel: DMI: Memory slots populated: 1/1 Sep 9 23:25:34.779174 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 9 23:25:34.779181 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 9 23:25:34.779187 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 9 23:25:34.779195 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 9 23:25:34.779202 kernel: audit: initializing netlink subsys (disabled) Sep 9 23:25:34.779209 kernel: audit: type=2000 audit(0.021:1): state=initialized audit_enabled=0 res=1 Sep 9 23:25:34.779216 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 9 23:25:34.779222 kernel: cpuidle: using governor menu Sep 9 23:25:34.779229 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 9 23:25:34.779236 kernel: ASID allocator initialised with 32768 entries Sep 9 23:25:34.779242 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 9 23:25:34.779249 kernel: Serial: AMBA PL011 UART driver Sep 9 23:25:34.779257 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 9 23:25:34.779264 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 9 23:25:34.779271 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 9 23:25:34.779278 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 9 23:25:34.779284 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 9 23:25:34.779291 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 9 23:25:34.779298 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 9 23:25:34.779305 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 9 23:25:34.779311 kernel: ACPI: Added _OSI(Module Device) Sep 9 23:25:34.779318 kernel: ACPI: Added _OSI(Processor Device) Sep 9 23:25:34.779326 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 9 23:25:34.779333 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 9 23:25:34.779339 kernel: ACPI: Interpreter enabled Sep 9 23:25:34.779346 kernel: ACPI: Using GIC for interrupt routing Sep 9 23:25:34.779353 kernel: ACPI: MCFG table detected, 1 entries Sep 9 23:25:34.779359 kernel: ACPI: CPU0 has been hot-added Sep 9 23:25:34.779366 kernel: ACPI: CPU1 has been hot-added Sep 9 23:25:34.779373 kernel: ACPI: CPU2 has been hot-added Sep 9 23:25:34.779379 kernel: ACPI: CPU3 has been hot-added Sep 9 23:25:34.779387 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 9 23:25:34.779394 kernel: printk: legacy console [ttyAMA0] enabled Sep 9 23:25:34.779401 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 9 23:25:34.779554 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 9 23:25:34.779621 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 9 23:25:34.779689 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 9 23:25:34.779750 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 9 23:25:34.779813 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 9 23:25:34.779823 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 9 23:25:34.779830 kernel: PCI host bridge to bus 0000:00 Sep 9 23:25:34.779893 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 9 23:25:34.779947 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 9 23:25:34.780000 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 9 23:25:34.780066 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 9 23:25:34.780181 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Sep 9 23:25:34.780254 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 9 23:25:34.780316 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Sep 9 23:25:34.780377 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Sep 9 23:25:34.780488 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Sep 9 23:25:34.780553 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Sep 9 23:25:34.780612 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Sep 9 23:25:34.780685 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Sep 9 23:25:34.780741 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 9 23:25:34.780793 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 9 23:25:34.780845 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 9 23:25:34.780854 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 9 23:25:34.780861 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 9 23:25:34.780868 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 9 23:25:34.780877 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 9 23:25:34.780884 kernel: iommu: Default domain type: Translated Sep 9 23:25:34.780891 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 9 23:25:34.780897 kernel: efivars: Registered efivars operations Sep 9 23:25:34.780904 kernel: vgaarb: loaded Sep 9 23:25:34.780911 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 9 23:25:34.780918 kernel: VFS: Disk quotas dquot_6.6.0 Sep 9 23:25:34.780925 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 9 23:25:34.780931 kernel: pnp: PnP ACPI init Sep 9 23:25:34.781002 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 9 23:25:34.781011 kernel: pnp: PnP ACPI: found 1 devices Sep 9 23:25:34.781018 kernel: NET: Registered PF_INET protocol family Sep 9 23:25:34.781025 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 9 23:25:34.781032 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 9 23:25:34.781039 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 9 23:25:34.781046 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 9 23:25:34.781053 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 9 23:25:34.781061 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 9 23:25:34.781068 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 23:25:34.781075 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 23:25:34.781082 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 9 23:25:34.781089 kernel: PCI: CLS 0 bytes, default 64 Sep 9 23:25:34.781096 kernel: kvm [1]: HYP mode not available Sep 9 23:25:34.781102 kernel: Initialise system trusted keyrings Sep 9 23:25:34.781109 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 9 23:25:34.781116 kernel: Key type asymmetric registered Sep 9 23:25:34.781122 kernel: Asymmetric key parser 'x509' registered Sep 9 23:25:34.781131 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Sep 9 23:25:34.781141 kernel: io scheduler mq-deadline registered Sep 9 23:25:34.781148 kernel: io scheduler kyber registered Sep 9 23:25:34.781155 kernel: io scheduler bfq registered Sep 9 23:25:34.781162 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 9 23:25:34.781168 kernel: ACPI: button: Power Button [PWRB] Sep 9 23:25:34.781175 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 9 23:25:34.781235 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 9 23:25:34.781244 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 9 23:25:34.781252 kernel: thunder_xcv, ver 1.0 Sep 9 23:25:34.781259 kernel: thunder_bgx, ver 1.0 Sep 9 23:25:34.781266 kernel: nicpf, ver 1.0 Sep 9 23:25:34.781273 kernel: nicvf, ver 1.0 Sep 9 23:25:34.781339 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 9 23:25:34.781395 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-09T23:25:34 UTC (1757460334) Sep 9 23:25:34.781404 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 9 23:25:34.781411 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Sep 9 23:25:34.781420 kernel: watchdog: NMI not fully supported Sep 9 23:25:34.781439 kernel: watchdog: Hard watchdog permanently disabled Sep 9 23:25:34.781446 kernel: NET: Registered PF_INET6 protocol family Sep 9 23:25:34.781453 kernel: Segment Routing with IPv6 Sep 9 23:25:34.781459 kernel: In-situ OAM (IOAM) with IPv6 Sep 9 23:25:34.781466 kernel: NET: Registered PF_PACKET protocol family Sep 9 23:25:34.781473 kernel: Key type dns_resolver registered Sep 9 23:25:34.781480 kernel: registered taskstats version 1 Sep 9 23:25:34.781487 kernel: Loading compiled-in X.509 certificates Sep 9 23:25:34.781495 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.45-flatcar: 820dabbdbfae37dcb388874c78ed83c436750814' Sep 9 23:25:34.781502 kernel: Demotion targets for Node 0: null Sep 9 23:25:34.781509 kernel: Key type .fscrypt registered Sep 9 23:25:34.781516 kernel: Key type fscrypt-provisioning registered Sep 9 23:25:34.781522 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 9 23:25:34.781529 kernel: ima: Allocated hash algorithm: sha1 Sep 9 23:25:34.781536 kernel: ima: No architecture policies found Sep 9 23:25:34.781543 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 9 23:25:34.781551 kernel: clk: Disabling unused clocks Sep 9 23:25:34.781557 kernel: PM: genpd: Disabling unused power domains Sep 9 23:25:34.781564 kernel: Warning: unable to open an initial console. Sep 9 23:25:34.781571 kernel: Freeing unused kernel memory: 38976K Sep 9 23:25:34.781578 kernel: Run /init as init process Sep 9 23:25:34.781584 kernel: with arguments: Sep 9 23:25:34.781591 kernel: /init Sep 9 23:25:34.781598 kernel: with environment: Sep 9 23:25:34.781605 kernel: HOME=/ Sep 9 23:25:34.781611 kernel: TERM=linux Sep 9 23:25:34.781619 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 9 23:25:34.781627 systemd[1]: Successfully made /usr/ read-only. Sep 9 23:25:34.781637 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) Sep 9 23:25:34.781645 systemd[1]: Detected virtualization kvm. Sep 9 23:25:34.781652 systemd[1]: Detected architecture arm64. Sep 9 23:25:34.781666 systemd[1]: Running in initrd. Sep 9 23:25:34.781673 systemd[1]: No hostname configured, using default hostname. Sep 9 23:25:34.781683 systemd[1]: Hostname set to . Sep 9 23:25:34.781690 systemd[1]: Initializing machine ID from VM UUID. Sep 9 23:25:34.781697 systemd[1]: Queued start job for default target initrd.target. Sep 9 23:25:34.781704 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 23:25:34.781712 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 23:25:34.781719 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 9 23:25:34.781727 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 23:25:34.781734 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 9 23:25:34.781744 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 9 23:25:34.781752 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 9 23:25:34.781759 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 9 23:25:34.781767 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 23:25:34.781774 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 23:25:34.781782 systemd[1]: Reached target paths.target - Path Units. Sep 9 23:25:34.781789 systemd[1]: Reached target slices.target - Slice Units. Sep 9 23:25:34.781798 systemd[1]: Reached target swap.target - Swaps. Sep 9 23:25:34.781805 systemd[1]: Reached target timers.target - Timer Units. Sep 9 23:25:34.781812 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 23:25:34.781820 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 23:25:34.781827 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 9 23:25:34.781835 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 9 23:25:34.781842 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 23:25:34.781850 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 23:25:34.781858 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 23:25:34.781866 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 23:25:34.781873 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 9 23:25:34.781881 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 23:25:34.781888 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 9 23:25:34.781896 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). Sep 9 23:25:34.781903 systemd[1]: Starting systemd-fsck-usr.service... Sep 9 23:25:34.781910 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 23:25:34.781918 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 23:25:34.781927 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 23:25:34.781934 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 9 23:25:34.781942 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 23:25:34.781949 systemd[1]: Finished systemd-fsck-usr.service. Sep 9 23:25:34.781974 systemd-journald[245]: Collecting audit messages is disabled. Sep 9 23:25:34.781993 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 23:25:34.782001 systemd-journald[245]: Journal started Sep 9 23:25:34.782020 systemd-journald[245]: Runtime Journal (/run/log/journal/ff7bb71204f74974a35de3cc72541096) is 6M, max 48.5M, 42.4M free. Sep 9 23:25:34.776453 systemd-modules-load[246]: Inserted module 'overlay' Sep 9 23:25:34.791065 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 9 23:25:34.791086 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:25:34.792337 systemd-modules-load[246]: Inserted module 'br_netfilter' Sep 9 23:25:34.793702 kernel: Bridge firewalling registered Sep 9 23:25:34.793720 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 23:25:34.794707 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 23:25:34.795753 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 23:25:34.799839 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 23:25:34.801338 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 23:25:34.802853 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 23:25:34.815109 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 23:25:34.822195 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 23:25:34.822360 systemd-tmpfiles[271]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 9 23:25:34.823214 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 23:25:34.825523 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 23:25:34.830156 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 23:25:34.835623 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 23:25:34.846703 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 9 23:25:34.861339 dracut-cmdline[289]: 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=1a0303a4c67016bd8cbb391a5d1bb2355d0bb259dfb78ea746a1288c781f86ca Sep 9 23:25:34.875637 systemd-resolved[283]: Positive Trust Anchors: Sep 9 23:25:34.875662 systemd-resolved[283]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 23:25:34.875694 systemd-resolved[283]: 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 Sep 9 23:25:34.880523 systemd-resolved[283]: Defaulting to hostname 'linux'. Sep 9 23:25:34.881458 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 23:25:34.885418 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 23:25:34.936451 kernel: SCSI subsystem initialized Sep 9 23:25:34.940447 kernel: Loading iSCSI transport class v2.0-870. Sep 9 23:25:34.948444 kernel: iscsi: registered transport (tcp) Sep 9 23:25:34.960722 kernel: iscsi: registered transport (qla4xxx) Sep 9 23:25:34.960747 kernel: QLogic iSCSI HBA Driver Sep 9 23:25:34.976892 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 23:25:34.989708 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 23:25:34.990968 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 23:25:35.036003 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 9 23:25:35.038026 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 9 23:25:35.103457 kernel: raid6: neonx8 gen() 15373 MB/s Sep 9 23:25:35.120441 kernel: raid6: neonx4 gen() 15584 MB/s Sep 9 23:25:35.137460 kernel: raid6: neonx2 gen() 13103 MB/s Sep 9 23:25:35.154443 kernel: raid6: neonx1 gen() 10428 MB/s Sep 9 23:25:35.171443 kernel: raid6: int64x8 gen() 6851 MB/s Sep 9 23:25:35.188442 kernel: raid6: int64x4 gen() 7303 MB/s Sep 9 23:25:35.205448 kernel: raid6: int64x2 gen() 6032 MB/s Sep 9 23:25:35.222443 kernel: raid6: int64x1 gen() 4826 MB/s Sep 9 23:25:35.222456 kernel: raid6: using algorithm neonx4 gen() 15584 MB/s Sep 9 23:25:35.239446 kernel: raid6: .... xor() 12281 MB/s, rmw enabled Sep 9 23:25:35.239464 kernel: raid6: using neon recovery algorithm Sep 9 23:25:35.244643 kernel: xor: measuring software checksum speed Sep 9 23:25:35.244667 kernel: 8regs : 21348 MB/sec Sep 9 23:25:35.245828 kernel: 32regs : 21704 MB/sec Sep 9 23:25:35.245841 kernel: arm64_neon : 28215 MB/sec Sep 9 23:25:35.245850 kernel: xor: using function: arm64_neon (28215 MB/sec) Sep 9 23:25:35.298453 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 9 23:25:35.305042 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 9 23:25:35.307485 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 23:25:35.333334 systemd-udevd[500]: Using default interface naming scheme 'v255'. Sep 9 23:25:35.337525 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 23:25:35.339289 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 9 23:25:35.362159 dracut-pre-trigger[508]: rd.md=0: removing MD RAID activation Sep 9 23:25:35.384945 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 23:25:35.387165 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 23:25:35.441641 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 23:25:35.446447 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 9 23:25:35.510715 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 9 23:25:35.518291 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 9 23:25:35.533417 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 9 23:25:35.533507 kernel: GPT:9289727 != 19775487 Sep 9 23:25:35.533518 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 9 23:25:35.533527 kernel: GPT:9289727 != 19775487 Sep 9 23:25:35.533535 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 9 23:25:35.533567 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 23:25:35.519681 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 23:25:35.519822 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:25:35.533442 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 23:25:35.535025 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 23:25:35.553903 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 9 23:25:35.561596 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:25:35.574505 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 9 23:25:35.575679 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 9 23:25:35.590015 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 9 23:25:35.596125 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 9 23:25:35.597148 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 9 23:25:35.599836 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 23:25:35.601575 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 23:25:35.603376 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 23:25:35.605985 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 9 23:25:35.607599 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 9 23:25:35.632051 disk-uuid[592]: Primary Header is updated. Sep 9 23:25:35.632051 disk-uuid[592]: Secondary Entries is updated. Sep 9 23:25:35.632051 disk-uuid[592]: Secondary Header is updated. Sep 9 23:25:35.637463 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 23:25:35.637985 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 9 23:25:35.642449 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 23:25:36.645321 disk-uuid[597]: The operation has completed successfully. Sep 9 23:25:36.646583 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 23:25:36.673985 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 9 23:25:36.674086 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 9 23:25:36.697089 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 9 23:25:36.725509 sh[613]: Success Sep 9 23:25:36.738938 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 9 23:25:36.739004 kernel: device-mapper: uevent: version 1.0.3 Sep 9 23:25:36.739026 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 9 23:25:36.745466 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Sep 9 23:25:36.776442 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 9 23:25:36.778942 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 9 23:25:36.792578 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 9 23:25:36.800827 kernel: BTRFS: device fsid 61baaba1-cd1f-4e69-9af9-cc1b703c9653 devid 1 transid 37 /dev/mapper/usr (253:0) scanned by mount (625) Sep 9 23:25:36.800866 kernel: BTRFS info (device dm-0): first mount of filesystem 61baaba1-cd1f-4e69-9af9-cc1b703c9653 Sep 9 23:25:36.800877 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 9 23:25:36.807994 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 9 23:25:36.808056 kernel: BTRFS info (device dm-0): enabling free space tree Sep 9 23:25:36.809038 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 9 23:25:36.810109 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 9 23:25:36.811169 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 9 23:25:36.811979 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 9 23:25:36.814019 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 9 23:25:36.842554 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (657) Sep 9 23:25:36.842593 kernel: BTRFS info (device vda6): first mount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:25:36.844109 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 9 23:25:36.848468 kernel: BTRFS info (device vda6): turning on async discard Sep 9 23:25:36.848511 kernel: BTRFS info (device vda6): enabling free space tree Sep 9 23:25:36.853459 kernel: BTRFS info (device vda6): last unmount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:25:36.853669 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 9 23:25:36.856031 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 9 23:25:36.924680 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 23:25:36.928255 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 23:25:36.962463 ignition[702]: Ignition 2.21.0 Sep 9 23:25:36.962482 ignition[702]: Stage: fetch-offline Sep 9 23:25:36.962515 ignition[702]: no configs at "/usr/lib/ignition/base.d" Sep 9 23:25:36.962522 ignition[702]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:25:36.962698 ignition[702]: parsed url from cmdline: "" Sep 9 23:25:36.962701 ignition[702]: no config URL provided Sep 9 23:25:36.962705 ignition[702]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 23:25:36.962712 ignition[702]: no config at "/usr/lib/ignition/user.ign" Sep 9 23:25:36.962733 ignition[702]: op(1): [started] loading QEMU firmware config module Sep 9 23:25:36.967805 systemd-networkd[801]: lo: Link UP Sep 9 23:25:36.962737 ignition[702]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 9 23:25:36.967810 systemd-networkd[801]: lo: Gained carrier Sep 9 23:25:36.971121 ignition[702]: op(1): [finished] loading QEMU firmware config module Sep 9 23:25:36.968513 systemd-networkd[801]: Enumeration completed Sep 9 23:25:36.968966 systemd-networkd[801]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 23:25:36.968970 systemd-networkd[801]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 23:25:36.969248 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 23:25:36.969847 systemd-networkd[801]: eth0: Link UP Sep 9 23:25:36.969941 systemd-networkd[801]: eth0: Gained carrier Sep 9 23:25:36.969951 systemd-networkd[801]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 23:25:36.970675 systemd[1]: Reached target network.target - Network. Sep 9 23:25:36.985182 ignition[702]: parsing config with SHA512: b80daa8d8da6b4bbed23f21b7a725f6b8e1b4452f2ed26e19d1a59d6e4fd0b4c133833c3d5bbb553f25309943ee4939eb43a387a13df7fb3f2964eeb11b21bc7 Sep 9 23:25:36.988763 unknown[702]: fetched base config from "system" Sep 9 23:25:36.988775 unknown[702]: fetched user config from "qemu" Sep 9 23:25:36.989032 ignition[702]: fetch-offline: fetch-offline passed Sep 9 23:25:36.990712 systemd-networkd[801]: eth0: DHCPv4 address 10.0.0.14/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 9 23:25:36.989082 ignition[702]: Ignition finished successfully Sep 9 23:25:36.991874 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 23:25:36.993050 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 9 23:25:36.996020 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 9 23:25:37.033749 ignition[809]: Ignition 2.21.0 Sep 9 23:25:37.033767 ignition[809]: Stage: kargs Sep 9 23:25:37.033904 ignition[809]: no configs at "/usr/lib/ignition/base.d" Sep 9 23:25:37.033912 ignition[809]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:25:37.035168 ignition[809]: kargs: kargs passed Sep 9 23:25:37.037435 ignition[809]: Ignition finished successfully Sep 9 23:25:37.042487 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 9 23:25:37.044204 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 9 23:25:37.079837 ignition[817]: Ignition 2.21.0 Sep 9 23:25:37.079856 ignition[817]: Stage: disks Sep 9 23:25:37.079998 ignition[817]: no configs at "/usr/lib/ignition/base.d" Sep 9 23:25:37.080007 ignition[817]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:25:37.080720 ignition[817]: disks: disks passed Sep 9 23:25:37.083128 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 9 23:25:37.081024 ignition[817]: Ignition finished successfully Sep 9 23:25:37.084845 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 9 23:25:37.085994 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 9 23:25:37.087489 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 23:25:37.088764 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 23:25:37.090249 systemd[1]: Reached target basic.target - Basic System. Sep 9 23:25:37.092591 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 9 23:25:37.116000 systemd-fsck[827]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 9 23:25:37.120049 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 9 23:25:37.122719 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 9 23:25:37.179454 kernel: EXT4-fs (vda9): mounted filesystem b3fb930d-58c7-4aff-a89a-67d23b38af56 r/w with ordered data mode. Quota mode: none. Sep 9 23:25:37.179615 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 9 23:25:37.180888 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 9 23:25:37.183117 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 23:25:37.184628 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 9 23:25:37.185444 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 9 23:25:37.185484 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 9 23:25:37.185505 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 23:25:37.203003 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 9 23:25:37.207449 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (835) Sep 9 23:25:37.205539 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 9 23:25:37.210509 kernel: BTRFS info (device vda6): first mount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:25:37.210542 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 9 23:25:37.213510 kernel: BTRFS info (device vda6): turning on async discard Sep 9 23:25:37.213546 kernel: BTRFS info (device vda6): enabling free space tree Sep 9 23:25:37.215070 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 23:25:37.244452 initrd-setup-root[859]: cut: /sysroot/etc/passwd: No such file or directory Sep 9 23:25:37.248365 initrd-setup-root[866]: cut: /sysroot/etc/group: No such file or directory Sep 9 23:25:37.251749 initrd-setup-root[873]: cut: /sysroot/etc/shadow: No such file or directory Sep 9 23:25:37.255414 initrd-setup-root[880]: cut: /sysroot/etc/gshadow: No such file or directory Sep 9 23:25:37.322453 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 9 23:25:37.324435 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 9 23:25:37.325784 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 9 23:25:37.339443 kernel: BTRFS info (device vda6): last unmount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:25:37.354572 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 9 23:25:37.367122 ignition[949]: INFO : Ignition 2.21.0 Sep 9 23:25:37.367122 ignition[949]: INFO : Stage: mount Sep 9 23:25:37.370215 ignition[949]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 23:25:37.370215 ignition[949]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:25:37.370215 ignition[949]: INFO : mount: mount passed Sep 9 23:25:37.370215 ignition[949]: INFO : Ignition finished successfully Sep 9 23:25:37.371278 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 9 23:25:37.373739 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 9 23:25:37.799844 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 9 23:25:37.801254 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 23:25:37.819462 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (961) Sep 9 23:25:37.822515 kernel: BTRFS info (device vda6): first mount of filesystem b5f2ab98-7907-428d-a6e6-1535b41157ff Sep 9 23:25:37.822539 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 9 23:25:37.826465 kernel: BTRFS info (device vda6): turning on async discard Sep 9 23:25:37.826503 kernel: BTRFS info (device vda6): enabling free space tree Sep 9 23:25:37.827516 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 23:25:37.861112 ignition[978]: INFO : Ignition 2.21.0 Sep 9 23:25:37.863278 ignition[978]: INFO : Stage: files Sep 9 23:25:37.863278 ignition[978]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 23:25:37.863278 ignition[978]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:25:37.863278 ignition[978]: DEBUG : files: compiled without relabeling support, skipping Sep 9 23:25:37.866260 ignition[978]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 9 23:25:37.866260 ignition[978]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 9 23:25:37.869455 ignition[978]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 9 23:25:37.870622 ignition[978]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 9 23:25:37.870622 ignition[978]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 9 23:25:37.869974 unknown[978]: wrote ssh authorized keys file for user: core Sep 9 23:25:37.874237 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 9 23:25:37.874237 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 9 23:25:37.878930 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 23:25:37.880567 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 23:25:37.880567 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Sep 9 23:25:37.884098 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Sep 9 23:25:37.886448 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Sep 9 23:25:37.886448 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Sep 9 23:25:38.074540 systemd-networkd[801]: eth0: Gained IPv6LL Sep 9 23:25:38.312915 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 9 23:25:38.894636 ignition[978]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Sep 9 23:25:38.894636 ignition[978]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 9 23:25:38.897896 ignition[978]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 9 23:25:38.899580 ignition[978]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 9 23:25:38.899580 ignition[978]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 9 23:25:38.899580 ignition[978]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 9 23:25:38.911856 ignition[978]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 9 23:25:38.915011 ignition[978]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 9 23:25:38.917468 ignition[978]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 9 23:25:38.917468 ignition[978]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 9 23:25:38.917468 ignition[978]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 9 23:25:38.917468 ignition[978]: INFO : files: files passed Sep 9 23:25:38.917468 ignition[978]: INFO : Ignition finished successfully Sep 9 23:25:38.917834 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 9 23:25:38.921577 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 9 23:25:38.923369 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 9 23:25:38.936738 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 9 23:25:38.937687 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 9 23:25:38.939561 initrd-setup-root-after-ignition[1007]: grep: /sysroot/oem/oem-release: No such file or directory Sep 9 23:25:38.940987 initrd-setup-root-after-ignition[1009]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 23:25:38.940987 initrd-setup-root-after-ignition[1009]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 9 23:25:38.943562 initrd-setup-root-after-ignition[1013]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 23:25:38.944877 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 23:25:38.946026 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 9 23:25:38.948302 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 9 23:25:38.979690 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 9 23:25:38.980539 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 9 23:25:38.981622 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 9 23:25:38.983497 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 9 23:25:38.985013 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 9 23:25:38.985752 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 9 23:25:39.010764 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 23:25:39.013131 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 9 23:25:39.037761 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 9 23:25:39.038780 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 23:25:39.040409 systemd[1]: Stopped target timers.target - Timer Units. Sep 9 23:25:39.041976 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 9 23:25:39.042096 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 23:25:39.044145 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 9 23:25:39.045907 systemd[1]: Stopped target basic.target - Basic System. Sep 9 23:25:39.047226 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 9 23:25:39.048656 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 23:25:39.050258 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 9 23:25:39.051800 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 9 23:25:39.053343 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 9 23:25:39.054854 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 23:25:39.056454 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 9 23:25:39.058032 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 9 23:25:39.059494 systemd[1]: Stopped target swap.target - Swaps. Sep 9 23:25:39.060822 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 9 23:25:39.060950 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 9 23:25:39.062939 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 9 23:25:39.064588 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 23:25:39.066291 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 9 23:25:39.066375 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 23:25:39.068286 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 9 23:25:39.068397 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 9 23:25:39.071030 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 9 23:25:39.071145 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 23:25:39.072687 systemd[1]: Stopped target paths.target - Path Units. Sep 9 23:25:39.073949 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 9 23:25:39.077468 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 23:25:39.078566 systemd[1]: Stopped target slices.target - Slice Units. Sep 9 23:25:39.080465 systemd[1]: Stopped target sockets.target - Socket Units. Sep 9 23:25:39.081924 systemd[1]: iscsid.socket: Deactivated successfully. Sep 9 23:25:39.082002 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 23:25:39.083292 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 9 23:25:39.083364 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 23:25:39.084818 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 9 23:25:39.084926 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 23:25:39.086551 systemd[1]: ignition-files.service: Deactivated successfully. Sep 9 23:25:39.086650 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 9 23:25:39.088983 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 9 23:25:39.090391 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 9 23:25:39.090524 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 23:25:39.111016 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 9 23:25:39.111846 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 9 23:25:39.111962 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 23:25:39.113619 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 9 23:25:39.113731 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 23:25:39.119566 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 9 23:25:39.119679 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 9 23:25:39.125349 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 9 23:25:39.126518 ignition[1035]: INFO : Ignition 2.21.0 Sep 9 23:25:39.126518 ignition[1035]: INFO : Stage: umount Sep 9 23:25:39.128836 ignition[1035]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 23:25:39.128836 ignition[1035]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 23:25:39.128836 ignition[1035]: INFO : umount: umount passed Sep 9 23:25:39.128836 ignition[1035]: INFO : Ignition finished successfully Sep 9 23:25:39.131720 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 9 23:25:39.131837 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 9 23:25:39.133850 systemd[1]: Stopped target network.target - Network. Sep 9 23:25:39.134998 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 9 23:25:39.135060 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 9 23:25:39.136562 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 9 23:25:39.136602 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 9 23:25:39.138208 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 9 23:25:39.138265 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 9 23:25:39.139811 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 9 23:25:39.139878 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 9 23:25:39.141539 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 9 23:25:39.143050 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 9 23:25:39.150581 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 9 23:25:39.150705 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 9 23:25:39.153349 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 9 23:25:39.154610 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 9 23:25:39.155954 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 9 23:25:39.155990 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 9 23:25:39.158227 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 9 23:25:39.159943 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 9 23:25:39.160001 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 23:25:39.161928 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 23:25:39.164409 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 9 23:25:39.170653 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 9 23:25:39.173666 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 9 23:25:39.175090 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 9 23:25:39.175248 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 23:25:39.179246 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 9 23:25:39.179309 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 9 23:25:39.180536 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 9 23:25:39.180567 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 23:25:39.182094 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 9 23:25:39.182145 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 9 23:25:39.184501 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 9 23:25:39.184546 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 9 23:25:39.186873 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 23:25:39.186921 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 23:25:39.189898 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 9 23:25:39.190804 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 9 23:25:39.190864 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 23:25:39.193660 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 9 23:25:39.193706 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 9 23:25:39.196382 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 9 23:25:39.196439 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 9 23:25:39.198490 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 9 23:25:39.198538 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 23:25:39.201118 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 9 23:25:39.201161 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 23:25:39.203611 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 23:25:39.203666 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:25:39.207276 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 9 23:25:39.207318 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 9 23:25:39.207346 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 9 23:25:39.207375 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 9 23:25:39.207403 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 9 23:25:39.207740 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 9 23:25:39.207854 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 9 23:25:39.209269 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 9 23:25:39.209370 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 9 23:25:39.283710 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 9 23:25:39.283813 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 9 23:25:39.285741 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 9 23:25:39.286934 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 9 23:25:39.287005 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 9 23:25:39.289442 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 9 23:25:39.310763 systemd[1]: Switching root. Sep 9 23:25:39.344531 systemd-journald[245]: Journal stopped Sep 9 23:25:40.127387 systemd-journald[245]: Received SIGTERM from PID 1 (systemd). Sep 9 23:25:40.127468 kernel: SELinux: policy capability network_peer_controls=1 Sep 9 23:25:40.127487 kernel: SELinux: policy capability open_perms=1 Sep 9 23:25:40.127497 kernel: SELinux: policy capability extended_socket_class=1 Sep 9 23:25:40.127507 kernel: SELinux: policy capability always_check_network=0 Sep 9 23:25:40.127516 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 9 23:25:40.127528 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 9 23:25:40.127538 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 9 23:25:40.127547 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 9 23:25:40.127556 kernel: SELinux: policy capability userspace_initial_context=0 Sep 9 23:25:40.127566 kernel: audit: type=1403 audit(1757460339.542:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 9 23:25:40.127579 systemd[1]: Successfully loaded SELinux policy in 46.203ms. Sep 9 23:25:40.127595 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.931ms. Sep 9 23:25:40.127607 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) Sep 9 23:25:40.127619 systemd[1]: Detected virtualization kvm. Sep 9 23:25:40.127629 systemd[1]: Detected architecture arm64. Sep 9 23:25:40.127648 systemd[1]: Detected first boot. Sep 9 23:25:40.127659 systemd[1]: Initializing machine ID from VM UUID. Sep 9 23:25:40.127669 zram_generator::config[1083]: No configuration found. Sep 9 23:25:40.127680 kernel: NET: Registered PF_VSOCK protocol family Sep 9 23:25:40.127694 systemd[1]: Populated /etc with preset unit settings. Sep 9 23:25:40.127706 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 9 23:25:40.127716 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 9 23:25:40.127727 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 9 23:25:40.127738 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 9 23:25:40.127752 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 9 23:25:40.127762 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 9 23:25:40.127772 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 9 23:25:40.127782 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 9 23:25:40.127792 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 9 23:25:40.127803 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 9 23:25:40.127814 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 9 23:25:40.127824 systemd[1]: Created slice user.slice - User and Session Slice. Sep 9 23:25:40.127834 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 23:25:40.127844 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 23:25:40.127855 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 9 23:25:40.127866 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 9 23:25:40.127877 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 9 23:25:40.127888 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 23:25:40.127898 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 9 23:25:40.127910 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 23:25:40.127920 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 23:25:40.127931 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 9 23:25:40.127941 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 9 23:25:40.127952 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 9 23:25:40.127962 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 9 23:25:40.127972 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 23:25:40.127983 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 23:25:40.127995 systemd[1]: Reached target slices.target - Slice Units. Sep 9 23:25:40.128006 systemd[1]: Reached target swap.target - Swaps. Sep 9 23:25:40.128064 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 9 23:25:40.128079 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 9 23:25:40.128089 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 9 23:25:40.128099 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 23:25:40.128110 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 23:25:40.128120 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 23:25:40.128130 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 9 23:25:40.128142 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 9 23:25:40.128152 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 9 23:25:40.128162 systemd[1]: Mounting media.mount - External Media Directory... Sep 9 23:25:40.128173 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 9 23:25:40.128183 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 9 23:25:40.128193 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 9 23:25:40.128204 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). Sep 9 23:25:40.128217 systemd[1]: Reached target machines.target - Containers. Sep 9 23:25:40.128227 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 9 23:25:40.128239 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 23:25:40.128254 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 23:25:40.128265 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 9 23:25:40.128275 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 23:25:40.128285 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 23:25:40.128295 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 23:25:40.128305 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 9 23:25:40.128315 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 23:25:40.128327 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 9 23:25:40.128338 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 9 23:25:40.128349 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 9 23:25:40.128359 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 9 23:25:40.128369 systemd[1]: Stopped systemd-fsck-usr.service. Sep 9 23:25:40.128379 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). Sep 9 23:25:40.128389 kernel: fuse: init (API version 7.41) Sep 9 23:25:40.128398 kernel: loop: module loaded Sep 9 23:25:40.128409 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 23:25:40.128419 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 23:25:40.128441 kernel: ACPI: bus type drm_connector registered Sep 9 23:25:40.128452 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 23:25:40.128462 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 9 23:25:40.128472 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 9 23:25:40.128482 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 23:25:40.128522 systemd-journald[1151]: Collecting audit messages is disabled. Sep 9 23:25:40.128546 systemd[1]: verity-setup.service: Deactivated successfully. Sep 9 23:25:40.128560 systemd[1]: Stopped verity-setup.service. Sep 9 23:25:40.128571 systemd-journald[1151]: Journal started Sep 9 23:25:40.128591 systemd-journald[1151]: Runtime Journal (/run/log/journal/ff7bb71204f74974a35de3cc72541096) is 6M, max 48.5M, 42.4M free. Sep 9 23:25:39.917722 systemd[1]: Queued start job for default target multi-user.target. Sep 9 23:25:39.943366 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 9 23:25:39.943764 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 9 23:25:40.137451 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 23:25:40.138218 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 9 23:25:40.139235 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 9 23:25:40.140246 systemd[1]: Mounted media.mount - External Media Directory. Sep 9 23:25:40.141257 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 9 23:25:40.142377 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 9 23:25:40.143521 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 9 23:25:40.144639 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 9 23:25:40.145922 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 23:25:40.147212 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 9 23:25:40.147383 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 9 23:25:40.148725 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 23:25:40.148887 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 23:25:40.150902 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 23:25:40.151132 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 23:25:40.152392 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 23:25:40.152598 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 23:25:40.153925 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 9 23:25:40.154115 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 9 23:25:40.155363 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 23:25:40.156678 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 23:25:40.157952 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 23:25:40.159922 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 23:25:40.161265 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 9 23:25:40.162729 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 9 23:25:40.175219 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 23:25:40.177689 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 9 23:25:40.179676 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 9 23:25:40.180706 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 9 23:25:40.180737 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 23:25:40.182533 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 9 23:25:40.188356 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 9 23:25:40.189476 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 23:25:40.190573 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 9 23:25:40.192360 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 9 23:25:40.193643 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 23:25:40.194660 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 9 23:25:40.197665 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 23:25:40.199297 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 23:25:40.200251 systemd-journald[1151]: Time spent on flushing to /var/log/journal/ff7bb71204f74974a35de3cc72541096 is 17.176ms for 871 entries. Sep 9 23:25:40.200251 systemd-journald[1151]: System Journal (/var/log/journal/ff7bb71204f74974a35de3cc72541096) is 8M, max 195.6M, 187.6M free. Sep 9 23:25:40.226871 systemd-journald[1151]: Received client request to flush runtime journal. Sep 9 23:25:40.202597 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 9 23:25:40.208026 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 9 23:25:40.211840 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 23:25:40.213095 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 9 23:25:40.214289 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 9 23:25:40.220472 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 9 23:25:40.223122 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 9 23:25:40.229832 kernel: loop0: detected capacity change from 0 to 138376 Sep 9 23:25:40.230859 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 9 23:25:40.235555 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 9 23:25:40.237375 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 23:25:40.251462 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 9 23:25:40.262701 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 9 23:25:40.265551 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 23:25:40.270511 kernel: loop1: detected capacity change from 0 to 207008 Sep 9 23:25:40.279990 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 9 23:25:40.292461 kernel: loop2: detected capacity change from 0 to 107312 Sep 9 23:25:40.296205 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. Sep 9 23:25:40.296219 systemd-tmpfiles[1217]: ACLs are not supported, ignoring. Sep 9 23:25:40.302471 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 23:25:40.322915 kernel: loop3: detected capacity change from 0 to 138376 Sep 9 23:25:40.329461 kernel: loop4: detected capacity change from 0 to 207008 Sep 9 23:25:40.336470 kernel: loop5: detected capacity change from 0 to 107312 Sep 9 23:25:40.340498 (sd-merge)[1225]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 9 23:25:40.340918 (sd-merge)[1225]: Merged extensions into '/usr'. Sep 9 23:25:40.344562 systemd[1]: Reload requested from client PID 1199 ('systemd-sysext') (unit systemd-sysext.service)... Sep 9 23:25:40.344579 systemd[1]: Reloading... Sep 9 23:25:40.417907 zram_generator::config[1249]: No configuration found. Sep 9 23:25:40.485470 ldconfig[1194]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 23:25:40.498932 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. Sep 9 23:25:40.562172 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 9 23:25:40.562246 systemd[1]: Reloading finished in 217 ms. Sep 9 23:25:40.581467 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 9 23:25:40.582650 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 9 23:25:40.600979 systemd[1]: Starting ensure-sysext.service... Sep 9 23:25:40.602824 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 23:25:40.611096 systemd[1]: Reload requested from client PID 1287 ('systemctl') (unit ensure-sysext.service)... Sep 9 23:25:40.611113 systemd[1]: Reloading... Sep 9 23:25:40.619363 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 9 23:25:40.619734 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 9 23:25:40.620027 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 9 23:25:40.620292 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 9 23:25:40.621104 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 23:25:40.621442 systemd-tmpfiles[1289]: ACLs are not supported, ignoring. Sep 9 23:25:40.621580 systemd-tmpfiles[1289]: ACLs are not supported, ignoring. Sep 9 23:25:40.624291 systemd-tmpfiles[1289]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 23:25:40.624381 systemd-tmpfiles[1289]: Skipping /boot Sep 9 23:25:40.633257 systemd-tmpfiles[1289]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 23:25:40.633381 systemd-tmpfiles[1289]: Skipping /boot Sep 9 23:25:40.661451 zram_generator::config[1316]: No configuration found. Sep 9 23:25:40.731980 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. Sep 9 23:25:40.794270 systemd[1]: Reloading finished in 182 ms. Sep 9 23:25:40.803048 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 9 23:25:40.813163 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 23:25:40.821180 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 23:25:40.823548 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 9 23:25:40.832008 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 9 23:25:40.836659 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 23:25:40.839692 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 23:25:40.842741 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 9 23:25:40.847862 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 23:25:40.853597 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 23:25:40.856090 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 23:25:40.858169 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 23:25:40.859554 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 23:25:40.859679 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). Sep 9 23:25:40.861605 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 23:25:40.861795 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 23:25:40.868520 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 9 23:25:40.870159 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 23:25:40.870317 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 23:25:40.874413 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 9 23:25:40.878165 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 23:25:40.879718 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 23:25:40.881720 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 23:25:40.883524 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 23:25:40.883718 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). Sep 9 23:25:40.885376 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 9 23:25:40.891350 systemd-udevd[1362]: Using default interface naming scheme 'v255'. Sep 9 23:25:40.897607 augenrules[1387]: No rules Sep 9 23:25:40.901784 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 9 23:25:40.903057 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). Sep 9 23:25:40.905363 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 23:25:40.905788 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 23:25:40.907544 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 23:25:40.907811 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 23:25:40.909269 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 23:25:40.911177 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 23:25:40.911334 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 23:25:40.912900 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 23:25:40.913038 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 23:25:40.918678 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 9 23:25:40.925226 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 9 23:25:40.937733 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 23:25:40.938603 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 23:25:40.941727 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 23:25:40.945699 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 23:25:40.952824 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 23:25:40.955080 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 23:25:40.956196 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 23:25:40.956331 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). Sep 9 23:25:40.959813 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 23:25:40.960671 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). Sep 9 23:25:40.964148 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 23:25:40.970642 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 23:25:40.972326 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 23:25:40.972542 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 23:25:40.973936 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 23:25:40.974092 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 23:25:40.977467 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 23:25:40.977705 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 23:25:40.977779 augenrules[1425]: /sbin/augenrules: No change Sep 9 23:25:40.980491 systemd[1]: Finished ensure-sysext.service. Sep 9 23:25:40.991857 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 23:25:40.991930 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 23:25:40.995019 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 9 23:25:40.997083 augenrules[1453]: No rules Sep 9 23:25:40.999810 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 23:25:41.000028 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 23:25:41.013189 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 9 23:25:41.027654 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 9 23:25:41.106884 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 9 23:25:41.108334 systemd-networkd[1431]: lo: Link UP Sep 9 23:25:41.108700 systemd-networkd[1431]: lo: Gained carrier Sep 9 23:25:41.109727 systemd-networkd[1431]: Enumeration completed Sep 9 23:25:41.110552 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 9 23:25:41.110553 systemd-networkd[1431]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 23:25:41.110744 systemd-networkd[1431]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 23:25:41.111445 systemd-networkd[1431]: eth0: Link UP Sep 9 23:25:41.111711 systemd-networkd[1431]: eth0: Gained carrier Sep 9 23:25:41.111786 systemd-networkd[1431]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 23:25:41.112105 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 23:25:41.114770 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 9 23:25:41.116596 systemd[1]: Reached target time-set.target - System Time Set. Sep 9 23:25:41.119162 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 9 23:25:41.122497 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 9 23:25:41.123908 systemd-resolved[1355]: Positive Trust Anchors: Sep 9 23:25:41.123929 systemd-resolved[1355]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 23:25:41.123962 systemd-resolved[1355]: 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 Sep 9 23:25:41.125576 systemd-networkd[1431]: eth0: DHCPv4 address 10.0.0.14/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 9 23:25:41.126754 systemd-timesyncd[1455]: Network configuration changed, trying to establish connection. Sep 9 23:25:41.128069 systemd-timesyncd[1455]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 9 23:25:41.128128 systemd-timesyncd[1455]: Initial clock synchronization to Tue 2025-09-09 23:25:40.859685 UTC. Sep 9 23:25:41.132655 systemd-resolved[1355]: Defaulting to hostname 'linux'. Sep 9 23:25:41.134476 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 23:25:41.135668 systemd[1]: Reached target network.target - Network. Sep 9 23:25:41.136567 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 23:25:41.137639 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 23:25:41.138756 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 9 23:25:41.139784 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 9 23:25:41.141391 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 9 23:25:41.142571 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 9 23:25:41.143694 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 9 23:25:41.144636 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 9 23:25:41.144665 systemd[1]: Reached target paths.target - Path Units. Sep 9 23:25:41.145514 systemd[1]: Reached target timers.target - Timer Units. Sep 9 23:25:41.147331 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 9 23:25:41.149945 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 23:25:41.152782 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 9 23:25:41.153952 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 9 23:25:41.155017 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 9 23:25:41.158250 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 9 23:25:41.159704 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 9 23:25:41.163585 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 9 23:25:41.164982 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 9 23:25:41.166221 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 23:25:41.169080 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 23:25:41.171582 systemd[1]: Reached target basic.target - Basic System. Sep 9 23:25:41.172365 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 9 23:25:41.172396 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 9 23:25:41.174595 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 23:25:41.179893 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 9 23:25:41.183415 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 9 23:25:41.202347 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 9 23:25:41.207644 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 9 23:25:41.208626 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). Sep 9 23:25:41.210579 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 9 23:25:41.214757 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 9 23:25:41.218747 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 9 23:25:41.219555 jq[1502]: false Sep 9 23:25:41.224650 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 9 23:25:41.226471 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 9 23:25:41.226991 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 9 23:25:41.227697 systemd[1]: Starting update-engine.service - Update Engine... Sep 9 23:25:41.232758 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 9 23:25:41.239729 extend-filesystems[1503]: Found /dev/vda6 Sep 9 23:25:41.242123 extend-filesystems[1503]: Found /dev/vda9 Sep 9 23:25:41.243013 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 9 23:25:41.245024 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 9 23:25:41.245229 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 9 23:25:41.245901 jq[1511]: true Sep 9 23:25:41.246275 systemd[1]: motdgen.service: Deactivated successfully. Sep 9 23:25:41.249482 extend-filesystems[1503]: Checking size of /dev/vda9 Sep 9 23:25:41.246516 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 9 23:25:41.247928 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 9 23:25:41.248086 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 9 23:25:41.262866 extend-filesystems[1503]: Resized partition /dev/vda9 Sep 9 23:25:41.265853 (ntainerd)[1527]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 9 23:25:41.269349 jq[1526]: true Sep 9 23:25:41.269589 update_engine[1510]: I20250909 23:25:41.269068 1510 main.cc:92] Flatcar Update Engine starting Sep 9 23:25:41.276502 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 23:25:41.280135 extend-filesystems[1537]: resize2fs 1.47.2 (1-Jan-2025) Sep 9 23:25:41.282290 dbus-daemon[1498]: [system] SELinux support is enabled Sep 9 23:25:41.282448 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 9 23:25:41.287312 update_engine[1510]: I20250909 23:25:41.287123 1510 update_check_scheduler.cc:74] Next update check in 7m28s Sep 9 23:25:41.285234 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). Sep 9 23:25:41.285258 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 9 23:25:41.287548 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). Sep 9 23:25:41.287566 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 9 23:25:41.289670 systemd[1]: Started update-engine.service - Update Engine. Sep 9 23:25:41.301490 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 9 23:25:41.298203 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 9 23:25:41.343450 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 9 23:25:41.355908 extend-filesystems[1537]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 9 23:25:41.355908 extend-filesystems[1537]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 9 23:25:41.355908 extend-filesystems[1537]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 9 23:25:41.359334 bash[1559]: Updated "/home/core/.ssh/authorized_keys" Sep 9 23:25:41.360792 extend-filesystems[1503]: Resized filesystem in /dev/vda9 Sep 9 23:25:41.361318 systemd-logind[1508]: Watching system buttons on /dev/input/event0 (Power Button) Sep 9 23:25:41.362034 systemd-logind[1508]: New seat seat0. Sep 9 23:25:41.362336 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 9 23:25:41.362665 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 9 23:25:41.369092 systemd[1]: Started systemd-logind.service - User Login Management. Sep 9 23:25:41.371445 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 9 23:25:41.373618 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 23:25:41.380230 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 9 23:25:41.396166 locksmithd[1546]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 9 23:25:41.476761 containerd[1527]: time="2025-09-09T23:25:41Z" 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 Sep 9 23:25:41.477374 containerd[1527]: time="2025-09-09T23:25:41.477325680Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 9 23:25:41.485779 containerd[1527]: time="2025-09-09T23:25:41.485735040Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.68µs" Sep 9 23:25:41.485779 containerd[1527]: time="2025-09-09T23:25:41.485773840Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 9 23:25:41.485868 containerd[1527]: time="2025-09-09T23:25:41.485792080Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 9 23:25:41.485975 containerd[1527]: time="2025-09-09T23:25:41.485955680Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 9 23:25:41.485995 containerd[1527]: time="2025-09-09T23:25:41.485977400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 9 23:25:41.486069 containerd[1527]: time="2025-09-09T23:25:41.486001120Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 23:25:41.486069 containerd[1527]: time="2025-09-09T23:25:41.486052440Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 23:25:41.486069 containerd[1527]: time="2025-09-09T23:25:41.486064080Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 23:25:41.486310 containerd[1527]: time="2025-09-09T23:25:41.486286720Z" 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 Sep 9 23:25:41.486310 containerd[1527]: time="2025-09-09T23:25:41.486307800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 23:25:41.486348 containerd[1527]: time="2025-09-09T23:25:41.486319480Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 23:25:41.486348 containerd[1527]: time="2025-09-09T23:25:41.486328080Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 9 23:25:41.486413 containerd[1527]: time="2025-09-09T23:25:41.486399840Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 9 23:25:41.486638 containerd[1527]: time="2025-09-09T23:25:41.486609720Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 23:25:41.486669 containerd[1527]: time="2025-09-09T23:25:41.486658680Z" 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 Sep 9 23:25:41.486689 containerd[1527]: time="2025-09-09T23:25:41.486672160Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 9 23:25:41.486711 containerd[1527]: time="2025-09-09T23:25:41.486704600Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 9 23:25:41.486934 containerd[1527]: time="2025-09-09T23:25:41.486918520Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 9 23:25:41.486997 containerd[1527]: time="2025-09-09T23:25:41.486981080Z" level=info msg="metadata content store policy set" policy=shared Sep 9 23:25:41.489958 containerd[1527]: time="2025-09-09T23:25:41.489919760Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 9 23:25:41.490016 containerd[1527]: time="2025-09-09T23:25:41.489970360Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 9 23:25:41.490016 containerd[1527]: time="2025-09-09T23:25:41.489985040Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 9 23:25:41.490016 containerd[1527]: time="2025-09-09T23:25:41.489995840Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 9 23:25:41.490016 containerd[1527]: time="2025-09-09T23:25:41.490007520Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 9 23:25:41.490083 containerd[1527]: time="2025-09-09T23:25:41.490024560Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 9 23:25:41.490083 containerd[1527]: time="2025-09-09T23:25:41.490037720Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 9 23:25:41.490083 containerd[1527]: time="2025-09-09T23:25:41.490049400Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 9 23:25:41.490083 containerd[1527]: time="2025-09-09T23:25:41.490059920Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 9 23:25:41.490083 containerd[1527]: time="2025-09-09T23:25:41.490069280Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 9 23:25:41.490083 containerd[1527]: time="2025-09-09T23:25:41.490078360Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 9 23:25:41.490213 containerd[1527]: time="2025-09-09T23:25:41.490089440Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 9 23:25:41.490231 containerd[1527]: time="2025-09-09T23:25:41.490208920Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 9 23:25:41.490249 containerd[1527]: time="2025-09-09T23:25:41.490231400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 9 23:25:41.490270 containerd[1527]: time="2025-09-09T23:25:41.490247400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 9 23:25:41.490270 containerd[1527]: time="2025-09-09T23:25:41.490263360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 9 23:25:41.490302 containerd[1527]: time="2025-09-09T23:25:41.490274960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 9 23:25:41.490302 containerd[1527]: time="2025-09-09T23:25:41.490288000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 9 23:25:41.490302 containerd[1527]: time="2025-09-09T23:25:41.490299640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 9 23:25:41.490353 containerd[1527]: time="2025-09-09T23:25:41.490310560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 9 23:25:41.490353 containerd[1527]: time="2025-09-09T23:25:41.490322840Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 9 23:25:41.490353 containerd[1527]: time="2025-09-09T23:25:41.490333240Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 9 23:25:41.490353 containerd[1527]: time="2025-09-09T23:25:41.490343120Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 9 23:25:41.490575 containerd[1527]: time="2025-09-09T23:25:41.490542840Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 9 23:25:41.490575 containerd[1527]: time="2025-09-09T23:25:41.490568120Z" level=info msg="Start snapshots syncer" Sep 9 23:25:41.490620 containerd[1527]: time="2025-09-09T23:25:41.490596080Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 9 23:25:41.490857 containerd[1527]: time="2025-09-09T23:25:41.490814480Z" 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\"}" Sep 9 23:25:41.490955 containerd[1527]: time="2025-09-09T23:25:41.490871120Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 9 23:25:41.490955 containerd[1527]: time="2025-09-09T23:25:41.490949160Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 9 23:25:41.491075 containerd[1527]: time="2025-09-09T23:25:41.491053920Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 9 23:25:41.491103 containerd[1527]: time="2025-09-09T23:25:41.491089400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 9 23:25:41.491122 containerd[1527]: time="2025-09-09T23:25:41.491106360Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 9 23:25:41.491122 containerd[1527]: time="2025-09-09T23:25:41.491119360Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 9 23:25:41.491159 containerd[1527]: time="2025-09-09T23:25:41.491131120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 9 23:25:41.491159 containerd[1527]: time="2025-09-09T23:25:41.491142080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 9 23:25:41.491159 containerd[1527]: time="2025-09-09T23:25:41.491152120Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 9 23:25:41.491206 containerd[1527]: time="2025-09-09T23:25:41.491176840Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 9 23:25:41.491206 containerd[1527]: time="2025-09-09T23:25:41.491192680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 9 23:25:41.491206 containerd[1527]: time="2025-09-09T23:25:41.491203040Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 9 23:25:41.491252 containerd[1527]: time="2025-09-09T23:25:41.491243360Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 23:25:41.491272 containerd[1527]: time="2025-09-09T23:25:41.491257440Z" 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 Sep 9 23:25:41.491272 containerd[1527]: time="2025-09-09T23:25:41.491267040Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 23:25:41.491305 containerd[1527]: time="2025-09-09T23:25:41.491276440Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 23:25:41.491305 containerd[1527]: time="2025-09-09T23:25:41.491284240Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 9 23:25:41.491305 containerd[1527]: time="2025-09-09T23:25:41.491293400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 9 23:25:41.491305 containerd[1527]: time="2025-09-09T23:25:41.491303520Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 9 23:25:41.491392 containerd[1527]: time="2025-09-09T23:25:41.491381280Z" level=info msg="runtime interface created" Sep 9 23:25:41.491392 containerd[1527]: time="2025-09-09T23:25:41.491390000Z" level=info msg="created NRI interface" Sep 9 23:25:41.491436 containerd[1527]: time="2025-09-09T23:25:41.491400320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 9 23:25:41.491436 containerd[1527]: time="2025-09-09T23:25:41.491411080Z" level=info msg="Connect containerd service" Sep 9 23:25:41.491475 containerd[1527]: time="2025-09-09T23:25:41.491452840Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 23:25:41.492173 containerd[1527]: time="2025-09-09T23:25:41.492143440Z" 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" Sep 9 23:25:41.569960 containerd[1527]: time="2025-09-09T23:25:41.569893680Z" level=info msg="Start subscribing containerd event" Sep 9 23:25:41.569960 containerd[1527]: time="2025-09-09T23:25:41.569964880Z" level=info msg="Start recovering state" Sep 9 23:25:41.570113 containerd[1527]: time="2025-09-09T23:25:41.570048320Z" level=info msg="Start event monitor" Sep 9 23:25:41.570113 containerd[1527]: time="2025-09-09T23:25:41.570062920Z" level=info msg="Start cni network conf syncer for default" Sep 9 23:25:41.570113 containerd[1527]: time="2025-09-09T23:25:41.570072120Z" level=info msg="Start streaming server" Sep 9 23:25:41.570113 containerd[1527]: time="2025-09-09T23:25:41.570081240Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 9 23:25:41.570113 containerd[1527]: time="2025-09-09T23:25:41.570088160Z" level=info msg="runtime interface starting up..." Sep 9 23:25:41.570113 containerd[1527]: time="2025-09-09T23:25:41.570094400Z" level=info msg="starting plugins..." Sep 9 23:25:41.570113 containerd[1527]: time="2025-09-09T23:25:41.570107800Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 9 23:25:41.571247 containerd[1527]: time="2025-09-09T23:25:41.570158320Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 23:25:41.571247 containerd[1527]: time="2025-09-09T23:25:41.570207480Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 23:25:41.571247 containerd[1527]: time="2025-09-09T23:25:41.570385120Z" level=info msg="containerd successfully booted in 0.094042s" Sep 9 23:25:41.570398 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 23:25:42.240177 sshd_keygen[1521]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 9 23:25:42.259440 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 9 23:25:42.264187 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 9 23:25:42.289838 systemd[1]: issuegen.service: Deactivated successfully. Sep 9 23:25:42.290035 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 9 23:25:42.293930 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 9 23:25:42.319272 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 9 23:25:42.321803 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 9 23:25:42.323577 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 9 23:25:42.324574 systemd[1]: Reached target getty.target - Login Prompts. Sep 9 23:25:42.426564 systemd-networkd[1431]: eth0: Gained IPv6LL Sep 9 23:25:42.428997 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 9 23:25:42.430414 systemd[1]: Reached target network-online.target - Network is Online. Sep 9 23:25:42.433137 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 9 23:25:42.435236 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 9 23:25:42.437139 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 9 23:25:42.461463 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 9 23:25:42.465914 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 9 23:25:42.466125 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 9 23:25:42.467898 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 9 23:25:42.990069 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 9 23:25:42.991525 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 9 23:25:42.997520 systemd[1]: Startup finished in 2.021s (kernel) + 4.934s (initrd) + 3.502s (userspace) = 10.458s. Sep 9 23:25:42.997839 (kubelet)[1631]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 9 23:25:43.343220 kubelet[1631]: E0909 23:25:43.343096 1631 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" Sep 9 23:25:43.345347 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 9 23:25:43.345522 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 9 23:25:43.346577 systemd[1]: kubelet.service: Consumed 746ms CPU time, 256.3M memory peak. Sep 9 23:25:46.883331 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 9 23:25:46.888756 systemd[1]: Started sshd@0-10.0.0.14:22-10.0.0.1:44610.service - OpenSSH per-connection server daemon (10.0.0.1:44610). Sep 9 23:25:46.981449 sshd[1644]: Accepted publickey for core from 10.0.0.1 port 44610 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:25:46.982619 sshd-session[1644]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:25:46.988657 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 9 23:25:46.989913 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 9 23:25:46.995284 systemd-logind[1508]: New session 1 of user core. Sep 9 23:25:47.014507 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 9 23:25:47.017727 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 9 23:25:47.034558 (systemd)[1648]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 9 23:25:47.037137 systemd-logind[1508]: New session c1 of user core. Sep 9 23:25:47.150573 systemd[1648]: Queued start job for default target default.target. Sep 9 23:25:47.159306 systemd[1648]: Created slice app.slice - User Application Slice. Sep 9 23:25:47.159336 systemd[1648]: Reached target paths.target - Paths. Sep 9 23:25:47.159372 systemd[1648]: Reached target timers.target - Timers. Sep 9 23:25:47.160587 systemd[1648]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 23:25:47.169055 systemd[1648]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 23:25:47.169114 systemd[1648]: Reached target sockets.target - Sockets. Sep 9 23:25:47.169149 systemd[1648]: Reached target basic.target - Basic System. Sep 9 23:25:47.169179 systemd[1648]: Reached target default.target - Main User Target. Sep 9 23:25:47.169204 systemd[1648]: Startup finished in 125ms. Sep 9 23:25:47.169571 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 9 23:25:47.170784 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 9 23:25:47.233531 systemd[1]: Started sshd@1-10.0.0.14:22-10.0.0.1:44620.service - OpenSSH per-connection server daemon (10.0.0.1:44620). Sep 9 23:25:47.286010 sshd[1659]: Accepted publickey for core from 10.0.0.1 port 44620 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:25:47.287301 sshd-session[1659]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:25:47.290977 systemd-logind[1508]: New session 2 of user core. Sep 9 23:25:47.302586 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 9 23:25:47.351768 sshd[1661]: Connection closed by 10.0.0.1 port 44620 Sep 9 23:25:47.352202 sshd-session[1659]: pam_unix(sshd:session): session closed for user core Sep 9 23:25:47.363591 systemd[1]: sshd@1-10.0.0.14:22-10.0.0.1:44620.service: Deactivated successfully. Sep 9 23:25:47.365835 systemd[1]: session-2.scope: Deactivated successfully. Sep 9 23:25:47.366494 systemd-logind[1508]: Session 2 logged out. Waiting for processes to exit. Sep 9 23:25:47.368625 systemd[1]: Started sshd@2-10.0.0.14:22-10.0.0.1:44626.service - OpenSSH per-connection server daemon (10.0.0.1:44626). Sep 9 23:25:47.369492 systemd-logind[1508]: Removed session 2. Sep 9 23:25:47.425793 sshd[1667]: Accepted publickey for core from 10.0.0.1 port 44626 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:25:47.427139 sshd-session[1667]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:25:47.431097 systemd-logind[1508]: New session 3 of user core. Sep 9 23:25:47.439585 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 9 23:25:47.488468 sshd[1669]: Connection closed by 10.0.0.1 port 44626 Sep 9 23:25:47.488594 sshd-session[1667]: pam_unix(sshd:session): session closed for user core Sep 9 23:25:47.499558 systemd[1]: sshd@2-10.0.0.14:22-10.0.0.1:44626.service: Deactivated successfully. Sep 9 23:25:47.501882 systemd[1]: session-3.scope: Deactivated successfully. Sep 9 23:25:47.502622 systemd-logind[1508]: Session 3 logged out. Waiting for processes to exit. Sep 9 23:25:47.505309 systemd[1]: Started sshd@3-10.0.0.14:22-10.0.0.1:44634.service - OpenSSH per-connection server daemon (10.0.0.1:44634). Sep 9 23:25:47.505958 systemd-logind[1508]: Removed session 3. Sep 9 23:25:47.559494 sshd[1675]: Accepted publickey for core from 10.0.0.1 port 44634 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:25:47.560748 sshd-session[1675]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:25:47.565675 systemd-logind[1508]: New session 4 of user core. Sep 9 23:25:47.584627 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 9 23:25:47.636357 sshd[1677]: Connection closed by 10.0.0.1 port 44634 Sep 9 23:25:47.636801 sshd-session[1675]: pam_unix(sshd:session): session closed for user core Sep 9 23:25:47.646519 systemd[1]: sshd@3-10.0.0.14:22-10.0.0.1:44634.service: Deactivated successfully. Sep 9 23:25:47.648084 systemd[1]: session-4.scope: Deactivated successfully. Sep 9 23:25:47.650005 systemd-logind[1508]: Session 4 logged out. Waiting for processes to exit. Sep 9 23:25:47.652394 systemd[1]: Started sshd@4-10.0.0.14:22-10.0.0.1:44636.service - OpenSSH per-connection server daemon (10.0.0.1:44636). Sep 9 23:25:47.653812 systemd-logind[1508]: Removed session 4. Sep 9 23:25:47.708641 sshd[1683]: Accepted publickey for core from 10.0.0.1 port 44636 ssh2: RSA SHA256:dVGL2zumnWizGzsOSYID+1qjGEdZrqRTZUf8FmvVils Sep 9 23:25:47.710025 sshd-session[1683]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 23:25:47.714726 systemd-logind[1508]: New session 5 of user core. Sep 9 23:25:47.725611 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 9 23:25:47.781926 sudo[1686]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 9 23:25:47.782187 sudo[1686]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 23:25:47.792629 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 9 23:25:47.850603 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 9 23:25:47.850849 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 9 23:25:48.303261 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 9 23:25:48.303767 systemd[1]: kubelet.service: Consumed 746ms CPU time, 256.3M memory peak. Sep 9 23:25:48.305906 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 9 23:25:48.331357 systemd[1]: Reload requested from client PID 1729 ('systemctl') (unit session-5.scope)... Sep 9 23:25:48.331373 systemd[1]: Reloading... Sep 9 23:25:48.407460 zram_generator::config[1773]: No configuration found. Sep 9 23:25:48.568574 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. Sep 9 23:25:48.657202 systemd[1]: Reloading finished in 325 ms. Sep 9 23:25:48.696561 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 9 23:25:48.698804 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Sep 9 23:25:48.700867 systemd[1]: kubelet.service: Deactivated successfully. Sep 9 23:25:48.701096 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 9 23:25:48.701141 systemd[1]: kubelet.service: Consumed 104ms CPU time, 95.1M memory peak. Sep 9 23:25:48.702644 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 9 23:25:48.817673 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 9 23:25:48.821618 (kubelet)[1817]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 9 23:25:48.869517 kubelet[1817]: 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. Sep 9 23:25:48.869517 kubelet[1817]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Sep 9 23:25:48.869517 kubelet[1817]: 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. Sep 9 23:25:48.869811 kubelet[1817]: I0909 23:25:48.869609 1817 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 9 23:25:49.702654 kubelet[1817]: I0909 23:25:49.702600 1817 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Sep 9 23:25:49.702654 kubelet[1817]: I0909 23:25:49.702634 1817 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 9 23:25:49.702916 kubelet[1817]: I0909 23:25:49.702890 1817 server.go:954] "Client rotation is on, will bootstrap in background" Sep 9 23:25:49.722912 kubelet[1817]: I0909 23:25:49.722881 1817 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 9 23:25:49.732671 kubelet[1817]: I0909 23:25:49.732641 1817 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Sep 9 23:25:49.735332 kubelet[1817]: I0909 23:25:49.735311 1817 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 9 23:25:49.736079 kubelet[1817]: I0909 23:25:49.736028 1817 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 9 23:25:49.736256 kubelet[1817]: I0909 23:25:49.736069 1817 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.14","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} Sep 9 23:25:49.736388 kubelet[1817]: I0909 23:25:49.736375 1817 topology_manager.go:138] "Creating topology manager with none policy" Sep 9 23:25:49.736388 kubelet[1817]: I0909 23:25:49.736387 1817 container_manager_linux.go:304] "Creating device plugin manager" Sep 9 23:25:49.736625 kubelet[1817]: I0909 23:25:49.736599 1817 state_mem.go:36] "Initialized new in-memory state store" Sep 9 23:25:49.738496 kubelet[1817]: I0909 23:25:49.738472 1817 kubelet.go:446] "Attempting to sync node with API server" Sep 9 23:25:49.738532 kubelet[1817]: I0909 23:25:49.738500 1817 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 9 23:25:49.738945 kubelet[1817]: I0909 23:25:49.738842 1817 kubelet.go:352] "Adding apiserver pod source" Sep 9 23:25:49.738945 kubelet[1817]: I0909 23:25:49.738860 1817 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 9 23:25:49.739293 kubelet[1817]: E0909 23:25:49.739251 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:49.740870 kubelet[1817]: E0909 23:25:49.740840 1817 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:49.743666 kubelet[1817]: I0909 23:25:49.743644 1817 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" Sep 9 23:25:49.744250 kubelet[1817]: I0909 23:25:49.744237 1817 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Sep 9 23:25:49.744358 kubelet[1817]: W0909 23:25:49.744346 1817 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 9 23:25:49.745164 kubelet[1817]: I0909 23:25:49.745150 1817 watchdog_linux.go:99] "Systemd watchdog is not enabled" Sep 9 23:25:49.745195 kubelet[1817]: I0909 23:25:49.745185 1817 server.go:1287] "Started kubelet" Sep 9 23:25:49.745299 kubelet[1817]: I0909 23:25:49.745270 1817 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Sep 9 23:25:49.746140 kubelet[1817]: I0909 23:25:49.746120 1817 server.go:479] "Adding debug handlers to kubelet server" Sep 9 23:25:49.747843 kubelet[1817]: I0909 23:25:49.747559 1817 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 9 23:25:49.747843 kubelet[1817]: I0909 23:25:49.747812 1817 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 9 23:25:49.749024 kubelet[1817]: I0909 23:25:49.748615 1817 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 9 23:25:49.749024 kubelet[1817]: I0909 23:25:49.748651 1817 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 9 23:25:49.749780 kubelet[1817]: E0909 23:25:49.749729 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:49.749780 kubelet[1817]: I0909 23:25:49.749770 1817 volume_manager.go:297] "Starting Kubelet Volume Manager" Sep 9 23:25:49.749864 kubelet[1817]: I0909 23:25:49.749831 1817 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Sep 9 23:25:49.749883 kubelet[1817]: I0909 23:25:49.749870 1817 reconciler.go:26] "Reconciler: start to sync state" Sep 9 23:25:49.751788 kubelet[1817]: W0909 23:25:49.750948 1817 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.14" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Sep 9 23:25:49.751788 kubelet[1817]: E0909 23:25:49.750984 1817 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.14\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Sep 9 23:25:49.751788 kubelet[1817]: W0909 23:25:49.751077 1817 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Sep 9 23:25:49.751788 kubelet[1817]: E0909 23:25:49.751091 1817 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Sep 9 23:25:49.752895 kubelet[1817]: I0909 23:25:49.752090 1817 factory.go:221] Registration of the systemd container factory successfully Sep 9 23:25:49.752895 kubelet[1817]: I0909 23:25:49.752196 1817 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 Sep 9 23:25:49.752895 kubelet[1817]: E0909 23:25:49.752412 1817 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 9 23:25:49.753139 kubelet[1817]: I0909 23:25:49.753119 1817 factory.go:221] Registration of the containerd container factory successfully Sep 9 23:25:49.766163 kubelet[1817]: I0909 23:25:49.766141 1817 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 9 23:25:49.766367 kubelet[1817]: I0909 23:25:49.766347 1817 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 9 23:25:49.766459 kubelet[1817]: I0909 23:25:49.766451 1817 state_mem.go:36] "Initialized new in-memory state store" Sep 9 23:25:49.766522 kubelet[1817]: E0909 23:25:49.766407 1817 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.14\" not found" node="10.0.0.14" Sep 9 23:25:49.850662 kubelet[1817]: E0909 23:25:49.850617 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:49.870153 kubelet[1817]: I0909 23:25:49.870129 1817 policy_none.go:49] "None policy: Start" Sep 9 23:25:49.870508 kubelet[1817]: I0909 23:25:49.870493 1817 memory_manager.go:186] "Starting memorymanager" policy="None" Sep 9 23:25:49.870578 kubelet[1817]: I0909 23:25:49.870570 1817 state_mem.go:35] "Initializing new in-memory state store" Sep 9 23:25:49.876256 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 9 23:25:49.890730 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 9 23:25:49.891360 kubelet[1817]: I0909 23:25:49.891330 1817 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 9 23:25:49.892850 kubelet[1817]: I0909 23:25:49.892577 1817 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 9 23:25:49.892850 kubelet[1817]: I0909 23:25:49.892612 1817 status_manager.go:227] "Starting to sync pod status with apiserver" Sep 9 23:25:49.892850 kubelet[1817]: I0909 23:25:49.892630 1817 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 9 23:25:49.892850 kubelet[1817]: I0909 23:25:49.892636 1817 kubelet.go:2382] "Starting kubelet main sync loop" Sep 9 23:25:49.892850 kubelet[1817]: E0909 23:25:49.892677 1817 kubelet.go:2406] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Sep 9 23:25:49.894249 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 9 23:25:49.903304 kubelet[1817]: I0909 23:25:49.903263 1817 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 9 23:25:49.903881 kubelet[1817]: I0909 23:25:49.903727 1817 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 9 23:25:49.903881 kubelet[1817]: I0909 23:25:49.903743 1817 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 9 23:25:49.904669 kubelet[1817]: I0909 23:25:49.904640 1817 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 9 23:25:49.905127 kubelet[1817]: E0909 23:25:49.905100 1817 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 9 23:25:49.905285 kubelet[1817]: E0909 23:25:49.905254 1817 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.14\" not found" Sep 9 23:25:50.005199 kubelet[1817]: I0909 23:25:50.005083 1817 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.14" Sep 9 23:25:50.011448 kubelet[1817]: I0909 23:25:50.011316 1817 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.14" Sep 9 23:25:50.011448 kubelet[1817]: E0909 23:25:50.011352 1817 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.14\": node \"10.0.0.14\" not found" Sep 9 23:25:50.027927 kubelet[1817]: E0909 23:25:50.027876 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:50.128350 kubelet[1817]: E0909 23:25:50.128301 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:50.228898 kubelet[1817]: E0909 23:25:50.228859 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:50.329492 kubelet[1817]: E0909 23:25:50.329383 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:50.342125 sudo[1686]: pam_unix(sudo:session): session closed for user root Sep 9 23:25:50.343451 sshd[1685]: Connection closed by 10.0.0.1 port 44636 Sep 9 23:25:50.343865 sshd-session[1683]: pam_unix(sshd:session): session closed for user core Sep 9 23:25:50.347522 systemd[1]: sshd@4-10.0.0.14:22-10.0.0.1:44636.service: Deactivated successfully. Sep 9 23:25:50.349206 systemd[1]: session-5.scope: Deactivated successfully. Sep 9 23:25:50.349375 systemd[1]: session-5.scope: Consumed 409ms CPU time, 75M memory peak. Sep 9 23:25:50.350281 systemd-logind[1508]: Session 5 logged out. Waiting for processes to exit. Sep 9 23:25:50.351529 systemd-logind[1508]: Removed session 5. Sep 9 23:25:50.429861 kubelet[1817]: E0909 23:25:50.429810 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:50.530379 kubelet[1817]: E0909 23:25:50.530325 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:50.630938 kubelet[1817]: E0909 23:25:50.630816 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:50.705319 kubelet[1817]: I0909 23:25:50.705273 1817 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 9 23:25:50.705496 kubelet[1817]: W0909 23:25:50.705476 1817 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 9 23:25:50.705564 kubelet[1817]: W0909 23:25:50.705534 1817 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 9 23:25:50.731620 kubelet[1817]: E0909 23:25:50.731570 1817 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.14\" not found" Sep 9 23:25:50.739710 kubelet[1817]: E0909 23:25:50.739673 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:50.832943 kubelet[1817]: I0909 23:25:50.832916 1817 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 9 23:25:50.833206 containerd[1527]: time="2025-09-09T23:25:50.833168238Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 9 23:25:50.833595 kubelet[1817]: I0909 23:25:50.833367 1817 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 9 23:25:51.740719 kubelet[1817]: I0909 23:25:51.740673 1817 apiserver.go:52] "Watching apiserver" Sep 9 23:25:51.740719 kubelet[1817]: E0909 23:25:51.740719 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:51.750405 kubelet[1817]: I0909 23:25:51.750375 1817 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Sep 9 23:25:51.751567 systemd[1]: Created slice kubepods-besteffort-podd9e298cd_98e7_4e4f_a7ae_4793a1404acd.slice - libcontainer container kubepods-besteffort-podd9e298cd_98e7_4e4f_a7ae_4793a1404acd.slice. Sep 9 23:25:51.759089 kubelet[1817]: I0909 23:25:51.759063 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/c94dc134-c8a9-4e6a-a829-643bd70094ca-run\") pod \"kube-flannel-ds-kchm5\" (UID: \"c94dc134-c8a9-4e6a-a829-643bd70094ca\") " pod="kube-flannel/kube-flannel-ds-kchm5" Sep 9 23:25:51.759089 kubelet[1817]: I0909 23:25:51.759094 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/c94dc134-c8a9-4e6a-a829-643bd70094ca-cni\") pod \"kube-flannel-ds-kchm5\" (UID: \"c94dc134-c8a9-4e6a-a829-643bd70094ca\") " pod="kube-flannel/kube-flannel-ds-kchm5" Sep 9 23:25:51.759189 kubelet[1817]: I0909 23:25:51.759111 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8g97v\" (UniqueName: \"kubernetes.io/projected/c94dc134-c8a9-4e6a-a829-643bd70094ca-kube-api-access-8g97v\") pod \"kube-flannel-ds-kchm5\" (UID: \"c94dc134-c8a9-4e6a-a829-643bd70094ca\") " pod="kube-flannel/kube-flannel-ds-kchm5" Sep 9 23:25:51.759189 kubelet[1817]: I0909 23:25:51.759136 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/d9e298cd-98e7-4e4f-a7ae-4793a1404acd-kube-proxy\") pod \"kube-proxy-tvsfz\" (UID: \"d9e298cd-98e7-4e4f-a7ae-4793a1404acd\") " pod="kube-system/kube-proxy-tvsfz" Sep 9 23:25:51.759189 kubelet[1817]: I0909 23:25:51.759153 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-tgkd9\" (UniqueName: \"kubernetes.io/projected/d9e298cd-98e7-4e4f-a7ae-4793a1404acd-kube-api-access-tgkd9\") pod \"kube-proxy-tvsfz\" (UID: \"d9e298cd-98e7-4e4f-a7ae-4793a1404acd\") " pod="kube-system/kube-proxy-tvsfz" Sep 9 23:25:51.759189 kubelet[1817]: I0909 23:25:51.759168 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/c94dc134-c8a9-4e6a-a829-643bd70094ca-cni-plugin\") pod \"kube-flannel-ds-kchm5\" (UID: \"c94dc134-c8a9-4e6a-a829-643bd70094ca\") " pod="kube-flannel/kube-flannel-ds-kchm5" Sep 9 23:25:51.759189 kubelet[1817]: I0909 23:25:51.759183 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/c94dc134-c8a9-4e6a-a829-643bd70094ca-flannel-cfg\") pod \"kube-flannel-ds-kchm5\" (UID: \"c94dc134-c8a9-4e6a-a829-643bd70094ca\") " pod="kube-flannel/kube-flannel-ds-kchm5" Sep 9 23:25:51.759291 kubelet[1817]: I0909 23:25:51.759198 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/c94dc134-c8a9-4e6a-a829-643bd70094ca-xtables-lock\") pod \"kube-flannel-ds-kchm5\" (UID: \"c94dc134-c8a9-4e6a-a829-643bd70094ca\") " pod="kube-flannel/kube-flannel-ds-kchm5" Sep 9 23:25:51.759291 kubelet[1817]: I0909 23:25:51.759212 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/d9e298cd-98e7-4e4f-a7ae-4793a1404acd-xtables-lock\") pod \"kube-proxy-tvsfz\" (UID: \"d9e298cd-98e7-4e4f-a7ae-4793a1404acd\") " pod="kube-system/kube-proxy-tvsfz" Sep 9 23:25:51.759291 kubelet[1817]: I0909 23:25:51.759242 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/d9e298cd-98e7-4e4f-a7ae-4793a1404acd-lib-modules\") pod \"kube-proxy-tvsfz\" (UID: \"d9e298cd-98e7-4e4f-a7ae-4793a1404acd\") " pod="kube-system/kube-proxy-tvsfz" Sep 9 23:25:51.769818 systemd[1]: Created slice kubepods-burstable-podc94dc134_c8a9_4e6a_a829_643bd70094ca.slice - libcontainer container kubepods-burstable-podc94dc134_c8a9_4e6a_a829_643bd70094ca.slice. Sep 9 23:25:52.068519 containerd[1527]: time="2025-09-09T23:25:52.068356753Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tvsfz,Uid:d9e298cd-98e7-4e4f-a7ae-4793a1404acd,Namespace:kube-system,Attempt:0,}" Sep 9 23:25:52.084825 containerd[1527]: time="2025-09-09T23:25:52.084787397Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-kchm5,Uid:c94dc134-c8a9-4e6a-a829-643bd70094ca,Namespace:kube-flannel,Attempt:0,}" Sep 9 23:25:52.541599 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2154062274.mount: Deactivated successfully. Sep 9 23:25:52.546722 containerd[1527]: time="2025-09-09T23:25:52.546669907Z" 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\"}" Sep 9 23:25:52.550319 containerd[1527]: time="2025-09-09T23:25:52.550248933Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Sep 9 23:25:52.551541 containerd[1527]: time="2025-09-09T23:25:52.551499206Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 9 23:25:52.552447 containerd[1527]: time="2025-09-09T23:25:52.552411010Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Sep 9 23:25:52.552742 containerd[1527]: time="2025-09-09T23:25:52.552703963Z" 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\"}" Sep 9 23:25:52.554813 containerd[1527]: time="2025-09-09T23:25:52.554785675Z" 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\"}" Sep 9 23:25:52.555533 containerd[1527]: time="2025-09-09T23:25:52.555509949Z" 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 467.354052ms" Sep 9 23:25:52.556967 containerd[1527]: time="2025-09-09T23:25:52.556722636Z" 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 481.3282ms" Sep 9 23:25:52.573990 containerd[1527]: time="2025-09-09T23:25:52.573950940Z" level=info msg="connecting to shim 7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac" address="unix:///run/containerd/s/a7aebf3179ecd24caa0cf3de9d41e32df43cd143dbf0ab31bb64ea9370b30642" namespace=k8s.io protocol=ttrpc version=3 Sep 9 23:25:52.574723 containerd[1527]: time="2025-09-09T23:25:52.574675254Z" level=info msg="connecting to shim d6b23b797fc1d546e1bcf604347ac9555838e9fe9725caf478c48b463282cb95" address="unix:///run/containerd/s/ebd4f6bfc65e5ea55134279615568dec94e7c70031e524e335b7d27923050ae3" namespace=k8s.io protocol=ttrpc version=3 Sep 9 23:25:52.599620 systemd[1]: Started cri-containerd-7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac.scope - libcontainer container 7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac. Sep 9 23:25:52.601068 systemd[1]: Started cri-containerd-d6b23b797fc1d546e1bcf604347ac9555838e9fe9725caf478c48b463282cb95.scope - libcontainer container d6b23b797fc1d546e1bcf604347ac9555838e9fe9725caf478c48b463282cb95. Sep 9 23:25:52.624885 containerd[1527]: time="2025-09-09T23:25:52.624845409Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tvsfz,Uid:d9e298cd-98e7-4e4f-a7ae-4793a1404acd,Namespace:kube-system,Attempt:0,} returns sandbox id \"d6b23b797fc1d546e1bcf604347ac9555838e9fe9725caf478c48b463282cb95\"" Sep 9 23:25:52.627844 containerd[1527]: time="2025-09-09T23:25:52.627814147Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.8\"" Sep 9 23:25:52.634318 containerd[1527]: time="2025-09-09T23:25:52.634284638Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-kchm5,Uid:c94dc134-c8a9-4e6a-a829-643bd70094ca,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac\"" Sep 9 23:25:52.741160 kubelet[1817]: E0909 23:25:52.741094 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:53.597243 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1309394921.mount: Deactivated successfully. Sep 9 23:25:53.741432 kubelet[1817]: E0909 23:25:53.741383 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:53.810545 containerd[1527]: time="2025-09-09T23:25:53.810487674Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:25:53.811099 containerd[1527]: time="2025-09-09T23:25:53.811073437Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.8: active requests=0, bytes read=27376726" Sep 9 23:25:53.811662 containerd[1527]: time="2025-09-09T23:25:53.811640903Z" level=info msg="ImageCreate event name:\"sha256:2cf30e39f99f8f4ee1a736a4f3175cc2d8d3f58936d8fa83ec5523658fdc7b8b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:25:53.813274 containerd[1527]: time="2025-09-09T23:25:53.813240063Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:adc1335b480ddd833aac3b0bd20f68ff0f3c3cf7a0bd337933b006d9f5cec40a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:25:53.813971 containerd[1527]: time="2025-09-09T23:25:53.813932397Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.8\" with image id \"sha256:2cf30e39f99f8f4ee1a736a4f3175cc2d8d3f58936d8fa83ec5523658fdc7b8b\", repo tag \"registry.k8s.io/kube-proxy:v1.32.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:adc1335b480ddd833aac3b0bd20f68ff0f3c3cf7a0bd337933b006d9f5cec40a\", size \"27375743\" in 1.185930354s" Sep 9 23:25:53.813971 containerd[1527]: time="2025-09-09T23:25:53.813966889Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.8\" returns image reference \"sha256:2cf30e39f99f8f4ee1a736a4f3175cc2d8d3f58936d8fa83ec5523658fdc7b8b\"" Sep 9 23:25:53.815007 containerd[1527]: time="2025-09-09T23:25:53.814957583Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 9 23:25:53.816632 containerd[1527]: time="2025-09-09T23:25:53.816120853Z" level=info msg="CreateContainer within sandbox \"d6b23b797fc1d546e1bcf604347ac9555838e9fe9725caf478c48b463282cb95\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 9 23:25:53.824372 containerd[1527]: time="2025-09-09T23:25:53.824343767Z" level=info msg="Container ff17aa2f84389312f67ff93e04dd6a9bed9c0d8f6d15db593cd5d4424078e53e: CDI devices from CRI Config.CDIDevices: []" Sep 9 23:25:53.830819 containerd[1527]: time="2025-09-09T23:25:53.830779146Z" level=info msg="CreateContainer within sandbox \"d6b23b797fc1d546e1bcf604347ac9555838e9fe9725caf478c48b463282cb95\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"ff17aa2f84389312f67ff93e04dd6a9bed9c0d8f6d15db593cd5d4424078e53e\"" Sep 9 23:25:53.831689 containerd[1527]: time="2025-09-09T23:25:53.831654576Z" level=info msg="StartContainer for \"ff17aa2f84389312f67ff93e04dd6a9bed9c0d8f6d15db593cd5d4424078e53e\"" Sep 9 23:25:53.833082 containerd[1527]: time="2025-09-09T23:25:53.833049207Z" level=info msg="connecting to shim ff17aa2f84389312f67ff93e04dd6a9bed9c0d8f6d15db593cd5d4424078e53e" address="unix:///run/containerd/s/ebd4f6bfc65e5ea55134279615568dec94e7c70031e524e335b7d27923050ae3" protocol=ttrpc version=3 Sep 9 23:25:53.857656 systemd[1]: Started cri-containerd-ff17aa2f84389312f67ff93e04dd6a9bed9c0d8f6d15db593cd5d4424078e53e.scope - libcontainer container ff17aa2f84389312f67ff93e04dd6a9bed9c0d8f6d15db593cd5d4424078e53e. Sep 9 23:25:53.892099 containerd[1527]: time="2025-09-09T23:25:53.892066272Z" level=info msg="StartContainer for \"ff17aa2f84389312f67ff93e04dd6a9bed9c0d8f6d15db593cd5d4424078e53e\" returns successfully" Sep 9 23:25:53.913709 kubelet[1817]: I0909 23:25:53.913638 1817 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-tvsfz" podStartSLOduration=3.726386292 podStartE2EDuration="4.913624927s" podCreationTimestamp="2025-09-09 23:25:49 +0000 UTC" firstStartedPulling="2025-09-09 23:25:52.627475045 +0000 UTC m=+3.798928652" lastFinishedPulling="2025-09-09 23:25:53.81471364 +0000 UTC m=+4.986167287" observedRunningTime="2025-09-09 23:25:53.913360424 +0000 UTC m=+5.084814150" watchObservedRunningTime="2025-09-09 23:25:53.913624927 +0000 UTC m=+5.085078573" Sep 9 23:25:54.742414 kubelet[1817]: E0909 23:25:54.742353 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:54.824752 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1743204434.mount: Deactivated successfully. Sep 9 23:25:54.871495 containerd[1527]: time="2025-09-09T23:25:54.871448975Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:25:54.871495 containerd[1527]: time="2025-09-09T23:25:54.871781752Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Sep 9 23:25:54.872723 containerd[1527]: time="2025-09-09T23:25:54.872693631Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:25:54.874719 containerd[1527]: time="2025-09-09T23:25:54.874693988Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:25:54.875434 containerd[1527]: time="2025-09-09T23:25:54.875297445Z" 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.060301473s" Sep 9 23:25:54.875434 containerd[1527]: time="2025-09-09T23:25:54.875331334Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Sep 9 23:25:54.877447 containerd[1527]: time="2025-09-09T23:25:54.877117429Z" level=info msg="CreateContainer within sandbox \"7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 9 23:25:54.882784 containerd[1527]: time="2025-09-09T23:25:54.882755093Z" level=info msg="Container 3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f: CDI devices from CRI Config.CDIDevices: []" Sep 9 23:25:54.887546 containerd[1527]: time="2025-09-09T23:25:54.887513575Z" level=info msg="CreateContainer within sandbox \"7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f\"" Sep 9 23:25:54.888065 containerd[1527]: time="2025-09-09T23:25:54.888039439Z" level=info msg="StartContainer for \"3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f\"" Sep 9 23:25:54.888794 containerd[1527]: time="2025-09-09T23:25:54.888769157Z" level=info msg="connecting to shim 3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f" address="unix:///run/containerd/s/a7aebf3179ecd24caa0cf3de9d41e32df43cd143dbf0ab31bb64ea9370b30642" protocol=ttrpc version=3 Sep 9 23:25:54.906585 systemd[1]: Started cri-containerd-3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f.scope - libcontainer container 3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f. Sep 9 23:25:54.930601 systemd[1]: cri-containerd-3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f.scope: Deactivated successfully. Sep 9 23:25:54.933138 containerd[1527]: time="2025-09-09T23:25:54.933095734Z" level=info msg="TaskExit event in podsandbox handler container_id:\"3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f\" id:\"3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f\" pid:2153 exited_at:{seconds:1757460354 nanos:932751753}" Sep 9 23:25:54.933221 containerd[1527]: time="2025-09-09T23:25:54.933194423Z" level=info msg="received exit event container_id:\"3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f\" id:\"3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f\" pid:2153 exited_at:{seconds:1757460354 nanos:932751753}" Sep 9 23:25:54.933461 containerd[1527]: time="2025-09-09T23:25:54.933418738Z" level=info msg="StartContainer for \"3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f\" returns successfully" Sep 9 23:25:54.948027 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3a9a45812b448919af71068fdccdcb471f6d626a03228301c0dfafd35f794f1f-rootfs.mount: Deactivated successfully. Sep 9 23:25:55.742510 kubelet[1817]: E0909 23:25:55.742472 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:55.911395 containerd[1527]: time="2025-09-09T23:25:55.911359131Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 9 23:25:56.743335 kubelet[1817]: E0909 23:25:56.743295 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:57.097213 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1912945652.mount: Deactivated successfully. Sep 9 23:25:57.657873 containerd[1527]: time="2025-09-09T23:25:57.657823801Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:25:57.658442 containerd[1527]: time="2025-09-09T23:25:57.658391580Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Sep 9 23:25:57.659152 containerd[1527]: time="2025-09-09T23:25:57.659128909Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:25:57.662077 containerd[1527]: time="2025-09-09T23:25:57.662038086Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:25:57.664003 containerd[1527]: time="2025-09-09T23:25:57.663945656Z" 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.752547465s" Sep 9 23:25:57.664003 containerd[1527]: time="2025-09-09T23:25:57.663979342Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Sep 9 23:25:57.665892 containerd[1527]: time="2025-09-09T23:25:57.665862822Z" level=info msg="CreateContainer within sandbox \"7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 9 23:25:57.674053 containerd[1527]: time="2025-09-09T23:25:57.673526986Z" level=info msg="Container 445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f: CDI devices from CRI Config.CDIDevices: []" Sep 9 23:25:57.679310 containerd[1527]: time="2025-09-09T23:25:57.679275935Z" level=info msg="CreateContainer within sandbox \"7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f\"" Sep 9 23:25:57.679861 containerd[1527]: time="2025-09-09T23:25:57.679832446Z" level=info msg="StartContainer for \"445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f\"" Sep 9 23:25:57.680669 containerd[1527]: time="2025-09-09T23:25:57.680646545Z" level=info msg="connecting to shim 445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f" address="unix:///run/containerd/s/a7aebf3179ecd24caa0cf3de9d41e32df43cd143dbf0ab31bb64ea9370b30642" protocol=ttrpc version=3 Sep 9 23:25:57.708595 systemd[1]: Started cri-containerd-445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f.scope - libcontainer container 445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f. Sep 9 23:25:57.730343 systemd[1]: cri-containerd-445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f.scope: Deactivated successfully. Sep 9 23:25:57.731034 containerd[1527]: time="2025-09-09T23:25:57.731002702Z" level=info msg="TaskExit event in podsandbox handler container_id:\"445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f\" id:\"445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f\" pid:2227 exited_at:{seconds:1757460357 nanos:730726915}" Sep 9 23:25:57.732099 containerd[1527]: time="2025-09-09T23:25:57.732071564Z" level=info msg="received exit event container_id:\"445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f\" id:\"445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f\" pid:2227 exited_at:{seconds:1757460357 nanos:730726915}" Sep 9 23:25:57.733004 containerd[1527]: time="2025-09-09T23:25:57.732978083Z" level=info msg="StartContainer for \"445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f\" returns successfully" Sep 9 23:25:57.744326 kubelet[1817]: E0909 23:25:57.744282 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:57.747710 kubelet[1817]: I0909 23:25:57.747687 1817 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Sep 9 23:25:57.916836 containerd[1527]: time="2025-09-09T23:25:57.916258500Z" level=info msg="CreateContainer within sandbox \"7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 9 23:25:57.922105 containerd[1527]: time="2025-09-09T23:25:57.922046233Z" level=info msg="Container 5a38a7dcbeebe208d9381d9879a57099bfc53e84ec9951405dde230b224aea4d: CDI devices from CRI Config.CDIDevices: []" Sep 9 23:25:57.927816 containerd[1527]: time="2025-09-09T23:25:57.927658126Z" level=info msg="CreateContainer within sandbox \"7264b7875307b1743fe74531218503e559a60d859f407165bc9fce6742eb93ac\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"5a38a7dcbeebe208d9381d9879a57099bfc53e84ec9951405dde230b224aea4d\"" Sep 9 23:25:57.928341 containerd[1527]: time="2025-09-09T23:25:57.928309605Z" level=info msg="StartContainer for \"5a38a7dcbeebe208d9381d9879a57099bfc53e84ec9951405dde230b224aea4d\"" Sep 9 23:25:57.929088 containerd[1527]: time="2025-09-09T23:25:57.929066604Z" level=info msg="connecting to shim 5a38a7dcbeebe208d9381d9879a57099bfc53e84ec9951405dde230b224aea4d" address="unix:///run/containerd/s/a7aebf3179ecd24caa0cf3de9d41e32df43cd143dbf0ab31bb64ea9370b30642" protocol=ttrpc version=3 Sep 9 23:25:57.945568 systemd[1]: Started cri-containerd-5a38a7dcbeebe208d9381d9879a57099bfc53e84ec9951405dde230b224aea4d.scope - libcontainer container 5a38a7dcbeebe208d9381d9879a57099bfc53e84ec9951405dde230b224aea4d. Sep 9 23:25:57.970288 containerd[1527]: time="2025-09-09T23:25:57.970244518Z" level=info msg="StartContainer for \"5a38a7dcbeebe208d9381d9879a57099bfc53e84ec9951405dde230b224aea4d\" returns successfully" Sep 9 23:25:58.001266 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-445343fbe8bd0e0321423cf1685660f3a3bdf64542e35e0d1c7b7dd5be75338f-rootfs.mount: Deactivated successfully. Sep 9 23:25:58.745471 kubelet[1817]: E0909 23:25:58.745395 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:25:58.930756 kubelet[1817]: I0909 23:25:58.930534 1817 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-kchm5" podStartSLOduration=4.901182329 podStartE2EDuration="9.930514751s" podCreationTimestamp="2025-09-09 23:25:49 +0000 UTC" firstStartedPulling="2025-09-09 23:25:52.635338776 +0000 UTC m=+3.806792423" lastFinishedPulling="2025-09-09 23:25:57.664671198 +0000 UTC m=+8.836124845" observedRunningTime="2025-09-09 23:25:58.929958283 +0000 UTC m=+10.101411930" watchObservedRunningTime="2025-09-09 23:25:58.930514751 +0000 UTC m=+10.101968358" Sep 9 23:25:59.029074 systemd-networkd[1431]: flannel.1: Link UP Sep 9 23:25:59.029080 systemd-networkd[1431]: flannel.1: Gained carrier Sep 9 23:25:59.746203 kubelet[1817]: E0909 23:25:59.746153 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:00.602586 systemd-networkd[1431]: flannel.1: Gained IPv6LL Sep 9 23:26:00.746730 kubelet[1817]: E0909 23:26:00.746691 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:01.126776 systemd[1]: Created slice kubepods-besteffort-pod807df20f_e19a_4581_b790_8f2167e6afd2.slice - libcontainer container kubepods-besteffort-pod807df20f_e19a_4581_b790_8f2167e6afd2.slice. Sep 9 23:26:01.214175 kubelet[1817]: I0909 23:26:01.214128 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6rgvf\" (UniqueName: \"kubernetes.io/projected/807df20f-e19a-4581-b790-8f2167e6afd2-kube-api-access-6rgvf\") pod \"nginx-deployment-7fcdb87857-lmrf8\" (UID: \"807df20f-e19a-4581-b790-8f2167e6afd2\") " pod="default/nginx-deployment-7fcdb87857-lmrf8" Sep 9 23:26:01.429737 containerd[1527]: time="2025-09-09T23:26:01.429627053Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-lmrf8,Uid:807df20f-e19a-4581-b790-8f2167e6afd2,Namespace:default,Attempt:0,}" Sep 9 23:26:01.440505 systemd-networkd[1431]: cni0: Link UP Sep 9 23:26:01.440510 systemd-networkd[1431]: cni0: Gained carrier Sep 9 23:26:01.441063 systemd-networkd[1431]: cni0: Lost carrier Sep 9 23:26:01.448947 kernel: cni0: port 1(veth5dc66678) entered blocking state Sep 9 23:26:01.449026 kernel: cni0: port 1(veth5dc66678) entered disabled state Sep 9 23:26:01.448688 systemd-networkd[1431]: veth5dc66678: Link UP Sep 9 23:26:01.449590 kernel: veth5dc66678: entered allmulticast mode Sep 9 23:26:01.450448 kernel: veth5dc66678: entered promiscuous mode Sep 9 23:26:01.456915 kernel: cni0: port 1(veth5dc66678) entered blocking state Sep 9 23:26:01.456960 kernel: cni0: port 1(veth5dc66678) entered forwarding state Sep 9 23:26:01.456989 systemd-networkd[1431]: veth5dc66678: Gained carrier Sep 9 23:26:01.457182 systemd-networkd[1431]: cni0: Gained carrier Sep 9 23:26:01.459945 containerd[1527]: 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)(0x4000018938), "name":"cbr0", "type":"bridge"} Sep 9 23:26:01.459945 containerd[1527]: delegateAdd: netconf sent to delegate plugin: Sep 9 23:26:01.483332 containerd[1527]: {"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-09-09T23:26:01.483290622Z" level=info msg="connecting to shim f99dfa793468753cb9b48015390cefe443abf3b9f9ef41d14d632aa2a61811b2" address="unix:///run/containerd/s/4512e75904afd6b9967518308083e9f0e4b6d9ad46859553bbfe068c5ac0c681" namespace=k8s.io protocol=ttrpc version=3 Sep 9 23:26:01.499566 systemd[1]: Started cri-containerd-f99dfa793468753cb9b48015390cefe443abf3b9f9ef41d14d632aa2a61811b2.scope - libcontainer container f99dfa793468753cb9b48015390cefe443abf3b9f9ef41d14d632aa2a61811b2. Sep 9 23:26:01.509714 systemd-resolved[1355]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 23:26:01.526446 containerd[1527]: time="2025-09-09T23:26:01.526368263Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-lmrf8,Uid:807df20f-e19a-4581-b790-8f2167e6afd2,Namespace:default,Attempt:0,} returns sandbox id \"f99dfa793468753cb9b48015390cefe443abf3b9f9ef41d14d632aa2a61811b2\"" Sep 9 23:26:01.528025 containerd[1527]: time="2025-09-09T23:26:01.527978501Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 9 23:26:01.747625 kubelet[1817]: E0909 23:26:01.747421 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:02.747852 kubelet[1817]: E0909 23:26:02.747710 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:03.098626 systemd-networkd[1431]: veth5dc66678: Gained IPv6LL Sep 9 23:26:03.258395 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3598251751.mount: Deactivated successfully. Sep 9 23:26:03.354539 systemd-networkd[1431]: cni0: Gained IPv6LL Sep 9 23:26:03.748610 kubelet[1817]: E0909 23:26:03.748345 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:04.002498 containerd[1527]: time="2025-09-09T23:26:04.002198525Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:26:04.003324 containerd[1527]: time="2025-09-09T23:26:04.003118767Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69986522" Sep 9 23:26:04.004048 containerd[1527]: time="2025-09-09T23:26:04.004013734Z" level=info msg="ImageCreate event name:\"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:26:04.006443 containerd[1527]: time="2025-09-09T23:26:04.006390503Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:26:04.007983 containerd[1527]: time="2025-09-09T23:26:04.007955877Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 2.479916931s" Sep 9 23:26:04.008038 containerd[1527]: time="2025-09-09T23:26:04.007988179Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 9 23:26:04.009937 containerd[1527]: time="2025-09-09T23:26:04.009902292Z" level=info msg="CreateContainer within sandbox \"f99dfa793468753cb9b48015390cefe443abf3b9f9ef41d14d632aa2a61811b2\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 9 23:26:04.015501 containerd[1527]: time="2025-09-09T23:26:04.015473256Z" level=info msg="Container aa071bdb34f99a5fea41e9a9697e9623a7083aed21f6045cf0986f521405cdfd: CDI devices from CRI Config.CDIDevices: []" Sep 9 23:26:04.020443 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1556800191.mount: Deactivated successfully. Sep 9 23:26:04.021670 containerd[1527]: time="2025-09-09T23:26:04.021117649Z" level=info msg="CreateContainer within sandbox \"f99dfa793468753cb9b48015390cefe443abf3b9f9ef41d14d632aa2a61811b2\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"aa071bdb34f99a5fea41e9a9697e9623a7083aed21f6045cf0986f521405cdfd\"" Sep 9 23:26:04.021801 containerd[1527]: time="2025-09-09T23:26:04.021770207Z" level=info msg="StartContainer for \"aa071bdb34f99a5fea41e9a9697e9623a7083aed21f6045cf0986f521405cdfd\"" Sep 9 23:26:04.022612 containerd[1527]: time="2025-09-09T23:26:04.022577810Z" level=info msg="connecting to shim aa071bdb34f99a5fea41e9a9697e9623a7083aed21f6045cf0986f521405cdfd" address="unix:///run/containerd/s/4512e75904afd6b9967518308083e9f0e4b6d9ad46859553bbfe068c5ac0c681" protocol=ttrpc version=3 Sep 9 23:26:04.045944 systemd[1]: Started cri-containerd-aa071bdb34f99a5fea41e9a9697e9623a7083aed21f6045cf0986f521405cdfd.scope - libcontainer container aa071bdb34f99a5fea41e9a9697e9623a7083aed21f6045cf0986f521405cdfd. Sep 9 23:26:04.072644 containerd[1527]: time="2025-09-09T23:26:04.072536882Z" level=info msg="StartContainer for \"aa071bdb34f99a5fea41e9a9697e9623a7083aed21f6045cf0986f521405cdfd\" returns successfully" Sep 9 23:26:04.749027 kubelet[1817]: E0909 23:26:04.748961 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:05.750014 kubelet[1817]: E0909 23:26:05.749955 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:06.750112 kubelet[1817]: E0909 23:26:06.750066 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:07.446451 kubelet[1817]: I0909 23:26:07.446325 1817 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-lmrf8" podStartSLOduration=3.964895328 podStartE2EDuration="6.446309663s" podCreationTimestamp="2025-09-09 23:26:01 +0000 UTC" firstStartedPulling="2025-09-09 23:26:01.527293004 +0000 UTC m=+12.698746651" lastFinishedPulling="2025-09-09 23:26:04.008707339 +0000 UTC m=+15.180160986" observedRunningTime="2025-09-09 23:26:04.93718233 +0000 UTC m=+16.108635977" watchObservedRunningTime="2025-09-09 23:26:07.446309663 +0000 UTC m=+18.617763310" Sep 9 23:26:07.452077 systemd[1]: Created slice kubepods-besteffort-pod2499e5ab_518c_4fff_8e74_058e01799d2a.slice - libcontainer container kubepods-besteffort-pod2499e5ab_518c_4fff_8e74_058e01799d2a.slice. Sep 9 23:26:07.546095 kubelet[1817]: I0909 23:26:07.546011 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/2499e5ab-518c-4fff-8e74-058e01799d2a-data\") pod \"nfs-server-provisioner-0\" (UID: \"2499e5ab-518c-4fff-8e74-058e01799d2a\") " pod="default/nfs-server-provisioner-0" Sep 9 23:26:07.546095 kubelet[1817]: I0909 23:26:07.546059 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-sjgmm\" (UniqueName: \"kubernetes.io/projected/2499e5ab-518c-4fff-8e74-058e01799d2a-kube-api-access-sjgmm\") pod \"nfs-server-provisioner-0\" (UID: \"2499e5ab-518c-4fff-8e74-058e01799d2a\") " pod="default/nfs-server-provisioner-0" Sep 9 23:26:07.751048 kubelet[1817]: E0909 23:26:07.750922 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:07.754740 containerd[1527]: time="2025-09-09T23:26:07.754678401Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:2499e5ab-518c-4fff-8e74-058e01799d2a,Namespace:default,Attempt:0,}" Sep 9 23:26:07.766334 systemd-networkd[1431]: vethe4947b29: Link UP Sep 9 23:26:07.768664 kernel: cni0: port 2(vethe4947b29) entered blocking state Sep 9 23:26:07.768717 kernel: cni0: port 2(vethe4947b29) entered disabled state Sep 9 23:26:07.768734 kernel: vethe4947b29: entered allmulticast mode Sep 9 23:26:07.769696 kernel: vethe4947b29: entered promiscuous mode Sep 9 23:26:07.777652 kernel: cni0: port 2(vethe4947b29) entered blocking state Sep 9 23:26:07.777717 kernel: cni0: port 2(vethe4947b29) entered forwarding state Sep 9 23:26:07.777955 systemd-networkd[1431]: vethe4947b29: Gained carrier Sep 9 23:26:07.780046 containerd[1527]: 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)(0x400011e8e8), "name":"cbr0", "type":"bridge"} Sep 9 23:26:07.780046 containerd[1527]: delegateAdd: netconf sent to delegate plugin: Sep 9 23:26:07.799954 containerd[1527]: {"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-09-09T23:26:07.799894833Z" level=info msg="connecting to shim 12db1c3718bfc07011919f141f6a5b4ab39706da35b42bbf753aaca80c6275c1" address="unix:///run/containerd/s/3da1461bfa9ec98805cf08210c434bcf68f249b8ed43a21e7466c9ced0827c11" namespace=k8s.io protocol=ttrpc version=3 Sep 9 23:26:07.827652 systemd[1]: Started cri-containerd-12db1c3718bfc07011919f141f6a5b4ab39706da35b42bbf753aaca80c6275c1.scope - libcontainer container 12db1c3718bfc07011919f141f6a5b4ab39706da35b42bbf753aaca80c6275c1. Sep 9 23:26:07.838149 systemd-resolved[1355]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 23:26:07.858806 containerd[1527]: time="2025-09-09T23:26:07.858766113Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:2499e5ab-518c-4fff-8e74-058e01799d2a,Namespace:default,Attempt:0,} returns sandbox id \"12db1c3718bfc07011919f141f6a5b4ab39706da35b42bbf753aaca80c6275c1\"" Sep 9 23:26:07.860594 containerd[1527]: time="2025-09-09T23:26:07.860548788Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 9 23:26:08.752007 kubelet[1817]: E0909 23:26:08.751942 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:09.114604 systemd-networkd[1431]: vethe4947b29: Gained IPv6LL Sep 9 23:26:09.739654 kubelet[1817]: E0909 23:26:09.739616 1817 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:09.752321 kubelet[1817]: E0909 23:26:09.752270 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:09.852748 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2250478626.mount: Deactivated successfully. Sep 9 23:26:10.753464 kubelet[1817]: E0909 23:26:10.753403 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:11.118450 containerd[1527]: time="2025-09-09T23:26:11.118305446Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:26:11.118975 containerd[1527]: time="2025-09-09T23:26:11.118779275Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Sep 9 23:26:11.119719 containerd[1527]: time="2025-09-09T23:26:11.119674290Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:26:11.121947 containerd[1527]: time="2025-09-09T23:26:11.121913167Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:26:11.122951 containerd[1527]: time="2025-09-09T23:26:11.122926659Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.262329484s" Sep 9 23:26:11.122996 containerd[1527]: time="2025-09-09T23:26:11.122957118Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 9 23:26:11.124936 containerd[1527]: time="2025-09-09T23:26:11.124908716Z" level=info msg="CreateContainer within sandbox \"12db1c3718bfc07011919f141f6a5b4ab39706da35b42bbf753aaca80c6275c1\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 9 23:26:11.131447 containerd[1527]: time="2025-09-09T23:26:11.129895434Z" level=info msg="Container ed604ee9e3f1e8fe3a9a7f5899e1c6b80da91b89d816db57f390899b9e566f6c: CDI devices from CRI Config.CDIDevices: []" Sep 9 23:26:11.137010 containerd[1527]: time="2025-09-09T23:26:11.136967975Z" level=info msg="CreateContainer within sandbox \"12db1c3718bfc07011919f141f6a5b4ab39706da35b42bbf753aaca80c6275c1\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"ed604ee9e3f1e8fe3a9a7f5899e1c6b80da91b89d816db57f390899b9e566f6c\"" Sep 9 23:26:11.137430 containerd[1527]: time="2025-09-09T23:26:11.137396636Z" level=info msg="StartContainer for \"ed604ee9e3f1e8fe3a9a7f5899e1c6b80da91b89d816db57f390899b9e566f6c\"" Sep 9 23:26:11.138219 containerd[1527]: time="2025-09-09T23:26:11.138191801Z" level=info msg="connecting to shim ed604ee9e3f1e8fe3a9a7f5899e1c6b80da91b89d816db57f390899b9e566f6c" address="unix:///run/containerd/s/3da1461bfa9ec98805cf08210c434bcf68f249b8ed43a21e7466c9ced0827c11" protocol=ttrpc version=3 Sep 9 23:26:11.160585 systemd[1]: Started cri-containerd-ed604ee9e3f1e8fe3a9a7f5899e1c6b80da91b89d816db57f390899b9e566f6c.scope - libcontainer container ed604ee9e3f1e8fe3a9a7f5899e1c6b80da91b89d816db57f390899b9e566f6c. Sep 9 23:26:11.186128 containerd[1527]: time="2025-09-09T23:26:11.186095592Z" level=info msg="StartContainer for \"ed604ee9e3f1e8fe3a9a7f5899e1c6b80da91b89d816db57f390899b9e566f6c\" returns successfully" Sep 9 23:26:11.754519 kubelet[1817]: E0909 23:26:11.754474 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:12.755289 kubelet[1817]: E0909 23:26:12.755246 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:13.756260 kubelet[1817]: E0909 23:26:13.756195 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:14.756413 kubelet[1817]: E0909 23:26:14.756361 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:15.757472 kubelet[1817]: E0909 23:26:15.757405 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:16.757983 kubelet[1817]: E0909 23:26:16.757932 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:17.758258 kubelet[1817]: E0909 23:26:17.758206 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:18.759147 kubelet[1817]: E0909 23:26:18.759072 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:19.759954 kubelet[1817]: E0909 23:26:19.759912 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:20.626841 kubelet[1817]: I0909 23:26:20.626608 1817 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.362986906 podStartE2EDuration="13.626591275s" podCreationTimestamp="2025-09-09 23:26:07 +0000 UTC" firstStartedPulling="2025-09-09 23:26:07.860050262 +0000 UTC m=+19.031503909" lastFinishedPulling="2025-09-09 23:26:11.123654631 +0000 UTC m=+22.295108278" observedRunningTime="2025-09-09 23:26:11.959310578 +0000 UTC m=+23.130764225" watchObservedRunningTime="2025-09-09 23:26:20.626591275 +0000 UTC m=+31.798044922" Sep 9 23:26:20.632004 systemd[1]: Created slice kubepods-besteffort-pode12563bb_14c3_4bae_9c93_785fc6c14750.slice - libcontainer container kubepods-besteffort-pode12563bb_14c3_4bae_9c93_785fc6c14750.slice. Sep 9 23:26:20.723756 kubelet[1817]: I0909 23:26:20.723720 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-d667f8db-4f3b-4237-a0cd-419db1b685f2\" (UniqueName: \"kubernetes.io/nfs/e12563bb-14c3-4bae-9c93-785fc6c14750-pvc-d667f8db-4f3b-4237-a0cd-419db1b685f2\") pod \"test-pod-1\" (UID: \"e12563bb-14c3-4bae-9c93-785fc6c14750\") " pod="default/test-pod-1" Sep 9 23:26:20.723756 kubelet[1817]: I0909 23:26:20.723760 1817 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vk5gw\" (UniqueName: \"kubernetes.io/projected/e12563bb-14c3-4bae-9c93-785fc6c14750-kube-api-access-vk5gw\") pod \"test-pod-1\" (UID: \"e12563bb-14c3-4bae-9c93-785fc6c14750\") " pod="default/test-pod-1" Sep 9 23:26:20.760780 kubelet[1817]: E0909 23:26:20.760717 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:20.845517 kernel: netfs: FS-Cache loaded Sep 9 23:26:20.871510 kernel: RPC: Registered named UNIX socket transport module. Sep 9 23:26:20.871601 kernel: RPC: Registered udp transport module. Sep 9 23:26:20.871618 kernel: RPC: Registered tcp transport module. Sep 9 23:26:20.871633 kernel: RPC: Registered tcp-with-tls transport module. Sep 9 23:26:20.872667 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 9 23:26:21.049565 kernel: NFS: Registering the id_resolver key type Sep 9 23:26:21.049666 kernel: Key type id_resolver registered Sep 9 23:26:21.049683 kernel: Key type id_legacy registered Sep 9 23:26:21.065994 nfsidmap[2830]: 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 Sep 9 23:26:21.066536 nfsidmap[2830]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 9 23:26:21.067754 nfsidmap[2831]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Sep 9 23:26:21.067887 nfsidmap[2831]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 9 23:26:21.073889 nfsrahead[2833]: setting /var/lib/kubelet/pods/e12563bb-14c3-4bae-9c93-785fc6c14750/volumes/kubernetes.io~nfs/pvc-d667f8db-4f3b-4237-a0cd-419db1b685f2 readahead to 128 Sep 9 23:26:21.234233 containerd[1527]: time="2025-09-09T23:26:21.234191757Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e12563bb-14c3-4bae-9c93-785fc6c14750,Namespace:default,Attempt:0,}" Sep 9 23:26:21.244506 systemd-networkd[1431]: veth5c1d0fb1: Link UP Sep 9 23:26:21.248882 kernel: cni0: port 3(veth5c1d0fb1) entered blocking state Sep 9 23:26:21.248957 kernel: cni0: port 3(veth5c1d0fb1) entered disabled state Sep 9 23:26:21.248975 kernel: veth5c1d0fb1: entered allmulticast mode Sep 9 23:26:21.249897 kernel: veth5c1d0fb1: entered promiscuous mode Sep 9 23:26:21.255340 systemd-networkd[1431]: veth5c1d0fb1: Gained carrier Sep 9 23:26:21.255651 kernel: cni0: port 3(veth5c1d0fb1) entered blocking state Sep 9 23:26:21.255688 kernel: cni0: port 3(veth5c1d0fb1) entered forwarding state Sep 9 23:26:21.257051 containerd[1527]: 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)(0x4000018938), "name":"cbr0", "type":"bridge"} Sep 9 23:26:21.257051 containerd[1527]: delegateAdd: netconf sent to delegate plugin: Sep 9 23:26:21.278479 containerd[1527]: {"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-09-09T23:26:21.278364183Z" level=info msg="connecting to shim 99a2ec008c5873be3fe2464e7b22a6da1488e3d8cdb09695050505be287eab48" address="unix:///run/containerd/s/9c62c41319474d09e3242b3edd9d58380b5df85c809a0328d331961a3f1d004f" namespace=k8s.io protocol=ttrpc version=3 Sep 9 23:26:21.308619 systemd[1]: Started cri-containerd-99a2ec008c5873be3fe2464e7b22a6da1488e3d8cdb09695050505be287eab48.scope - libcontainer container 99a2ec008c5873be3fe2464e7b22a6da1488e3d8cdb09695050505be287eab48. Sep 9 23:26:21.318896 systemd-resolved[1355]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 23:26:21.338195 containerd[1527]: time="2025-09-09T23:26:21.338136346Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:e12563bb-14c3-4bae-9c93-785fc6c14750,Namespace:default,Attempt:0,} returns sandbox id \"99a2ec008c5873be3fe2464e7b22a6da1488e3d8cdb09695050505be287eab48\"" Sep 9 23:26:21.339313 containerd[1527]: time="2025-09-09T23:26:21.339289770Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 9 23:26:21.581156 containerd[1527]: time="2025-09-09T23:26:21.581026208Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 23:26:21.582163 containerd[1527]: time="2025-09-09T23:26:21.582133429Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 9 23:26:21.584686 containerd[1527]: time="2025-09-09T23:26:21.584649407Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 245.188388ms" Sep 9 23:26:21.584686 containerd[1527]: time="2025-09-09T23:26:21.584683329Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 9 23:26:21.587201 containerd[1527]: time="2025-09-09T23:26:21.586815326Z" level=info msg="CreateContainer within sandbox \"99a2ec008c5873be3fe2464e7b22a6da1488e3d8cdb09695050505be287eab48\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 9 23:26:21.593134 containerd[1527]: time="2025-09-09T23:26:21.593085951Z" level=info msg="Container 6c3dc2d43b3999756ab4b0a24d13572b1df2b73a774dadbc24186cffca37bc83: CDI devices from CRI Config.CDIDevices: []" Sep 9 23:26:21.598832 containerd[1527]: time="2025-09-09T23:26:21.598783744Z" level=info msg="CreateContainer within sandbox \"99a2ec008c5873be3fe2464e7b22a6da1488e3d8cdb09695050505be287eab48\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"6c3dc2d43b3999756ab4b0a24d13572b1df2b73a774dadbc24186cffca37bc83\"" Sep 9 23:26:21.599226 containerd[1527]: time="2025-09-09T23:26:21.599207887Z" level=info msg="StartContainer for \"6c3dc2d43b3999756ab4b0a24d13572b1df2b73a774dadbc24186cffca37bc83\"" Sep 9 23:26:21.600087 containerd[1527]: time="2025-09-09T23:26:21.600060614Z" level=info msg="connecting to shim 6c3dc2d43b3999756ab4b0a24d13572b1df2b73a774dadbc24186cffca37bc83" address="unix:///run/containerd/s/9c62c41319474d09e3242b3edd9d58380b5df85c809a0328d331961a3f1d004f" protocol=ttrpc version=3 Sep 9 23:26:21.621622 systemd[1]: Started cri-containerd-6c3dc2d43b3999756ab4b0a24d13572b1df2b73a774dadbc24186cffca37bc83.scope - libcontainer container 6c3dc2d43b3999756ab4b0a24d13572b1df2b73a774dadbc24186cffca37bc83. Sep 9 23:26:21.648582 containerd[1527]: time="2025-09-09T23:26:21.648505755Z" level=info msg="StartContainer for \"6c3dc2d43b3999756ab4b0a24d13572b1df2b73a774dadbc24186cffca37bc83\" returns successfully" Sep 9 23:26:21.761646 kubelet[1817]: E0909 23:26:21.761573 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:21.985665 kubelet[1817]: I0909 23:26:21.985599 1817 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=14.739249779 podStartE2EDuration="14.98558319s" podCreationTimestamp="2025-09-09 23:26:07 +0000 UTC" firstStartedPulling="2025-09-09 23:26:21.339051757 +0000 UTC m=+32.510505404" lastFinishedPulling="2025-09-09 23:26:21.585385168 +0000 UTC m=+32.756838815" observedRunningTime="2025-09-09 23:26:21.985438702 +0000 UTC m=+33.156892349" watchObservedRunningTime="2025-09-09 23:26:21.98558319 +0000 UTC m=+33.157036837" Sep 9 23:26:22.618569 systemd-networkd[1431]: veth5c1d0fb1: Gained IPv6LL Sep 9 23:26:22.762291 kubelet[1817]: E0909 23:26:22.762243 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:23.763166 kubelet[1817]: E0909 23:26:23.763096 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 23:26:24.766606 kubelet[1817]: E0909 23:26:24.764089 1817 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"