Aug 13 00:27:36.905175 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Aug 13 00:27:36.905196 kernel: Linux version 6.6.100-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Tue Aug 12 22:21:53 -00 2025 Aug 13 00:27:36.905205 kernel: KASLR enabled Aug 13 00:27:36.905211 kernel: efi: EFI v2.7 by EDK II Aug 13 00:27:36.905217 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdba86018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Aug 13 00:27:36.905222 kernel: random: crng init done Aug 13 00:27:36.905229 kernel: ACPI: Early table checksum verification disabled Aug 13 00:27:36.905235 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Aug 13 00:27:36.905241 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Aug 13 00:27:36.905249 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:27:36.905255 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:27:36.905260 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:27:36.905266 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:27:36.905272 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:27:36.905280 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:27:36.905287 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:27:36.905294 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:27:36.905300 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Aug 13 00:27:36.905306 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Aug 13 00:27:36.905313 kernel: NUMA: Failed to initialise from firmware Aug 13 00:27:36.905319 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Aug 13 00:27:36.905325 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Aug 13 00:27:36.905331 kernel: Zone ranges: Aug 13 00:27:36.905338 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Aug 13 00:27:36.905344 kernel: DMA32 empty Aug 13 00:27:36.905351 kernel: Normal empty Aug 13 00:27:36.905358 kernel: Movable zone start for each node Aug 13 00:27:36.905364 kernel: Early memory node ranges Aug 13 00:27:36.905370 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Aug 13 00:27:36.905377 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Aug 13 00:27:36.905383 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Aug 13 00:27:36.905389 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Aug 13 00:27:36.905395 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Aug 13 00:27:36.905401 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Aug 13 00:27:36.905408 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Aug 13 00:27:36.905414 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Aug 13 00:27:36.905420 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Aug 13 00:27:36.905428 kernel: psci: probing for conduit method from ACPI. Aug 13 00:27:36.905434 kernel: psci: PSCIv1.1 detected in firmware. Aug 13 00:27:36.905441 kernel: psci: Using standard PSCI v0.2 function IDs Aug 13 00:27:36.905449 kernel: psci: Trusted OS migration not required Aug 13 00:27:36.905456 kernel: psci: SMC Calling Convention v1.1 Aug 13 00:27:36.905463 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Aug 13 00:27:36.905471 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Aug 13 00:27:36.905478 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Aug 13 00:27:36.905485 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Aug 13 00:27:36.905492 kernel: Detected PIPT I-cache on CPU0 Aug 13 00:27:36.905498 kernel: CPU features: detected: GIC system register CPU interface Aug 13 00:27:36.905505 kernel: CPU features: detected: Hardware dirty bit management Aug 13 00:27:36.905512 kernel: CPU features: detected: Spectre-v4 Aug 13 00:27:36.905518 kernel: CPU features: detected: Spectre-BHB Aug 13 00:27:36.905525 kernel: CPU features: kernel page table isolation forced ON by KASLR Aug 13 00:27:36.905532 kernel: CPU features: detected: Kernel page table isolation (KPTI) Aug 13 00:27:36.905539 kernel: CPU features: detected: ARM erratum 1418040 Aug 13 00:27:36.905546 kernel: CPU features: detected: SSBS not fully self-synchronizing Aug 13 00:27:36.905553 kernel: alternatives: applying boot alternatives Aug 13 00:27:36.905561 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=2f9df6e9e6c671c457040a64675390bbff42294b08c628cd2dc472ed8120146a Aug 13 00:27:36.905568 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 13 00:27:36.905574 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 13 00:27:36.905588 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 13 00:27:36.905595 kernel: Fallback order for Node 0: 0 Aug 13 00:27:36.905602 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Aug 13 00:27:36.905609 kernel: Policy zone: DMA Aug 13 00:27:36.905615 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 13 00:27:36.905624 kernel: software IO TLB: area num 4. Aug 13 00:27:36.905631 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Aug 13 00:27:36.905638 kernel: Memory: 2386404K/2572288K available (10304K kernel code, 2186K rwdata, 8108K rodata, 39424K init, 897K bss, 185884K reserved, 0K cma-reserved) Aug 13 00:27:36.905645 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 13 00:27:36.905726 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 13 00:27:36.905733 kernel: rcu: RCU event tracing is enabled. Aug 13 00:27:36.905741 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Aug 13 00:27:36.905748 kernel: Trampoline variant of Tasks RCU enabled. Aug 13 00:27:36.905754 kernel: Tracing variant of Tasks RCU enabled. Aug 13 00:27:36.905762 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 13 00:27:36.905768 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Aug 13 00:27:36.905778 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Aug 13 00:27:36.905785 kernel: GICv3: 256 SPIs implemented Aug 13 00:27:36.905791 kernel: GICv3: 0 Extended SPIs implemented Aug 13 00:27:36.905798 kernel: Root IRQ handler: gic_handle_irq Aug 13 00:27:36.905805 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Aug 13 00:27:36.905811 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Aug 13 00:27:36.905818 kernel: ITS [mem 0x08080000-0x0809ffff] Aug 13 00:27:36.905825 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Aug 13 00:27:36.905832 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Aug 13 00:27:36.905838 kernel: GICv3: using LPI property table @0x00000000400f0000 Aug 13 00:27:36.905845 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Aug 13 00:27:36.905852 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 13 00:27:36.905860 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 13 00:27:36.905867 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Aug 13 00:27:36.905874 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Aug 13 00:27:36.905880 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Aug 13 00:27:36.905887 kernel: arm-pv: using stolen time PV Aug 13 00:27:36.905894 kernel: Console: colour dummy device 80x25 Aug 13 00:27:36.905901 kernel: ACPI: Core revision 20230628 Aug 13 00:27:36.905908 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Aug 13 00:27:36.905915 kernel: pid_max: default: 32768 minimum: 301 Aug 13 00:27:36.905922 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Aug 13 00:27:36.905930 kernel: landlock: Up and running. Aug 13 00:27:36.905937 kernel: SELinux: Initializing. Aug 13 00:27:36.905944 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 13 00:27:36.905951 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 13 00:27:36.905958 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Aug 13 00:27:36.905965 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Aug 13 00:27:36.905972 kernel: rcu: Hierarchical SRCU implementation. Aug 13 00:27:36.905979 kernel: rcu: Max phase no-delay instances is 400. Aug 13 00:27:36.905986 kernel: Platform MSI: ITS@0x8080000 domain created Aug 13 00:27:36.905994 kernel: PCI/MSI: ITS@0x8080000 domain created Aug 13 00:27:36.906001 kernel: Remapping and enabling EFI services. Aug 13 00:27:36.906008 kernel: smp: Bringing up secondary CPUs ... Aug 13 00:27:36.906015 kernel: Detected PIPT I-cache on CPU1 Aug 13 00:27:36.906022 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Aug 13 00:27:36.906029 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Aug 13 00:27:36.906036 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 13 00:27:36.906042 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Aug 13 00:27:36.906049 kernel: Detected PIPT I-cache on CPU2 Aug 13 00:27:36.906056 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Aug 13 00:27:36.906065 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Aug 13 00:27:36.906072 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 13 00:27:36.906083 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Aug 13 00:27:36.906091 kernel: Detected PIPT I-cache on CPU3 Aug 13 00:27:36.906098 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Aug 13 00:27:36.906106 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Aug 13 00:27:36.906113 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 13 00:27:36.906120 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Aug 13 00:27:36.906127 kernel: smp: Brought up 1 node, 4 CPUs Aug 13 00:27:36.906136 kernel: SMP: Total of 4 processors activated. Aug 13 00:27:36.906143 kernel: CPU features: detected: 32-bit EL0 Support Aug 13 00:27:36.906150 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Aug 13 00:27:36.906158 kernel: CPU features: detected: Common not Private translations Aug 13 00:27:36.906165 kernel: CPU features: detected: CRC32 instructions Aug 13 00:27:36.906172 kernel: CPU features: detected: Enhanced Virtualization Traps Aug 13 00:27:36.906179 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Aug 13 00:27:36.906187 kernel: CPU features: detected: LSE atomic instructions Aug 13 00:27:36.906195 kernel: CPU features: detected: Privileged Access Never Aug 13 00:27:36.906203 kernel: CPU features: detected: RAS Extension Support Aug 13 00:27:36.906210 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Aug 13 00:27:36.906217 kernel: CPU: All CPU(s) started at EL1 Aug 13 00:27:36.906224 kernel: alternatives: applying system-wide alternatives Aug 13 00:27:36.906232 kernel: devtmpfs: initialized Aug 13 00:27:36.906239 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 13 00:27:36.906247 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Aug 13 00:27:36.906254 kernel: pinctrl core: initialized pinctrl subsystem Aug 13 00:27:36.906262 kernel: SMBIOS 3.0.0 present. Aug 13 00:27:36.906270 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Aug 13 00:27:36.906277 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 13 00:27:36.906284 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Aug 13 00:27:36.906292 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Aug 13 00:27:36.906299 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Aug 13 00:27:36.906306 kernel: audit: initializing netlink subsys (disabled) Aug 13 00:27:36.906314 kernel: audit: type=2000 audit(0.029:1): state=initialized audit_enabled=0 res=1 Aug 13 00:27:36.906322 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 13 00:27:36.906329 kernel: cpuidle: using governor menu Aug 13 00:27:36.906337 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Aug 13 00:27:36.906344 kernel: ASID allocator initialised with 32768 entries Aug 13 00:27:36.906351 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 13 00:27:36.906358 kernel: Serial: AMBA PL011 UART driver Aug 13 00:27:36.906366 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Aug 13 00:27:36.906373 kernel: Modules: 0 pages in range for non-PLT usage Aug 13 00:27:36.906380 kernel: Modules: 509008 pages in range for PLT usage Aug 13 00:27:36.906387 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 13 00:27:36.906396 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Aug 13 00:27:36.906403 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Aug 13 00:27:36.906410 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Aug 13 00:27:36.906417 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 13 00:27:36.906425 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Aug 13 00:27:36.906432 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Aug 13 00:27:36.906439 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Aug 13 00:27:36.906446 kernel: ACPI: Added _OSI(Module Device) Aug 13 00:27:36.906453 kernel: ACPI: Added _OSI(Processor Device) Aug 13 00:27:36.906462 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 13 00:27:36.906469 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 13 00:27:36.906476 kernel: ACPI: Interpreter enabled Aug 13 00:27:36.906483 kernel: ACPI: Using GIC for interrupt routing Aug 13 00:27:36.906491 kernel: ACPI: MCFG table detected, 1 entries Aug 13 00:27:36.906498 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Aug 13 00:27:36.906505 kernel: printk: console [ttyAMA0] enabled Aug 13 00:27:36.906513 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 13 00:27:36.906665 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 13 00:27:36.906748 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Aug 13 00:27:36.906815 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Aug 13 00:27:36.906880 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Aug 13 00:27:36.906946 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Aug 13 00:27:36.906955 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Aug 13 00:27:36.906963 kernel: PCI host bridge to bus 0000:00 Aug 13 00:27:36.907031 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Aug 13 00:27:36.907095 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Aug 13 00:27:36.907152 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Aug 13 00:27:36.907208 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 13 00:27:36.907286 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Aug 13 00:27:36.907361 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Aug 13 00:27:36.907429 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Aug 13 00:27:36.907497 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Aug 13 00:27:36.907562 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Aug 13 00:27:36.907704 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Aug 13 00:27:36.907779 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Aug 13 00:27:36.907845 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Aug 13 00:27:36.907902 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Aug 13 00:27:36.907958 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Aug 13 00:27:36.908020 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Aug 13 00:27:36.908029 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Aug 13 00:27:36.908036 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Aug 13 00:27:36.908044 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Aug 13 00:27:36.908052 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Aug 13 00:27:36.908059 kernel: iommu: Default domain type: Translated Aug 13 00:27:36.908066 kernel: iommu: DMA domain TLB invalidation policy: strict mode Aug 13 00:27:36.908073 kernel: efivars: Registered efivars operations Aug 13 00:27:36.908082 kernel: vgaarb: loaded Aug 13 00:27:36.908090 kernel: clocksource: Switched to clocksource arch_sys_counter Aug 13 00:27:36.908097 kernel: VFS: Disk quotas dquot_6.6.0 Aug 13 00:27:36.908104 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 13 00:27:36.908112 kernel: pnp: PnP ACPI init Aug 13 00:27:36.908181 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Aug 13 00:27:36.908192 kernel: pnp: PnP ACPI: found 1 devices Aug 13 00:27:36.908199 kernel: NET: Registered PF_INET protocol family Aug 13 00:27:36.908207 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 13 00:27:36.908216 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Aug 13 00:27:36.908223 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 13 00:27:36.908231 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 13 00:27:36.908238 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Aug 13 00:27:36.908245 kernel: TCP: Hash tables configured (established 32768 bind 32768) Aug 13 00:27:36.908253 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 13 00:27:36.908260 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 13 00:27:36.908268 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 13 00:27:36.908276 kernel: PCI: CLS 0 bytes, default 64 Aug 13 00:27:36.908283 kernel: kvm [1]: HYP mode not available Aug 13 00:27:36.908291 kernel: Initialise system trusted keyrings Aug 13 00:27:36.908298 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Aug 13 00:27:36.908305 kernel: Key type asymmetric registered Aug 13 00:27:36.908312 kernel: Asymmetric key parser 'x509' registered Aug 13 00:27:36.908320 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 13 00:27:36.908327 kernel: io scheduler mq-deadline registered Aug 13 00:27:36.908334 kernel: io scheduler kyber registered Aug 13 00:27:36.908341 kernel: io scheduler bfq registered Aug 13 00:27:36.908350 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Aug 13 00:27:36.908357 kernel: ACPI: button: Power Button [PWRB] Aug 13 00:27:36.908365 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Aug 13 00:27:36.908435 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Aug 13 00:27:36.908445 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 13 00:27:36.908452 kernel: thunder_xcv, ver 1.0 Aug 13 00:27:36.908459 kernel: thunder_bgx, ver 1.0 Aug 13 00:27:36.908466 kernel: nicpf, ver 1.0 Aug 13 00:27:36.908474 kernel: nicvf, ver 1.0 Aug 13 00:27:36.908550 kernel: rtc-efi rtc-efi.0: registered as rtc0 Aug 13 00:27:36.908626 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-08-13T00:27:36 UTC (1755044856) Aug 13 00:27:36.908637 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 13 00:27:36.908645 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Aug 13 00:27:36.908664 kernel: watchdog: Delayed init of the lockup detector failed: -19 Aug 13 00:27:36.908672 kernel: watchdog: Hard watchdog permanently disabled Aug 13 00:27:36.908679 kernel: NET: Registered PF_INET6 protocol family Aug 13 00:27:36.908686 kernel: Segment Routing with IPv6 Aug 13 00:27:36.908696 kernel: In-situ OAM (IOAM) with IPv6 Aug 13 00:27:36.908704 kernel: NET: Registered PF_PACKET protocol family Aug 13 00:27:36.908712 kernel: Key type dns_resolver registered Aug 13 00:27:36.908719 kernel: registered taskstats version 1 Aug 13 00:27:36.908726 kernel: Loading compiled-in X.509 certificates Aug 13 00:27:36.908734 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.100-flatcar: 7263800c6d21650660e2b030c1023dce09b1e8b6' Aug 13 00:27:36.908741 kernel: Key type .fscrypt registered Aug 13 00:27:36.908748 kernel: Key type fscrypt-provisioning registered Aug 13 00:27:36.908755 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 13 00:27:36.908764 kernel: ima: Allocated hash algorithm: sha1 Aug 13 00:27:36.908771 kernel: ima: No architecture policies found Aug 13 00:27:36.908778 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Aug 13 00:27:36.908785 kernel: clk: Disabling unused clocks Aug 13 00:27:36.908793 kernel: Freeing unused kernel memory: 39424K Aug 13 00:27:36.908800 kernel: Run /init as init process Aug 13 00:27:36.908807 kernel: with arguments: Aug 13 00:27:36.908814 kernel: /init Aug 13 00:27:36.908821 kernel: with environment: Aug 13 00:27:36.908829 kernel: HOME=/ Aug 13 00:27:36.908836 kernel: TERM=linux Aug 13 00:27:36.908843 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 13 00:27:36.908853 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 13 00:27:36.908862 systemd[1]: Detected virtualization kvm. Aug 13 00:27:36.908870 systemd[1]: Detected architecture arm64. Aug 13 00:27:36.908877 systemd[1]: Running in initrd. Aug 13 00:27:36.908886 systemd[1]: No hostname configured, using default hostname. Aug 13 00:27:36.908905 systemd[1]: Hostname set to . Aug 13 00:27:36.908913 systemd[1]: Initializing machine ID from VM UUID. Aug 13 00:27:36.908920 systemd[1]: Queued start job for default target initrd.target. Aug 13 00:27:36.908928 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 00:27:36.908936 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 00:27:36.908944 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 13 00:27:36.908952 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 13 00:27:36.908961 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 13 00:27:36.908970 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 13 00:27:36.908979 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 13 00:27:36.908987 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 13 00:27:36.908995 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 00:27:36.909003 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 13 00:27:36.909011 systemd[1]: Reached target paths.target - Path Units. Aug 13 00:27:36.909020 systemd[1]: Reached target slices.target - Slice Units. Aug 13 00:27:36.909028 systemd[1]: Reached target swap.target - Swaps. Aug 13 00:27:36.909036 systemd[1]: Reached target timers.target - Timer Units. Aug 13 00:27:36.909044 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 13 00:27:36.909052 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 13 00:27:36.909061 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 13 00:27:36.909069 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 13 00:27:36.909077 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 13 00:27:36.909085 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 13 00:27:36.909094 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 00:27:36.909102 systemd[1]: Reached target sockets.target - Socket Units. Aug 13 00:27:36.909110 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 13 00:27:36.909118 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 13 00:27:36.909126 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 13 00:27:36.909134 systemd[1]: Starting systemd-fsck-usr.service... Aug 13 00:27:36.909142 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 13 00:27:36.909154 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 13 00:27:36.909163 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:27:36.909171 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 13 00:27:36.909179 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 00:27:36.909187 systemd[1]: Finished systemd-fsck-usr.service. Aug 13 00:27:36.909196 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 13 00:27:36.909206 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:27:36.909233 systemd-journald[237]: Collecting audit messages is disabled. Aug 13 00:27:36.909252 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 13 00:27:36.909266 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 13 00:27:36.909277 systemd-journald[237]: Journal started Aug 13 00:27:36.909296 systemd-journald[237]: Runtime Journal (/run/log/journal/a0524e5d1f5a4a07aae47540e0c20f02) is 5.9M, max 47.3M, 41.4M free. Aug 13 00:27:36.897500 systemd-modules-load[239]: Inserted module 'overlay' Aug 13 00:27:36.911684 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 13 00:27:36.913660 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 13 00:27:36.916232 kernel: Bridge firewalling registered Aug 13 00:27:36.916267 systemd[1]: Started systemd-journald.service - Journal Service. Aug 13 00:27:36.915619 systemd-modules-load[239]: Inserted module 'br_netfilter' Aug 13 00:27:36.918075 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 13 00:27:36.922310 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 13 00:27:36.924835 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 13 00:27:36.925854 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 00:27:36.930552 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 00:27:36.932456 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 13 00:27:36.937259 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 00:27:36.938963 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 13 00:27:36.941359 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 13 00:27:36.945296 dracut-cmdline[271]: dracut-dracut-053 Aug 13 00:27:36.947905 dracut-cmdline[271]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=2f9df6e9e6c671c457040a64675390bbff42294b08c628cd2dc472ed8120146a Aug 13 00:27:36.977954 systemd-resolved[280]: Positive Trust Anchors: Aug 13 00:27:36.977972 systemd-resolved[280]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 13 00:27:36.978005 systemd-resolved[280]: 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 Aug 13 00:27:36.983351 systemd-resolved[280]: Defaulting to hostname 'linux'. Aug 13 00:27:36.984463 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 13 00:27:36.985572 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 13 00:27:37.023682 kernel: SCSI subsystem initialized Aug 13 00:27:37.027662 kernel: Loading iSCSI transport class v2.0-870. Aug 13 00:27:37.034666 kernel: iscsi: registered transport (tcp) Aug 13 00:27:37.047853 kernel: iscsi: registered transport (qla4xxx) Aug 13 00:27:37.047904 kernel: QLogic iSCSI HBA Driver Aug 13 00:27:37.088055 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 13 00:27:37.098838 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 13 00:27:37.115176 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 13 00:27:37.115226 kernel: device-mapper: uevent: version 1.0.3 Aug 13 00:27:37.115961 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 13 00:27:37.160674 kernel: raid6: neonx8 gen() 15758 MB/s Aug 13 00:27:37.177666 kernel: raid6: neonx4 gen() 15665 MB/s Aug 13 00:27:37.194672 kernel: raid6: neonx2 gen() 13236 MB/s Aug 13 00:27:37.211670 kernel: raid6: neonx1 gen() 10469 MB/s Aug 13 00:27:37.228669 kernel: raid6: int64x8 gen() 6956 MB/s Aug 13 00:27:37.245660 kernel: raid6: int64x4 gen() 7349 MB/s Aug 13 00:27:37.262661 kernel: raid6: int64x2 gen() 6127 MB/s Aug 13 00:27:37.279669 kernel: raid6: int64x1 gen() 5052 MB/s Aug 13 00:27:37.279702 kernel: raid6: using algorithm neonx8 gen() 15758 MB/s Aug 13 00:27:37.296661 kernel: raid6: .... xor() 11948 MB/s, rmw enabled Aug 13 00:27:37.296679 kernel: raid6: using neon recovery algorithm Aug 13 00:27:37.301782 kernel: xor: measuring software checksum speed Aug 13 00:27:37.301803 kernel: 8regs : 19797 MB/sec Aug 13 00:27:37.302806 kernel: 32regs : 19650 MB/sec Aug 13 00:27:37.302819 kernel: arm64_neon : 27087 MB/sec Aug 13 00:27:37.302829 kernel: xor: using function: arm64_neon (27087 MB/sec) Aug 13 00:27:37.353581 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 13 00:27:37.364719 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 13 00:27:37.376857 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 00:27:37.390466 systemd-udevd[458]: Using default interface naming scheme 'v255'. Aug 13 00:27:37.393619 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 00:27:37.406834 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 13 00:27:37.418115 dracut-pre-trigger[464]: rd.md=0: removing MD RAID activation Aug 13 00:27:37.444939 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 13 00:27:37.455839 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 13 00:27:37.497703 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 00:27:37.506961 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 13 00:27:37.518301 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 13 00:27:37.519489 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 13 00:27:37.522740 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 00:27:37.525497 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 13 00:27:37.538825 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 13 00:27:37.541385 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Aug 13 00:27:37.541540 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Aug 13 00:27:37.552811 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Aug 13 00:27:37.552862 kernel: GPT:9289727 != 19775487 Aug 13 00:27:37.552879 kernel: GPT:Alternate GPT header not at the end of the disk. Aug 13 00:27:37.552889 kernel: GPT:9289727 != 19775487 Aug 13 00:27:37.554044 kernel: GPT: Use GNU Parted to correct GPT errors. Aug 13 00:27:37.554078 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 13 00:27:37.556237 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 13 00:27:37.559375 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 13 00:27:37.559484 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 00:27:37.562820 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 13 00:27:37.563827 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 13 00:27:37.563970 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:27:37.565861 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:27:37.580012 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:27:37.583711 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by (udev-worker) (506) Aug 13 00:27:37.583769 kernel: BTRFS: device fsid 03408483-5051-409a-aab4-4e6d5027e982 devid 1 transid 41 /dev/vda3 scanned by (udev-worker) (515) Aug 13 00:27:37.594090 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Aug 13 00:27:37.595221 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:27:37.600453 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Aug 13 00:27:37.607513 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 13 00:27:37.611229 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Aug 13 00:27:37.612263 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Aug 13 00:27:37.623838 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 13 00:27:37.625838 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 13 00:27:37.630352 disk-uuid[547]: Primary Header is updated. Aug 13 00:27:37.630352 disk-uuid[547]: Secondary Entries is updated. Aug 13 00:27:37.630352 disk-uuid[547]: Secondary Header is updated. Aug 13 00:27:37.635715 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 13 00:27:37.649512 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 00:27:38.648685 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 13 00:27:38.648743 disk-uuid[548]: The operation has completed successfully. Aug 13 00:27:38.677507 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 13 00:27:38.677615 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 13 00:27:38.697857 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 13 00:27:38.700693 sh[571]: Success Aug 13 00:27:38.713673 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Aug 13 00:27:38.744082 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 13 00:27:38.762188 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 13 00:27:38.764488 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 13 00:27:38.773322 kernel: BTRFS info (device dm-0): first mount of filesystem 03408483-5051-409a-aab4-4e6d5027e982 Aug 13 00:27:38.773364 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Aug 13 00:27:38.773383 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 13 00:27:38.775022 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 13 00:27:38.775038 kernel: BTRFS info (device dm-0): using free space tree Aug 13 00:27:38.779419 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 13 00:27:38.780672 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 13 00:27:38.789820 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 13 00:27:38.791316 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 13 00:27:38.801162 kernel: BTRFS info (device vda6): first mount of filesystem dbce4b09-c4b8-4cc9-bd11-416717f60c7d Aug 13 00:27:38.801205 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 13 00:27:38.801216 kernel: BTRFS info (device vda6): using free space tree Aug 13 00:27:38.803750 kernel: BTRFS info (device vda6): auto enabling async discard Aug 13 00:27:38.810460 systemd[1]: mnt-oem.mount: Deactivated successfully. Aug 13 00:27:38.811957 kernel: BTRFS info (device vda6): last unmount of filesystem dbce4b09-c4b8-4cc9-bd11-416717f60c7d Aug 13 00:27:38.818142 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 13 00:27:38.823835 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 13 00:27:38.886022 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 13 00:27:38.899833 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 13 00:27:38.930646 systemd-networkd[763]: lo: Link UP Aug 13 00:27:38.930667 systemd-networkd[763]: lo: Gained carrier Aug 13 00:27:38.931351 systemd-networkd[763]: Enumeration completed Aug 13 00:27:38.931884 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:27:38.931887 systemd-networkd[763]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 13 00:27:38.933364 systemd-networkd[763]: eth0: Link UP Aug 13 00:27:38.933367 systemd-networkd[763]: eth0: Gained carrier Aug 13 00:27:38.933374 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:27:38.934751 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 13 00:27:38.936211 systemd[1]: Reached target network.target - Network. Aug 13 00:27:38.941871 ignition[664]: Ignition 2.19.0 Aug 13 00:27:38.941882 ignition[664]: Stage: fetch-offline Aug 13 00:27:38.941925 ignition[664]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:27:38.941933 ignition[664]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:27:38.942096 ignition[664]: parsed url from cmdline: "" Aug 13 00:27:38.942100 ignition[664]: no config URL provided Aug 13 00:27:38.942105 ignition[664]: reading system config file "/usr/lib/ignition/user.ign" Aug 13 00:27:38.942111 ignition[664]: no config at "/usr/lib/ignition/user.ign" Aug 13 00:27:38.942136 ignition[664]: op(1): [started] loading QEMU firmware config module Aug 13 00:27:38.942141 ignition[664]: op(1): executing: "modprobe" "qemu_fw_cfg" Aug 13 00:27:38.953005 ignition[664]: op(1): [finished] loading QEMU firmware config module Aug 13 00:27:38.955689 systemd-networkd[763]: eth0: DHCPv4 address 10.0.0.157/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 13 00:27:38.959880 ignition[664]: parsing config with SHA512: 224a533f6a7826c69a0397a922495696b0a1b69eecb17f1b87e7bbd22e30a5ebfa2aecfa6af7126b6dd93e72243c0e5b9878d921178754c4efb3e8117936fa23 Aug 13 00:27:38.962770 unknown[664]: fetched base config from "system" Aug 13 00:27:38.962781 unknown[664]: fetched user config from "qemu" Aug 13 00:27:38.963055 ignition[664]: fetch-offline: fetch-offline passed Aug 13 00:27:38.963116 ignition[664]: Ignition finished successfully Aug 13 00:27:38.964990 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 13 00:27:38.966385 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Aug 13 00:27:38.972816 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 13 00:27:38.983055 ignition[770]: Ignition 2.19.0 Aug 13 00:27:38.983065 ignition[770]: Stage: kargs Aug 13 00:27:38.983231 ignition[770]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:27:38.983241 ignition[770]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:27:38.983925 ignition[770]: kargs: kargs passed Aug 13 00:27:38.983967 ignition[770]: Ignition finished successfully Aug 13 00:27:38.988341 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 13 00:27:38.990084 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 13 00:27:39.003765 ignition[778]: Ignition 2.19.0 Aug 13 00:27:39.003775 ignition[778]: Stage: disks Aug 13 00:27:39.003948 ignition[778]: no configs at "/usr/lib/ignition/base.d" Aug 13 00:27:39.003959 ignition[778]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:27:39.004644 ignition[778]: disks: disks passed Aug 13 00:27:39.004708 ignition[778]: Ignition finished successfully Aug 13 00:27:39.006918 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 13 00:27:39.008120 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 13 00:27:39.009303 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 13 00:27:39.010776 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 13 00:27:39.012178 systemd[1]: Reached target sysinit.target - System Initialization. Aug 13 00:27:39.013413 systemd[1]: Reached target basic.target - Basic System. Aug 13 00:27:39.025852 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 13 00:27:39.035477 systemd-fsck[789]: ROOT: clean, 14/553520 files, 52654/553472 blocks Aug 13 00:27:39.039523 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 13 00:27:39.052820 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 13 00:27:39.096539 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 13 00:27:39.097712 kernel: EXT4-fs (vda9): mounted filesystem 128aec8b-f05d-48ed-8996-c9e8b21a7810 r/w with ordered data mode. Quota mode: none. Aug 13 00:27:39.097627 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 13 00:27:39.111739 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 13 00:27:39.113196 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 13 00:27:39.114417 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 13 00:27:39.114455 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 13 00:27:39.119587 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (797) Aug 13 00:27:39.114476 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 13 00:27:39.121903 kernel: BTRFS info (device vda6): first mount of filesystem dbce4b09-c4b8-4cc9-bd11-416717f60c7d Aug 13 00:27:39.121919 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 13 00:27:39.121929 kernel: BTRFS info (device vda6): using free space tree Aug 13 00:27:39.123911 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 13 00:27:39.125714 kernel: BTRFS info (device vda6): auto enabling async discard Aug 13 00:27:39.125414 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 13 00:27:39.127927 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 13 00:27:39.166335 initrd-setup-root[822]: cut: /sysroot/etc/passwd: No such file or directory Aug 13 00:27:39.170255 initrd-setup-root[829]: cut: /sysroot/etc/group: No such file or directory Aug 13 00:27:39.174022 initrd-setup-root[836]: cut: /sysroot/etc/shadow: No such file or directory Aug 13 00:27:39.177513 initrd-setup-root[843]: cut: /sysroot/etc/gshadow: No such file or directory Aug 13 00:27:39.249639 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 13 00:27:39.255781 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 13 00:27:39.257141 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 13 00:27:39.261701 kernel: BTRFS info (device vda6): last unmount of filesystem dbce4b09-c4b8-4cc9-bd11-416717f60c7d Aug 13 00:27:39.275333 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 13 00:27:39.279822 ignition[912]: INFO : Ignition 2.19.0 Aug 13 00:27:39.279822 ignition[912]: INFO : Stage: mount Aug 13 00:27:39.281713 ignition[912]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 00:27:39.281713 ignition[912]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:27:39.281713 ignition[912]: INFO : mount: mount passed Aug 13 00:27:39.281713 ignition[912]: INFO : Ignition finished successfully Aug 13 00:27:39.282701 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 13 00:27:39.294748 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 13 00:27:39.772516 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 13 00:27:39.784847 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 13 00:27:39.791312 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by mount (926) Aug 13 00:27:39.791352 kernel: BTRFS info (device vda6): first mount of filesystem dbce4b09-c4b8-4cc9-bd11-416717f60c7d Aug 13 00:27:39.791363 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 13 00:27:39.791986 kernel: BTRFS info (device vda6): using free space tree Aug 13 00:27:39.794670 kernel: BTRFS info (device vda6): auto enabling async discard Aug 13 00:27:39.795485 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 13 00:27:39.813496 ignition[943]: INFO : Ignition 2.19.0 Aug 13 00:27:39.813496 ignition[943]: INFO : Stage: files Aug 13 00:27:39.814836 ignition[943]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 00:27:39.814836 ignition[943]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:27:39.814836 ignition[943]: DEBUG : files: compiled without relabeling support, skipping Aug 13 00:27:39.817270 ignition[943]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 13 00:27:39.817270 ignition[943]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 13 00:27:39.819949 ignition[943]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 13 00:27:39.820957 ignition[943]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 13 00:27:39.820957 ignition[943]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 13 00:27:39.820454 unknown[943]: wrote ssh authorized keys file for user: core Aug 13 00:27:39.823799 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Aug 13 00:27:39.823799 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Aug 13 00:27:39.823799 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 13 00:27:39.823799 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 13 00:27:39.823799 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Aug 13 00:27:39.823799 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Aug 13 00:27:39.823799 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Aug 13 00:27:39.823799 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Aug 13 00:27:40.243796 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Aug 13 00:27:40.502820 systemd-networkd[763]: eth0: Gained IPv6LL Aug 13 00:27:40.853208 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Aug 13 00:27:40.853208 ignition[943]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Aug 13 00:27:40.856115 ignition[943]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 13 00:27:40.856115 ignition[943]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 13 00:27:40.856115 ignition[943]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Aug 13 00:27:40.856115 ignition[943]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Aug 13 00:27:40.895413 ignition[943]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Aug 13 00:27:40.899825 ignition[943]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Aug 13 00:27:40.901064 ignition[943]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Aug 13 00:27:40.901064 ignition[943]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 13 00:27:40.901064 ignition[943]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 13 00:27:40.901064 ignition[943]: INFO : files: files passed Aug 13 00:27:40.901064 ignition[943]: INFO : Ignition finished successfully Aug 13 00:27:40.902681 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 13 00:27:40.911861 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 13 00:27:40.913415 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 13 00:27:40.916184 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 13 00:27:40.916272 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 13 00:27:40.922551 initrd-setup-root-after-ignition[971]: grep: /sysroot/oem/oem-release: No such file or directory Aug 13 00:27:40.926811 initrd-setup-root-after-ignition[973]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 13 00:27:40.926811 initrd-setup-root-after-ignition[973]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 13 00:27:40.929727 initrd-setup-root-after-ignition[977]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 13 00:27:40.929160 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 13 00:27:40.932041 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 13 00:27:40.944816 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 13 00:27:40.965939 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 13 00:27:40.966891 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 13 00:27:40.967993 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 13 00:27:40.968790 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 13 00:27:40.970697 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 13 00:27:40.971526 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 13 00:27:40.989489 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 13 00:27:41.000833 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 13 00:27:41.009393 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 13 00:27:41.010489 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 00:27:41.012318 systemd[1]: Stopped target timers.target - Timer Units. Aug 13 00:27:41.013957 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 13 00:27:41.014084 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 13 00:27:41.016295 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 13 00:27:41.018065 systemd[1]: Stopped target basic.target - Basic System. Aug 13 00:27:41.019553 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 13 00:27:41.021246 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 13 00:27:41.022961 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 13 00:27:41.024583 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 13 00:27:41.026090 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 13 00:27:41.027672 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 13 00:27:41.029315 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 13 00:27:41.030785 systemd[1]: Stopped target swap.target - Swaps. Aug 13 00:27:41.032149 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 13 00:27:41.032284 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 13 00:27:41.034396 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 13 00:27:41.036174 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 00:27:41.037820 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 13 00:27:41.038740 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 00:27:41.040612 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 13 00:27:41.040745 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 13 00:27:41.048045 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 13 00:27:41.048160 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 13 00:27:41.049819 systemd[1]: Stopped target paths.target - Path Units. Aug 13 00:27:41.051196 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 13 00:27:41.054728 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 00:27:41.055836 systemd[1]: Stopped target slices.target - Slice Units. Aug 13 00:27:41.057646 systemd[1]: Stopped target sockets.target - Socket Units. Aug 13 00:27:41.059007 systemd[1]: iscsid.socket: Deactivated successfully. Aug 13 00:27:41.059099 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 13 00:27:41.060415 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 13 00:27:41.060501 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 13 00:27:41.061786 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 13 00:27:41.061897 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 13 00:27:41.063598 systemd[1]: ignition-files.service: Deactivated successfully. Aug 13 00:27:41.063708 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 13 00:27:41.075837 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 13 00:27:41.077406 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 13 00:27:41.078266 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 13 00:27:41.078390 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 00:27:41.080192 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 13 00:27:41.080292 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 13 00:27:41.085333 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 13 00:27:41.086399 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 13 00:27:41.090431 ignition[998]: INFO : Ignition 2.19.0 Aug 13 00:27:41.090431 ignition[998]: INFO : Stage: umount Aug 13 00:27:41.092728 ignition[998]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 13 00:27:41.092728 ignition[998]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 13 00:27:41.092728 ignition[998]: INFO : umount: umount passed Aug 13 00:27:41.092728 ignition[998]: INFO : Ignition finished successfully Aug 13 00:27:41.093029 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 13 00:27:41.093549 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 13 00:27:41.094694 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 13 00:27:41.096760 systemd[1]: Stopped target network.target - Network. Aug 13 00:27:41.097575 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 13 00:27:41.097644 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 13 00:27:41.099825 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 13 00:27:41.099875 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 13 00:27:41.100762 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 13 00:27:41.100800 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 13 00:27:41.101627 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 13 00:27:41.101687 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 13 00:27:41.103524 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 13 00:27:41.105041 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 13 00:27:41.107684 systemd-networkd[763]: eth0: DHCPv6 lease lost Aug 13 00:27:41.110146 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 13 00:27:41.110259 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 13 00:27:41.112074 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 13 00:27:41.112131 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 13 00:27:41.122756 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 13 00:27:41.123505 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 13 00:27:41.123575 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 13 00:27:41.125342 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 00:27:41.129161 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 13 00:27:41.129253 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 13 00:27:41.132474 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 13 00:27:41.132529 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 13 00:27:41.134204 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 13 00:27:41.134248 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 13 00:27:41.135757 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 13 00:27:41.135798 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 00:27:41.138900 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 13 00:27:41.138994 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 13 00:27:41.141180 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 13 00:27:41.141301 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 00:27:41.144114 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 13 00:27:41.144175 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 13 00:27:41.145271 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 13 00:27:41.145322 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 00:27:41.147065 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 13 00:27:41.147114 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 13 00:27:41.149328 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 13 00:27:41.149374 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 13 00:27:41.151796 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 13 00:27:41.151844 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 13 00:27:41.161824 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 13 00:27:41.162752 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 13 00:27:41.162812 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 00:27:41.164598 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Aug 13 00:27:41.164640 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 13 00:27:41.166372 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 13 00:27:41.166417 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 00:27:41.168168 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 13 00:27:41.168209 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:27:41.170155 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 13 00:27:41.170246 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 13 00:27:41.171723 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 13 00:27:41.171793 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 13 00:27:41.174270 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 13 00:27:41.175492 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 13 00:27:41.175551 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 13 00:27:41.177949 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 13 00:27:41.188093 systemd[1]: Switching root. Aug 13 00:27:41.222600 systemd-journald[237]: Journal stopped Aug 13 00:27:41.908396 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). Aug 13 00:27:41.908467 kernel: SELinux: policy capability network_peer_controls=1 Aug 13 00:27:41.908480 kernel: SELinux: policy capability open_perms=1 Aug 13 00:27:41.908494 kernel: SELinux: policy capability extended_socket_class=1 Aug 13 00:27:41.908503 kernel: SELinux: policy capability always_check_network=0 Aug 13 00:27:41.908513 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 13 00:27:41.908525 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 13 00:27:41.908535 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 13 00:27:41.908544 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 13 00:27:41.908553 kernel: audit: type=1403 audit(1755044861.356:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 13 00:27:41.908573 systemd[1]: Successfully loaded SELinux policy in 32.566ms. Aug 13 00:27:41.908597 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.444ms. Aug 13 00:27:41.908609 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Aug 13 00:27:41.908620 systemd[1]: Detected virtualization kvm. Aug 13 00:27:41.908631 systemd[1]: Detected architecture arm64. Aug 13 00:27:41.908694 systemd[1]: Detected first boot. Aug 13 00:27:41.908728 systemd[1]: Initializing machine ID from VM UUID. Aug 13 00:27:41.908751 zram_generator::config[1043]: No configuration found. Aug 13 00:27:41.908763 systemd[1]: Populated /etc with preset unit settings. Aug 13 00:27:41.908775 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 13 00:27:41.908786 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 13 00:27:41.908796 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 13 00:27:41.908808 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 13 00:27:41.908820 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 13 00:27:41.908831 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 13 00:27:41.908844 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 13 00:27:41.908855 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 13 00:27:41.908871 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 13 00:27:41.908889 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 13 00:27:41.908900 systemd[1]: Created slice user.slice - User and Session Slice. Aug 13 00:27:41.908910 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 13 00:27:41.908921 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 13 00:27:41.908933 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 13 00:27:41.908943 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 13 00:27:41.908957 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 13 00:27:41.908968 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 13 00:27:41.908979 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Aug 13 00:27:41.908990 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 13 00:27:41.909001 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 13 00:27:41.909012 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 13 00:27:41.909023 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 13 00:27:41.909035 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 13 00:27:41.909046 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 13 00:27:41.909066 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 13 00:27:41.909076 systemd[1]: Reached target slices.target - Slice Units. Aug 13 00:27:41.909087 systemd[1]: Reached target swap.target - Swaps. Aug 13 00:27:41.909097 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 13 00:27:41.909108 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 13 00:27:41.909120 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 13 00:27:41.909130 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 13 00:27:41.909141 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 13 00:27:41.909153 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 13 00:27:41.909164 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 13 00:27:41.909174 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 13 00:27:41.909185 systemd[1]: Mounting media.mount - External Media Directory... Aug 13 00:27:41.909200 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 13 00:27:41.909210 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 13 00:27:41.909235 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 13 00:27:41.909247 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). Aug 13 00:27:41.909260 systemd[1]: Reached target machines.target - Containers. Aug 13 00:27:41.909279 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 13 00:27:41.909290 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:27:41.909300 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 13 00:27:41.909311 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 13 00:27:41.909322 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 00:27:41.909332 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 13 00:27:41.909343 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 00:27:41.909354 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 13 00:27:41.909366 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 00:27:41.909379 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 13 00:27:41.909390 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 13 00:27:41.909400 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 13 00:27:41.909411 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 13 00:27:41.909422 systemd[1]: Stopped systemd-fsck-usr.service. Aug 13 00:27:41.909433 kernel: loop: module loaded Aug 13 00:27:41.909443 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 13 00:27:41.909455 kernel: fuse: init (API version 7.39) Aug 13 00:27:41.909465 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 13 00:27:41.909476 kernel: ACPI: bus type drm_connector registered Aug 13 00:27:41.909485 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 13 00:27:41.909496 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 13 00:27:41.909507 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 13 00:27:41.909517 systemd[1]: verity-setup.service: Deactivated successfully. Aug 13 00:27:41.909528 systemd[1]: Stopped verity-setup.service. Aug 13 00:27:41.909538 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 13 00:27:41.909581 systemd-journald[1117]: Collecting audit messages is disabled. Aug 13 00:27:41.909605 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 13 00:27:41.909626 systemd[1]: Mounted media.mount - External Media Directory. Aug 13 00:27:41.909642 systemd-journald[1117]: Journal started Aug 13 00:27:41.909692 systemd-journald[1117]: Runtime Journal (/run/log/journal/a0524e5d1f5a4a07aae47540e0c20f02) is 5.9M, max 47.3M, 41.4M free. Aug 13 00:27:41.723106 systemd[1]: Queued start job for default target multi-user.target. Aug 13 00:27:41.741408 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Aug 13 00:27:41.741839 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 13 00:27:41.912672 systemd[1]: Started systemd-journald.service - Journal Service. Aug 13 00:27:41.913123 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 13 00:27:41.914192 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 13 00:27:41.915225 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 13 00:27:41.917682 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 13 00:27:41.918781 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 13 00:27:41.920053 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 13 00:27:41.920202 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 13 00:27:41.921390 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 00:27:41.921547 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 00:27:41.922839 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 13 00:27:41.922991 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 13 00:27:41.926077 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 00:27:41.926249 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 00:27:41.927485 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 13 00:27:41.927642 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 13 00:27:41.928971 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 00:27:41.929122 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 00:27:41.930680 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 13 00:27:41.931795 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 13 00:27:41.934680 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 13 00:27:41.947422 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 13 00:27:41.961788 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 13 00:27:41.963783 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 13 00:27:41.964706 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 13 00:27:41.964747 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 13 00:27:41.966587 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Aug 13 00:27:41.968606 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 13 00:27:41.970540 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 13 00:27:41.971581 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:27:41.972880 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 13 00:27:41.979452 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 13 00:27:41.980597 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 13 00:27:41.981507 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 13 00:27:41.982530 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 13 00:27:41.985841 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 13 00:27:41.987613 systemd-journald[1117]: Time spent on flushing to /var/log/journal/a0524e5d1f5a4a07aae47540e0c20f02 is 27.666ms for 837 entries. Aug 13 00:27:41.987613 systemd-journald[1117]: System Journal (/var/log/journal/a0524e5d1f5a4a07aae47540e0c20f02) is 8.0M, max 195.6M, 187.6M free. Aug 13 00:27:42.026783 systemd-journald[1117]: Received client request to flush runtime journal. Aug 13 00:27:42.026932 kernel: loop0: detected capacity change from 0 to 114328 Aug 13 00:27:42.026955 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 13 00:27:41.989134 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 13 00:27:41.992098 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 13 00:27:41.994708 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 13 00:27:41.999748 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 13 00:27:42.000931 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 13 00:27:42.002804 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 13 00:27:42.006837 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 13 00:27:42.008310 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 13 00:27:42.018885 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Aug 13 00:27:42.022956 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 13 00:27:42.028781 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 13 00:27:42.032226 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 13 00:27:42.040260 udevadm[1165]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Aug 13 00:27:42.040516 systemd-tmpfiles[1155]: ACLs are not supported, ignoring. Aug 13 00:27:42.040527 systemd-tmpfiles[1155]: ACLs are not supported, ignoring. Aug 13 00:27:42.044553 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 13 00:27:42.047046 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Aug 13 00:27:42.049230 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 13 00:27:42.061922 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 13 00:27:42.066669 kernel: loop1: detected capacity change from 0 to 114432 Aug 13 00:27:42.083010 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 13 00:27:42.088871 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 13 00:27:42.096695 kernel: loop2: detected capacity change from 0 to 207008 Aug 13 00:27:42.101358 systemd-tmpfiles[1177]: ACLs are not supported, ignoring. Aug 13 00:27:42.101376 systemd-tmpfiles[1177]: ACLs are not supported, ignoring. Aug 13 00:27:42.106797 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 13 00:27:42.139676 kernel: loop3: detected capacity change from 0 to 114328 Aug 13 00:27:42.145691 kernel: loop4: detected capacity change from 0 to 114432 Aug 13 00:27:42.149670 kernel: loop5: detected capacity change from 0 to 207008 Aug 13 00:27:42.155308 (sd-merge)[1182]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Aug 13 00:27:42.155720 (sd-merge)[1182]: Merged extensions into '/usr'. Aug 13 00:27:42.163814 systemd[1]: Reloading requested from client PID 1154 ('systemd-sysext') (unit systemd-sysext.service)... Aug 13 00:27:42.163831 systemd[1]: Reloading... Aug 13 00:27:42.222689 zram_generator::config[1208]: No configuration found. Aug 13 00:27:42.265310 ldconfig[1149]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 13 00:27:42.326609 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. Aug 13 00:27:42.371344 systemd[1]: Reloading finished in 207 ms. Aug 13 00:27:42.410748 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 13 00:27:42.411936 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 13 00:27:42.429884 systemd[1]: Starting ensure-sysext.service... Aug 13 00:27:42.432435 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Aug 13 00:27:42.443430 systemd[1]: Reloading requested from client PID 1242 ('systemctl') (unit ensure-sysext.service)... Aug 13 00:27:42.443446 systemd[1]: Reloading... Aug 13 00:27:42.455426 systemd-tmpfiles[1243]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 13 00:27:42.455739 systemd-tmpfiles[1243]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 13 00:27:42.456351 systemd-tmpfiles[1243]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 13 00:27:42.456574 systemd-tmpfiles[1243]: ACLs are not supported, ignoring. Aug 13 00:27:42.456632 systemd-tmpfiles[1243]: ACLs are not supported, ignoring. Aug 13 00:27:42.459272 systemd-tmpfiles[1243]: Detected autofs mount point /boot during canonicalization of boot. Aug 13 00:27:42.459287 systemd-tmpfiles[1243]: Skipping /boot Aug 13 00:27:42.466388 systemd-tmpfiles[1243]: Detected autofs mount point /boot during canonicalization of boot. Aug 13 00:27:42.466397 systemd-tmpfiles[1243]: Skipping /boot Aug 13 00:27:42.497695 zram_generator::config[1271]: No configuration found. Aug 13 00:27:42.587965 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. Aug 13 00:27:42.632840 systemd[1]: Reloading finished in 189 ms. Aug 13 00:27:42.646863 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 13 00:27:42.659109 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Aug 13 00:27:42.666922 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 13 00:27:42.669531 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 13 00:27:42.673910 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 13 00:27:42.679048 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 13 00:27:42.682001 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 13 00:27:42.685995 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 13 00:27:42.690474 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:27:42.693932 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 00:27:42.696086 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 00:27:42.700937 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 00:27:42.702000 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:27:42.704382 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 13 00:27:42.706181 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 00:27:42.707999 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 00:27:42.709517 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 00:27:42.709674 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 00:27:42.715871 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 13 00:27:42.718407 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:27:42.722022 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 00:27:42.724332 systemd-udevd[1318]: Using default interface naming scheme 'v255'. Aug 13 00:27:42.726944 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 00:27:42.730208 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:27:42.731915 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 13 00:27:42.735319 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 13 00:27:42.737251 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 00:27:42.737421 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 00:27:42.740515 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 00:27:42.740681 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 00:27:42.745612 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 13 00:27:42.747391 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 00:27:42.747538 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 00:27:42.749624 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 13 00:27:42.750887 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 13 00:27:42.756513 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 13 00:27:42.779752 systemd[1]: Finished ensure-sysext.service. Aug 13 00:27:42.790682 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 13 00:27:42.797704 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1363) Aug 13 00:27:42.801886 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 13 00:27:42.807013 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 13 00:27:42.809820 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 13 00:27:42.811765 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 13 00:27:42.813920 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 13 00:27:42.816882 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 13 00:27:42.835963 augenrules[1380]: No rules Aug 13 00:27:42.837881 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Aug 13 00:27:42.840698 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). Aug 13 00:27:42.841329 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 13 00:27:42.842633 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 13 00:27:42.843023 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 13 00:27:42.844181 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 13 00:27:42.844321 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 13 00:27:42.845464 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 13 00:27:42.845610 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 13 00:27:42.847043 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 13 00:27:42.847185 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 13 00:27:42.848037 systemd-resolved[1315]: Positive Trust Anchors: Aug 13 00:27:42.848052 systemd-resolved[1315]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 13 00:27:42.848085 systemd-resolved[1315]: 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 Aug 13 00:27:42.849904 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Aug 13 00:27:42.859151 systemd-resolved[1315]: Defaulting to hostname 'linux'. Aug 13 00:27:42.862394 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 13 00:27:42.864638 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 13 00:27:42.865771 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 13 00:27:42.874873 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 13 00:27:42.875844 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 13 00:27:42.875913 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 13 00:27:42.899165 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 13 00:27:42.906928 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Aug 13 00:27:42.908547 systemd[1]: Reached target time-set.target - System Time Set. Aug 13 00:27:42.912501 systemd-networkd[1374]: lo: Link UP Aug 13 00:27:42.912514 systemd-networkd[1374]: lo: Gained carrier Aug 13 00:27:42.913309 systemd-networkd[1374]: Enumeration completed Aug 13 00:27:42.913823 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 13 00:27:42.914937 systemd[1]: Reached target network.target - Network. Aug 13 00:27:42.917142 systemd-networkd[1374]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:27:42.917151 systemd-networkd[1374]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 13 00:27:42.917859 systemd-networkd[1374]: eth0: Link UP Aug 13 00:27:42.917865 systemd-networkd[1374]: eth0: Gained carrier Aug 13 00:27:42.917880 systemd-networkd[1374]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 13 00:27:42.923875 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 13 00:27:42.934785 systemd-networkd[1374]: eth0: DHCPv4 address 10.0.0.157/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 13 00:27:42.935415 systemd-timesyncd[1381]: Network configuration changed, trying to establish connection. Aug 13 00:27:42.936174 systemd-timesyncd[1381]: Contacted time server 10.0.0.1:123 (10.0.0.1). Aug 13 00:27:42.936280 systemd-timesyncd[1381]: Initial clock synchronization to Wed 2025-08-13 00:27:43.173034 UTC. Aug 13 00:27:42.966859 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 13 00:27:42.978518 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 13 00:27:42.991900 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 13 00:27:43.012699 lvm[1403]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 13 00:27:43.017738 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 13 00:27:43.043516 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 13 00:27:43.044906 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 13 00:27:43.045845 systemd[1]: Reached target sysinit.target - System Initialization. Aug 13 00:27:43.046800 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 13 00:27:43.047740 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 13 00:27:43.048842 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 13 00:27:43.049872 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 13 00:27:43.051163 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 13 00:27:43.052493 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 13 00:27:43.052530 systemd[1]: Reached target paths.target - Path Units. Aug 13 00:27:43.053561 systemd[1]: Reached target timers.target - Timer Units. Aug 13 00:27:43.055471 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 13 00:27:43.058121 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 13 00:27:43.072915 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 13 00:27:43.075184 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 13 00:27:43.076644 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 13 00:27:43.077542 systemd[1]: Reached target sockets.target - Socket Units. Aug 13 00:27:43.078334 systemd[1]: Reached target basic.target - Basic System. Aug 13 00:27:43.079122 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 13 00:27:43.079166 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 13 00:27:43.080226 systemd[1]: Starting containerd.service - containerd container runtime... Aug 13 00:27:43.082177 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 13 00:27:43.085061 lvm[1410]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 13 00:27:43.086303 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 13 00:27:43.093859 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 13 00:27:43.094755 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). Aug 13 00:27:43.096060 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 13 00:27:43.100207 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 13 00:27:43.103905 jq[1413]: false Aug 13 00:27:43.106115 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 13 00:27:43.115631 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 13 00:27:43.123154 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 13 00:27:43.123668 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 13 00:27:43.126100 systemd[1]: Starting update-engine.service - Update Engine... Aug 13 00:27:43.130522 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 13 00:27:43.133322 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 13 00:27:43.135110 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 13 00:27:43.135524 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 13 00:27:43.136518 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 13 00:27:43.145006 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 13 00:27:43.146928 jq[1425]: true Aug 13 00:27:43.149430 extend-filesystems[1414]: Found loop3 Aug 13 00:27:43.158932 extend-filesystems[1414]: Found loop4 Aug 13 00:27:43.158932 extend-filesystems[1414]: Found loop5 Aug 13 00:27:43.158932 extend-filesystems[1414]: Found vda Aug 13 00:27:43.158932 extend-filesystems[1414]: Found vda1 Aug 13 00:27:43.158932 extend-filesystems[1414]: Found vda2 Aug 13 00:27:43.158932 extend-filesystems[1414]: Found vda3 Aug 13 00:27:43.158932 extend-filesystems[1414]: Found usr Aug 13 00:27:43.158932 extend-filesystems[1414]: Found vda4 Aug 13 00:27:43.158932 extend-filesystems[1414]: Found vda6 Aug 13 00:27:43.158932 extend-filesystems[1414]: Found vda7 Aug 13 00:27:43.158932 extend-filesystems[1414]: Found vda9 Aug 13 00:27:43.158932 extend-filesystems[1414]: Checking size of /dev/vda9 Aug 13 00:27:43.177239 extend-filesystems[1414]: Resized partition /dev/vda9 Aug 13 00:27:43.162644 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 13 00:27:43.162171 dbus-daemon[1412]: [system] SELinux support is enabled Aug 13 00:27:43.183205 extend-filesystems[1442]: resize2fs 1.47.1 (20-May-2024) Aug 13 00:27:43.183193 (ntainerd)[1443]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 13 00:27:43.189283 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Aug 13 00:27:43.189327 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (1336) Aug 13 00:27:43.189341 jq[1438]: true Aug 13 00:27:43.183247 systemd[1]: motdgen.service: Deactivated successfully. Aug 13 00:27:43.183454 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 13 00:27:43.198925 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). Aug 13 00:27:43.199037 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 13 00:27:43.215993 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). Aug 13 00:27:43.216026 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 13 00:27:43.248360 update_engine[1424]: I20250813 00:27:43.247167 1424 main.cc:92] Flatcar Update Engine starting Aug 13 00:27:43.252682 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Aug 13 00:27:43.276866 update_engine[1424]: I20250813 00:27:43.254933 1424 update_check_scheduler.cc:74] Next update check in 5m0s Aug 13 00:27:43.256977 systemd[1]: Started update-engine.service - Update Engine. Aug 13 00:27:43.265901 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 13 00:27:43.278266 systemd-logind[1421]: Watching system buttons on /dev/input/event0 (Power Button) Aug 13 00:27:43.286502 extend-filesystems[1442]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Aug 13 00:27:43.286502 extend-filesystems[1442]: old_desc_blocks = 1, new_desc_blocks = 1 Aug 13 00:27:43.286502 extend-filesystems[1442]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Aug 13 00:27:43.314800 extend-filesystems[1414]: Resized filesystem in /dev/vda9 Aug 13 00:27:43.289025 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 13 00:27:43.289220 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 13 00:27:43.295062 systemd-logind[1421]: New seat seat0. Aug 13 00:27:43.317250 systemd[1]: Started systemd-logind.service - User Login Management. Aug 13 00:27:43.322888 bash[1463]: Updated "/home/core/.ssh/authorized_keys" Aug 13 00:27:43.325135 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 13 00:27:43.327532 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 13 00:27:43.344374 locksmithd[1459]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Aug 13 00:27:43.500404 containerd[1443]: time="2025-08-13T00:27:43.500010382Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Aug 13 00:27:43.526645 containerd[1443]: time="2025-08-13T00:27:43.526591411Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 13 00:27:43.528290 containerd[1443]: time="2025-08-13T00:27:43.528248728Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.100-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 13 00:27:43.528417 containerd[1443]: time="2025-08-13T00:27:43.528400741Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 13 00:27:43.528481 containerd[1443]: time="2025-08-13T00:27:43.528468037Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 13 00:27:43.528726 containerd[1443]: time="2025-08-13T00:27:43.528704644Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 13 00:27:43.528812 containerd[1443]: time="2025-08-13T00:27:43.528796980Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 13 00:27:43.528937 containerd[1443]: time="2025-08-13T00:27:43.528916210Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 13 00:27:43.529009 containerd[1443]: time="2025-08-13T00:27:43.528994790Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 13 00:27:43.529261 containerd[1443]: time="2025-08-13T00:27:43.529239881Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Aug 13 00:27:43.529325 containerd[1443]: time="2025-08-13T00:27:43.529311790Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 13 00:27:43.529742 containerd[1443]: time="2025-08-13T00:27:43.529372290Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Aug 13 00:27:43.529742 containerd[1443]: time="2025-08-13T00:27:43.529388723Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 13 00:27:43.529742 containerd[1443]: time="2025-08-13T00:27:43.529476364Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 13 00:27:43.529742 containerd[1443]: time="2025-08-13T00:27:43.529708070Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 13 00:27:43.530060 containerd[1443]: time="2025-08-13T00:27:43.530037465Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Aug 13 00:27:43.530120 containerd[1443]: time="2025-08-13T00:27:43.530107356Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 13 00:27:43.530264 containerd[1443]: time="2025-08-13T00:27:43.530245655Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 13 00:27:43.530369 containerd[1443]: time="2025-08-13T00:27:43.530353518Z" level=info msg="metadata content store policy set" policy=shared Aug 13 00:27:43.533597 containerd[1443]: time="2025-08-13T00:27:43.533568072Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 13 00:27:43.533738 containerd[1443]: time="2025-08-13T00:27:43.533722556Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 13 00:27:43.533803 containerd[1443]: time="2025-08-13T00:27:43.533789729Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 13 00:27:43.533885 containerd[1443]: time="2025-08-13T00:27:43.533872057Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 13 00:27:43.533990 containerd[1443]: time="2025-08-13T00:27:43.533974360Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 13 00:27:43.534194 containerd[1443]: time="2025-08-13T00:27:43.534173694Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 13 00:27:43.534514 containerd[1443]: time="2025-08-13T00:27:43.534492094Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 13 00:27:43.534706 containerd[1443]: time="2025-08-13T00:27:43.534664576Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 13 00:27:43.534782 containerd[1443]: time="2025-08-13T00:27:43.534766467Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 13 00:27:43.534840 containerd[1443]: time="2025-08-13T00:27:43.534826597Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 13 00:27:43.534922 containerd[1443]: time="2025-08-13T00:27:43.534905672Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 13 00:27:43.534997 containerd[1443]: time="2025-08-13T00:27:43.534982111Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 13 00:27:43.535050 containerd[1443]: time="2025-08-13T00:27:43.535037998Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 13 00:27:43.535105 containerd[1443]: time="2025-08-13T00:27:43.535092650Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 13 00:27:43.535177 containerd[1443]: time="2025-08-13T00:27:43.535162418Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 13 00:27:43.535241 containerd[1443]: time="2025-08-13T00:27:43.535227489Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 13 00:27:43.535295 containerd[1443]: time="2025-08-13T00:27:43.535282842Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535348779Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535378885Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535395112Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535410473Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535424353Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535437944Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535452564Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535466279Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535480035Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535493214Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535511829Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535524061Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535536293Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535551696Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.535858 containerd[1443]: time="2025-08-13T00:27:43.535568088Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 13 00:27:43.536170 containerd[1443]: time="2025-08-13T00:27:43.535593128Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.536170 containerd[1443]: time="2025-08-13T00:27:43.535610755Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.536170 containerd[1443]: time="2025-08-13T00:27:43.535625211Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 13 00:27:43.536866 containerd[1443]: time="2025-08-13T00:27:43.536829536Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 13 00:27:43.536958 containerd[1443]: time="2025-08-13T00:27:43.536941518Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Aug 13 00:27:43.537045 containerd[1443]: time="2025-08-13T00:27:43.537029365Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 13 00:27:43.537119 containerd[1443]: time="2025-08-13T00:27:43.537101191Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 13 00:27:43.537168 containerd[1443]: time="2025-08-13T00:27:43.537155843Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.537228 containerd[1443]: time="2025-08-13T00:27:43.537215479Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 13 00:27:43.537282 containerd[1443]: time="2025-08-13T00:27:43.537268525Z" level=info msg="NRI interface is disabled by configuration." Aug 13 00:27:43.537337 containerd[1443]: time="2025-08-13T00:27:43.537323960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 13 00:27:43.537824 containerd[1443]: time="2025-08-13T00:27:43.537757429Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Aug 13 00:27:43.538001 containerd[1443]: time="2025-08-13T00:27:43.537983493Z" level=info msg="Connect containerd service" Aug 13 00:27:43.538100 containerd[1443]: time="2025-08-13T00:27:43.538085796Z" level=info msg="using legacy CRI server" Aug 13 00:27:43.538150 containerd[1443]: time="2025-08-13T00:27:43.538137606Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 13 00:27:43.538299 containerd[1443]: time="2025-08-13T00:27:43.538282288Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 13 00:27:43.539160 containerd[1443]: time="2025-08-13T00:27:43.539079461Z" 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" Aug 13 00:27:43.539373 containerd[1443]: time="2025-08-13T00:27:43.539345474Z" level=info msg="Start subscribing containerd event" Aug 13 00:27:43.539453 containerd[1443]: time="2025-08-13T00:27:43.539438510Z" level=info msg="Start recovering state" Aug 13 00:27:43.539566 containerd[1443]: time="2025-08-13T00:27:43.539551109Z" level=info msg="Start event monitor" Aug 13 00:27:43.539631 containerd[1443]: time="2025-08-13T00:27:43.539615110Z" level=info msg="Start snapshots syncer" Aug 13 00:27:43.539721 containerd[1443]: time="2025-08-13T00:27:43.539690231Z" level=info msg="Start cni network conf syncer for default" Aug 13 00:27:43.539797 containerd[1443]: time="2025-08-13T00:27:43.539784009Z" level=info msg="Start streaming server" Aug 13 00:27:43.539915 containerd[1443]: time="2025-08-13T00:27:43.539818810Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 13 00:27:43.539991 containerd[1443]: time="2025-08-13T00:27:43.539973047Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 13 00:27:43.541161 containerd[1443]: time="2025-08-13T00:27:43.540035113Z" level=info msg="containerd successfully booted in 0.041090s" Aug 13 00:27:43.540132 systemd[1]: Started containerd.service - containerd container runtime. Aug 13 00:27:43.662404 sshd_keygen[1427]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 13 00:27:43.682380 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 13 00:27:43.695371 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 13 00:27:43.702568 systemd[1]: issuegen.service: Deactivated successfully. Aug 13 00:27:43.702887 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 13 00:27:43.706662 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 13 00:27:43.725774 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 13 00:27:43.729089 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 13 00:27:43.731647 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Aug 13 00:27:43.733091 systemd[1]: Reached target getty.target - Login Prompts. Aug 13 00:27:44.344244 systemd-networkd[1374]: eth0: Gained IPv6LL Aug 13 00:27:44.346995 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 13 00:27:44.348569 systemd[1]: Reached target network-online.target - Network is Online. Aug 13 00:27:44.359973 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 13 00:27:44.365323 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 00:27:44.367379 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 13 00:27:44.381981 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 13 00:27:44.382180 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 13 00:27:44.384208 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 13 00:27:44.392199 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 13 00:27:44.930908 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 00:27:44.932348 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 13 00:27:44.935856 systemd[1]: Startup finished in 547ms (kernel) + 4.653s (initrd) + 3.615s (userspace) = 8.816s. Aug 13 00:27:44.935933 (kubelet)[1519]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Aug 13 00:27:45.372326 kubelet[1519]: E0813 00:27:45.372209 1519 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" Aug 13 00:27:45.374481 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Aug 13 00:27:45.374627 systemd[1]: kubelet.service: Failed with result 'exit-code'. Aug 13 00:27:50.015721 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 13 00:27:50.018272 systemd[1]: Started sshd@0-10.0.0.157:22-10.0.0.1:52512.service - OpenSSH per-connection server daemon (10.0.0.1:52512). Aug 13 00:27:50.083035 sshd[1532]: Accepted publickey for core from 10.0.0.1 port 52512 ssh2: RSA SHA256:xv2nBVgCAUDE9/psT+0gyR3NWqhWRcWqt2l4ADAtRXs Aug 13 00:27:50.085169 sshd[1532]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:27:50.099962 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 13 00:27:50.111955 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 13 00:27:50.113785 systemd-logind[1421]: New session 1 of user core. Aug 13 00:27:50.123694 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 13 00:27:50.125937 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 13 00:27:50.132730 (systemd)[1536]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 13 00:27:50.234307 systemd[1536]: Queued start job for default target default.target. Aug 13 00:27:50.245753 systemd[1536]: Created slice app.slice - User Application Slice. Aug 13 00:27:50.245785 systemd[1536]: Reached target paths.target - Paths. Aug 13 00:27:50.245798 systemd[1536]: Reached target timers.target - Timers. Aug 13 00:27:50.247089 systemd[1536]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 13 00:27:50.257946 systemd[1536]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 13 00:27:50.258067 systemd[1536]: Reached target sockets.target - Sockets. Aug 13 00:27:50.258080 systemd[1536]: Reached target basic.target - Basic System. Aug 13 00:27:50.258117 systemd[1536]: Reached target default.target - Main User Target. Aug 13 00:27:50.258145 systemd[1536]: Startup finished in 119ms. Aug 13 00:27:50.258280 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 13 00:27:50.259701 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 13 00:27:50.322838 systemd[1]: Started sshd@1-10.0.0.157:22-10.0.0.1:52514.service - OpenSSH per-connection server daemon (10.0.0.1:52514). Aug 13 00:27:50.371323 sshd[1547]: Accepted publickey for core from 10.0.0.1 port 52514 ssh2: RSA SHA256:xv2nBVgCAUDE9/psT+0gyR3NWqhWRcWqt2l4ADAtRXs Aug 13 00:27:50.372615 sshd[1547]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:27:50.377213 systemd-logind[1421]: New session 2 of user core. Aug 13 00:27:50.387828 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 13 00:27:50.440079 sshd[1547]: pam_unix(sshd:session): session closed for user core Aug 13 00:27:50.454219 systemd[1]: sshd@1-10.0.0.157:22-10.0.0.1:52514.service: Deactivated successfully. Aug 13 00:27:50.457305 systemd[1]: session-2.scope: Deactivated successfully. Aug 13 00:27:50.458599 systemd-logind[1421]: Session 2 logged out. Waiting for processes to exit. Aug 13 00:27:50.459858 systemd[1]: Started sshd@2-10.0.0.157:22-10.0.0.1:52526.service - OpenSSH per-connection server daemon (10.0.0.1:52526). Aug 13 00:27:50.460683 systemd-logind[1421]: Removed session 2. Aug 13 00:27:50.498803 sshd[1554]: Accepted publickey for core from 10.0.0.1 port 52526 ssh2: RSA SHA256:xv2nBVgCAUDE9/psT+0gyR3NWqhWRcWqt2l4ADAtRXs Aug 13 00:27:50.500178 sshd[1554]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:27:50.504717 systemd-logind[1421]: New session 3 of user core. Aug 13 00:27:50.521850 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 13 00:27:50.571212 sshd[1554]: pam_unix(sshd:session): session closed for user core Aug 13 00:27:50.581288 systemd[1]: sshd@2-10.0.0.157:22-10.0.0.1:52526.service: Deactivated successfully. Aug 13 00:27:50.584779 systemd[1]: session-3.scope: Deactivated successfully. Aug 13 00:27:50.586057 systemd-logind[1421]: Session 3 logged out. Waiting for processes to exit. Aug 13 00:27:50.597983 systemd[1]: Started sshd@3-10.0.0.157:22-10.0.0.1:52530.service - OpenSSH per-connection server daemon (10.0.0.1:52530). Aug 13 00:27:50.598904 systemd-logind[1421]: Removed session 3. Aug 13 00:27:50.638408 sshd[1561]: Accepted publickey for core from 10.0.0.1 port 52530 ssh2: RSA SHA256:xv2nBVgCAUDE9/psT+0gyR3NWqhWRcWqt2l4ADAtRXs Aug 13 00:27:50.639882 sshd[1561]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:27:50.644324 systemd-logind[1421]: New session 4 of user core. Aug 13 00:27:50.659864 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 13 00:27:50.714923 sshd[1561]: pam_unix(sshd:session): session closed for user core Aug 13 00:27:50.724111 systemd[1]: sshd@3-10.0.0.157:22-10.0.0.1:52530.service: Deactivated successfully. Aug 13 00:27:50.725528 systemd[1]: session-4.scope: Deactivated successfully. Aug 13 00:27:50.728769 systemd-logind[1421]: Session 4 logged out. Waiting for processes to exit. Aug 13 00:27:50.738031 systemd[1]: Started sshd@4-10.0.0.157:22-10.0.0.1:52538.service - OpenSSH per-connection server daemon (10.0.0.1:52538). Aug 13 00:27:50.738937 systemd-logind[1421]: Removed session 4. Aug 13 00:27:50.773790 sshd[1568]: Accepted publickey for core from 10.0.0.1 port 52538 ssh2: RSA SHA256:xv2nBVgCAUDE9/psT+0gyR3NWqhWRcWqt2l4ADAtRXs Aug 13 00:27:50.775213 sshd[1568]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Aug 13 00:27:50.779491 systemd-logind[1421]: New session 5 of user core. Aug 13 00:27:50.787857 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 13 00:27:50.860056 sudo[1571]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Aug 13 00:27:50.860378 sudo[1571]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Aug 13 00:27:50.884058 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 13 00:27:50.902467 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 13 00:27:50.902697 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 13 00:27:51.351604 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 00:27:51.360910 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 00:27:51.390205 systemd[1]: Reloading requested from client PID 1613 ('systemctl') (unit session-5.scope)... Aug 13 00:27:51.390220 systemd[1]: Reloading... Aug 13 00:27:51.460692 zram_generator::config[1655]: No configuration found. Aug 13 00:27:51.798161 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. Aug 13 00:27:51.865376 systemd[1]: Reloading finished in 474 ms. Aug 13 00:27:51.910947 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Aug 13 00:27:51.911016 systemd[1]: kubelet.service: Failed with result 'signal'. Aug 13 00:27:51.911239 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 00:27:51.915159 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Aug 13 00:27:52.017883 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Aug 13 00:27:52.022144 (kubelet)[1697]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Aug 13 00:27:52.061910 kubelet[1697]: 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. Aug 13 00:27:52.061910 kubelet[1697]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Aug 13 00:27:52.061910 kubelet[1697]: 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. Aug 13 00:27:52.062225 kubelet[1697]: I0813 00:27:52.061938 1697 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" Aug 13 00:27:52.955538 kubelet[1697]: I0813 00:27:52.955471 1697 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Aug 13 00:27:52.955538 kubelet[1697]: I0813 00:27:52.955515 1697 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Aug 13 00:27:52.955840 kubelet[1697]: I0813 00:27:52.955809 1697 server.go:954] "Client rotation is on, will bootstrap in background" Aug 13 00:27:53.006773 kubelet[1697]: I0813 00:27:53.006702 1697 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Aug 13 00:27:53.013969 kubelet[1697]: E0813 00:27:53.013906 1697 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Aug 13 00:27:53.013969 kubelet[1697]: I0813 00:27:53.013949 1697 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Aug 13 00:27:53.016996 kubelet[1697]: I0813 00:27:53.016964 1697 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Aug 13 00:27:53.017325 kubelet[1697]: I0813 00:27:53.017282 1697 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Aug 13 00:27:53.017488 kubelet[1697]: I0813 00:27:53.017313 1697 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.157","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Aug 13 00:27:53.017629 kubelet[1697]: I0813 00:27:53.017610 1697 topology_manager.go:138] "Creating topology manager with none policy" Aug 13 00:27:53.017629 kubelet[1697]: I0813 00:27:53.017622 1697 container_manager_linux.go:304] "Creating device plugin manager" Aug 13 00:27:53.017906 kubelet[1697]: I0813 00:27:53.017881 1697 state_mem.go:36] "Initialized new in-memory state store" Aug 13 00:27:53.021739 kubelet[1697]: I0813 00:27:53.021697 1697 kubelet.go:446] "Attempting to sync node with API server" Aug 13 00:27:53.021739 kubelet[1697]: I0813 00:27:53.021728 1697 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Aug 13 00:27:53.021838 kubelet[1697]: I0813 00:27:53.021748 1697 kubelet.go:352] "Adding apiserver pod source" Aug 13 00:27:53.021838 kubelet[1697]: I0813 00:27:53.021757 1697 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Aug 13 00:27:53.025415 kubelet[1697]: E0813 00:27:53.025378 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:27:53.025460 kubelet[1697]: E0813 00:27:53.025419 1697 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:27:53.033021 kubelet[1697]: I0813 00:27:53.032989 1697 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Aug 13 00:27:53.033780 kubelet[1697]: I0813 00:27:53.033747 1697 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Aug 13 00:27:53.033919 kubelet[1697]: W0813 00:27:53.033894 1697 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Aug 13 00:27:53.035679 kubelet[1697]: I0813 00:27:53.035641 1697 watchdog_linux.go:99] "Systemd watchdog is not enabled" Aug 13 00:27:53.035710 kubelet[1697]: I0813 00:27:53.035689 1697 server.go:1287] "Started kubelet" Aug 13 00:27:53.035855 kubelet[1697]: I0813 00:27:53.035819 1697 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Aug 13 00:27:53.036737 kubelet[1697]: I0813 00:27:53.036709 1697 server.go:479] "Adding debug handlers to kubelet server" Aug 13 00:27:53.037848 kubelet[1697]: I0813 00:27:53.037705 1697 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Aug 13 00:27:53.039980 kubelet[1697]: I0813 00:27:53.039827 1697 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Aug 13 00:27:53.040540 kubelet[1697]: I0813 00:27:53.040279 1697 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Aug 13 00:27:53.041278 kubelet[1697]: I0813 00:27:53.041243 1697 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Aug 13 00:27:53.041559 kubelet[1697]: E0813 00:27:53.041540 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:53.042716 kubelet[1697]: I0813 00:27:53.042691 1697 factory.go:221] Registration of the systemd container factory successfully Aug 13 00:27:53.043310 kubelet[1697]: I0813 00:27:53.043281 1697 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 Aug 13 00:27:53.044012 kubelet[1697]: I0813 00:27:53.042951 1697 volume_manager.go:297] "Starting Kubelet Volume Manager" Aug 13 00:27:53.044012 kubelet[1697]: I0813 00:27:53.042963 1697 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Aug 13 00:27:53.044012 kubelet[1697]: I0813 00:27:53.043950 1697 reconciler.go:26] "Reconciler: start to sync state" Aug 13 00:27:53.045526 kubelet[1697]: I0813 00:27:53.045500 1697 factory.go:221] Registration of the containerd container factory successfully Aug 13 00:27:53.055024 kubelet[1697]: E0813 00:27:53.054746 1697 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.157.185b2c036e296675 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.157,UID:10.0.0.157,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.157,},FirstTimestamp:2025-08-13 00:27:53.035671157 +0000 UTC m=+1.010397344,LastTimestamp:2025-08-13 00:27:53.035671157 +0000 UTC m=+1.010397344,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.157,}" Aug 13 00:27:53.055024 kubelet[1697]: E0813 00:27:53.055004 1697 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.157\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Aug 13 00:27:53.055190 kubelet[1697]: W0813 00:27:53.055075 1697 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 Aug 13 00:27:53.055190 kubelet[1697]: E0813 00:27:53.055103 1697 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" Aug 13 00:27:53.055190 kubelet[1697]: W0813 00:27:53.055165 1697 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Aug 13 00:27:53.055190 kubelet[1697]: E0813 00:27:53.055176 1697 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:anonymous\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError" Aug 13 00:27:53.055601 kubelet[1697]: I0813 00:27:53.055578 1697 cpu_manager.go:221] "Starting CPU manager" policy="none" Aug 13 00:27:53.055601 kubelet[1697]: I0813 00:27:53.055597 1697 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Aug 13 00:27:53.055687 kubelet[1697]: I0813 00:27:53.055614 1697 state_mem.go:36] "Initialized new in-memory state store" Aug 13 00:27:53.056499 kubelet[1697]: W0813 00:27:53.056458 1697 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.157" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Aug 13 00:27:53.056499 kubelet[1697]: E0813 00:27:53.056495 1697 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.157\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Aug 13 00:27:53.058448 kubelet[1697]: E0813 00:27:53.058348 1697 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.157.185b2c036f41c0b2 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.157,UID:10.0.0.157,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientMemory,Message:Node 10.0.0.157 status is now: NodeHasSufficientMemory,Source:EventSource{Component:kubelet,Host:10.0.0.157,},FirstTimestamp:2025-08-13 00:27:53.054044338 +0000 UTC m=+1.028770524,LastTimestamp:2025-08-13 00:27:53.054044338 +0000 UTC m=+1.028770524,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.157,}" Aug 13 00:27:53.129458 kubelet[1697]: I0813 00:27:53.129405 1697 policy_none.go:49] "None policy: Start" Aug 13 00:27:53.129458 kubelet[1697]: I0813 00:27:53.129443 1697 memory_manager.go:186] "Starting memorymanager" policy="None" Aug 13 00:27:53.129458 kubelet[1697]: I0813 00:27:53.129456 1697 state_mem.go:35] "Initializing new in-memory state store" Aug 13 00:27:53.138110 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Aug 13 00:27:53.142769 kubelet[1697]: E0813 00:27:53.142732 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:53.149204 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Aug 13 00:27:53.152708 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Aug 13 00:27:53.155102 kubelet[1697]: I0813 00:27:53.155046 1697 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Aug 13 00:27:53.156039 kubelet[1697]: I0813 00:27:53.156001 1697 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Aug 13 00:27:53.156039 kubelet[1697]: I0813 00:27:53.156030 1697 status_manager.go:227] "Starting to sync pod status with apiserver" Aug 13 00:27:53.156365 kubelet[1697]: I0813 00:27:53.156050 1697 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Aug 13 00:27:53.156365 kubelet[1697]: I0813 00:27:53.156057 1697 kubelet.go:2382] "Starting kubelet main sync loop" Aug 13 00:27:53.156365 kubelet[1697]: E0813 00:27:53.156098 1697 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]" Aug 13 00:27:53.158896 kubelet[1697]: I0813 00:27:53.158869 1697 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Aug 13 00:27:53.159309 kubelet[1697]: I0813 00:27:53.159064 1697 eviction_manager.go:189] "Eviction manager: starting control loop" Aug 13 00:27:53.159309 kubelet[1697]: I0813 00:27:53.159082 1697 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Aug 13 00:27:53.159545 kubelet[1697]: I0813 00:27:53.159466 1697 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Aug 13 00:27:53.160944 kubelet[1697]: E0813 00:27:53.160911 1697 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Aug 13 00:27:53.161015 kubelet[1697]: E0813 00:27:53.160954 1697 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.157\" not found" Aug 13 00:27:53.259353 kubelet[1697]: E0813 00:27:53.259198 1697 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.157\" not found" node="10.0.0.157" Aug 13 00:27:53.260741 kubelet[1697]: I0813 00:27:53.260705 1697 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.157" Aug 13 00:27:53.266314 kubelet[1697]: I0813 00:27:53.266287 1697 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.157" Aug 13 00:27:53.266372 kubelet[1697]: E0813 00:27:53.266315 1697 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.157\": node \"10.0.0.157\" not found" Aug 13 00:27:53.286976 kubelet[1697]: E0813 00:27:53.286939 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:53.387078 kubelet[1697]: E0813 00:27:53.387016 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:53.487418 kubelet[1697]: E0813 00:27:53.487377 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:53.552050 sudo[1571]: pam_unix(sudo:session): session closed for user root Aug 13 00:27:53.556046 sshd[1568]: pam_unix(sshd:session): session closed for user core Aug 13 00:27:53.559898 systemd[1]: sshd@4-10.0.0.157:22-10.0.0.1:52538.service: Deactivated successfully. Aug 13 00:27:53.563398 systemd[1]: session-5.scope: Deactivated successfully. Aug 13 00:27:53.564890 systemd-logind[1421]: Session 5 logged out. Waiting for processes to exit. Aug 13 00:27:53.565863 systemd-logind[1421]: Removed session 5. Aug 13 00:27:53.587795 kubelet[1697]: E0813 00:27:53.587751 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:53.687963 kubelet[1697]: E0813 00:27:53.687907 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:53.788876 kubelet[1697]: E0813 00:27:53.788823 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:53.889863 kubelet[1697]: E0813 00:27:53.889823 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:53.958835 kubelet[1697]: I0813 00:27:53.958793 1697 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Aug 13 00:27:53.959025 kubelet[1697]: W0813 00:27:53.958990 1697 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 Aug 13 00:27:53.990043 kubelet[1697]: E0813 00:27:53.990002 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:54.025615 kubelet[1697]: E0813 00:27:54.025570 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:27:54.090539 kubelet[1697]: E0813 00:27:54.090499 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:54.191638 kubelet[1697]: E0813 00:27:54.191512 1697 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.157\" not found" Aug 13 00:27:54.292915 kubelet[1697]: I0813 00:27:54.292873 1697 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Aug 13 00:27:54.293217 containerd[1443]: time="2025-08-13T00:27:54.293173907Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Aug 13 00:27:54.293479 kubelet[1697]: I0813 00:27:54.293373 1697 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Aug 13 00:27:55.025983 kubelet[1697]: E0813 00:27:55.025943 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:27:55.025983 kubelet[1697]: I0813 00:27:55.025961 1697 apiserver.go:52] "Watching apiserver" Aug 13 00:27:55.044138 kubelet[1697]: I0813 00:27:55.044048 1697 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Aug 13 00:27:55.045885 systemd[1]: Created slice kubepods-besteffort-podb1592a84_c338_46b4_8b12_67ab258299fd.slice - libcontainer container kubepods-besteffort-podb1592a84_c338_46b4_8b12_67ab258299fd.slice. Aug 13 00:27:55.055917 kubelet[1697]: I0813 00:27:55.055768 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/70490b33-cc01-4931-9fb7-553f3dc68018-run\") pod \"kube-flannel-ds-5m9wx\" (UID: \"70490b33-cc01-4931-9fb7-553f3dc68018\") " pod="kube-flannel/kube-flannel-ds-5m9wx" Aug 13 00:27:55.055917 kubelet[1697]: I0813 00:27:55.055805 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/70490b33-cc01-4931-9fb7-553f3dc68018-xtables-lock\") pod \"kube-flannel-ds-5m9wx\" (UID: \"70490b33-cc01-4931-9fb7-553f3dc68018\") " pod="kube-flannel/kube-flannel-ds-5m9wx" Aug 13 00:27:55.055917 kubelet[1697]: I0813 00:27:55.055825 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b1592a84-c338-46b4-8b12-67ab258299fd-kube-proxy\") pod \"kube-proxy-9vg2w\" (UID: \"b1592a84-c338-46b4-8b12-67ab258299fd\") " pod="kube-system/kube-proxy-9vg2w" Aug 13 00:27:55.055917 kubelet[1697]: I0813 00:27:55.055840 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b1592a84-c338-46b4-8b12-67ab258299fd-lib-modules\") pod \"kube-proxy-9vg2w\" (UID: \"b1592a84-c338-46b4-8b12-67ab258299fd\") " pod="kube-system/kube-proxy-9vg2w" Aug 13 00:27:55.055917 kubelet[1697]: I0813 00:27:55.055858 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/70490b33-cc01-4931-9fb7-553f3dc68018-cni-plugin\") pod \"kube-flannel-ds-5m9wx\" (UID: \"70490b33-cc01-4931-9fb7-553f3dc68018\") " pod="kube-flannel/kube-flannel-ds-5m9wx" Aug 13 00:27:55.056134 kubelet[1697]: I0813 00:27:55.055874 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/70490b33-cc01-4931-9fb7-553f3dc68018-cni\") pod \"kube-flannel-ds-5m9wx\" (UID: \"70490b33-cc01-4931-9fb7-553f3dc68018\") " pod="kube-flannel/kube-flannel-ds-5m9wx" Aug 13 00:27:55.056134 kubelet[1697]: I0813 00:27:55.055892 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/70490b33-cc01-4931-9fb7-553f3dc68018-flannel-cfg\") pod \"kube-flannel-ds-5m9wx\" (UID: \"70490b33-cc01-4931-9fb7-553f3dc68018\") " pod="kube-flannel/kube-flannel-ds-5m9wx" Aug 13 00:27:55.056134 kubelet[1697]: I0813 00:27:55.055907 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-pq2q4\" (UniqueName: \"kubernetes.io/projected/70490b33-cc01-4931-9fb7-553f3dc68018-kube-api-access-pq2q4\") pod \"kube-flannel-ds-5m9wx\" (UID: \"70490b33-cc01-4931-9fb7-553f3dc68018\") " pod="kube-flannel/kube-flannel-ds-5m9wx" Aug 13 00:27:55.056134 kubelet[1697]: I0813 00:27:55.055932 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b1592a84-c338-46b4-8b12-67ab258299fd-xtables-lock\") pod \"kube-proxy-9vg2w\" (UID: \"b1592a84-c338-46b4-8b12-67ab258299fd\") " pod="kube-system/kube-proxy-9vg2w" Aug 13 00:27:55.056134 kubelet[1697]: I0813 00:27:55.055947 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dgbpg\" (UniqueName: \"kubernetes.io/projected/b1592a84-c338-46b4-8b12-67ab258299fd-kube-api-access-dgbpg\") pod \"kube-proxy-9vg2w\" (UID: \"b1592a84-c338-46b4-8b12-67ab258299fd\") " pod="kube-system/kube-proxy-9vg2w" Aug 13 00:27:55.060263 systemd[1]: Created slice kubepods-burstable-pod70490b33_cc01_4931_9fb7_553f3dc68018.slice - libcontainer container kubepods-burstable-pod70490b33_cc01_4931_9fb7_553f3dc68018.slice. Aug 13 00:27:55.358322 kubelet[1697]: E0813 00:27:55.357373 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:27:55.358983 containerd[1443]: time="2025-08-13T00:27:55.358636930Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-9vg2w,Uid:b1592a84-c338-46b4-8b12-67ab258299fd,Namespace:kube-system,Attempt:0,}" Aug 13 00:27:55.368366 kubelet[1697]: E0813 00:27:55.368300 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:27:55.369087 containerd[1443]: time="2025-08-13T00:27:55.368797909Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-5m9wx,Uid:70490b33-cc01-4931-9fb7-553f3dc68018,Namespace:kube-flannel,Attempt:0,}" Aug 13 00:27:55.967279 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount146002327.mount: Deactivated successfully. Aug 13 00:27:55.972404 containerd[1443]: time="2025-08-13T00:27:55.972361873Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 13 00:27:55.973559 containerd[1443]: time="2025-08-13T00:27:55.973529035Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 13 00:27:55.974402 containerd[1443]: time="2025-08-13T00:27:55.974368091Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Aug 13 00:27:55.975114 containerd[1443]: time="2025-08-13T00:27:55.975086833Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 13 00:27:55.976880 containerd[1443]: time="2025-08-13T00:27:55.976842767Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Aug 13 00:27:55.977694 containerd[1443]: time="2025-08-13T00:27:55.977666773Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Aug 13 00:27:55.980615 containerd[1443]: time="2025-08-13T00:27:55.980152153Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 611.268173ms" Aug 13 00:27:55.980875 containerd[1443]: time="2025-08-13T00:27:55.980849569Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"268403\" in 622.103432ms" Aug 13 00:27:56.026045 kubelet[1697]: E0813 00:27:56.026013 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:27:56.093055 containerd[1443]: time="2025-08-13T00:27:56.092635869Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 00:27:56.093055 containerd[1443]: time="2025-08-13T00:27:56.092731686Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 00:27:56.093055 containerd[1443]: time="2025-08-13T00:27:56.092750424Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:27:56.093220 containerd[1443]: time="2025-08-13T00:27:56.092933092Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:27:56.093220 containerd[1443]: time="2025-08-13T00:27:56.092973100Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 00:27:56.093220 containerd[1443]: time="2025-08-13T00:27:56.093037514Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 00:27:56.093220 containerd[1443]: time="2025-08-13T00:27:56.093055126Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:27:56.093506 containerd[1443]: time="2025-08-13T00:27:56.093294167Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:27:56.217062 systemd[1]: run-containerd-runc-k8s.io-3bac036b9ea503f926dec259a64649c25ec6a6c7fad7309498a79c79a37d48fc-runc.muovMh.mount: Deactivated successfully. Aug 13 00:27:56.233907 systemd[1]: Started cri-containerd-3bac036b9ea503f926dec259a64649c25ec6a6c7fad7309498a79c79a37d48fc.scope - libcontainer container 3bac036b9ea503f926dec259a64649c25ec6a6c7fad7309498a79c79a37d48fc. Aug 13 00:27:56.235393 systemd[1]: Started cri-containerd-b0115532cae79167618a3ce818d128631ca0368520d5a221a7a751b624cca72d.scope - libcontainer container b0115532cae79167618a3ce818d128631ca0368520d5a221a7a751b624cca72d. Aug 13 00:27:56.256757 containerd[1443]: time="2025-08-13T00:27:56.256502918Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-9vg2w,Uid:b1592a84-c338-46b4-8b12-67ab258299fd,Namespace:kube-system,Attempt:0,} returns sandbox id \"3bac036b9ea503f926dec259a64649c25ec6a6c7fad7309498a79c79a37d48fc\"" Aug 13 00:27:56.260671 kubelet[1697]: E0813 00:27:56.258780 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:27:56.261442 containerd[1443]: time="2025-08-13T00:27:56.261398582Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.7\"" Aug 13 00:27:56.268885 containerd[1443]: time="2025-08-13T00:27:56.268848067Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-5m9wx,Uid:70490b33-cc01-4931-9fb7-553f3dc68018,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"b0115532cae79167618a3ce818d128631ca0368520d5a221a7a751b624cca72d\"" Aug 13 00:27:56.269671 kubelet[1697]: E0813 00:27:56.269633 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:27:57.027061 kubelet[1697]: E0813 00:27:57.027006 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:27:57.236308 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3241304492.mount: Deactivated successfully. Aug 13 00:27:57.460229 containerd[1443]: time="2025-08-13T00:27:57.459865342Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:27:57.461088 containerd[1443]: time="2025-08-13T00:27:57.461056559Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.7: active requests=0, bytes read=27378407" Aug 13 00:27:57.462175 containerd[1443]: time="2025-08-13T00:27:57.462122726Z" level=info msg="ImageCreate event name:\"sha256:176e5fd5af03be683be55601db94020ad4cc275f4cca27999608d3cf65c9fb11\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:27:57.464117 containerd[1443]: time="2025-08-13T00:27:57.464060738Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:8d589a18b5424f77a784ef2f00feffac0ef210414100822f1c120f0d7221def3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:27:57.464807 containerd[1443]: time="2025-08-13T00:27:57.464686946Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.7\" with image id \"sha256:176e5fd5af03be683be55601db94020ad4cc275f4cca27999608d3cf65c9fb11\", repo tag \"registry.k8s.io/kube-proxy:v1.32.7\", repo digest \"registry.k8s.io/kube-proxy@sha256:8d589a18b5424f77a784ef2f00feffac0ef210414100822f1c120f0d7221def3\", size \"27377424\" in 1.203239317s" Aug 13 00:27:57.464807 containerd[1443]: time="2025-08-13T00:27:57.464717324Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.7\" returns image reference \"sha256:176e5fd5af03be683be55601db94020ad4cc275f4cca27999608d3cf65c9fb11\"" Aug 13 00:27:57.465761 containerd[1443]: time="2025-08-13T00:27:57.465710319Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Aug 13 00:27:57.467211 containerd[1443]: time="2025-08-13T00:27:57.467169031Z" level=info msg="CreateContainer within sandbox \"3bac036b9ea503f926dec259a64649c25ec6a6c7fad7309498a79c79a37d48fc\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Aug 13 00:27:57.478814 containerd[1443]: time="2025-08-13T00:27:57.478760938Z" level=info msg="CreateContainer within sandbox \"3bac036b9ea503f926dec259a64649c25ec6a6c7fad7309498a79c79a37d48fc\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"7cb40b571e86061ada8004718dd2c5799c5cabffa7c5fba3d313e65f5856933b\"" Aug 13 00:27:57.479583 containerd[1443]: time="2025-08-13T00:27:57.479546992Z" level=info msg="StartContainer for \"7cb40b571e86061ada8004718dd2c5799c5cabffa7c5fba3d313e65f5856933b\"" Aug 13 00:27:57.502834 systemd[1]: Started cri-containerd-7cb40b571e86061ada8004718dd2c5799c5cabffa7c5fba3d313e65f5856933b.scope - libcontainer container 7cb40b571e86061ada8004718dd2c5799c5cabffa7c5fba3d313e65f5856933b. Aug 13 00:27:57.528056 containerd[1443]: time="2025-08-13T00:27:57.527944851Z" level=info msg="StartContainer for \"7cb40b571e86061ada8004718dd2c5799c5cabffa7c5fba3d313e65f5856933b\" returns successfully" Aug 13 00:27:58.027369 kubelet[1697]: E0813 00:27:58.027301 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:27:58.175917 kubelet[1697]: E0813 00:27:58.175376 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:27:58.613860 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3113345005.mount: Deactivated successfully. Aug 13 00:27:58.653388 containerd[1443]: time="2025-08-13T00:27:58.653323448Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:27:58.655182 containerd[1443]: time="2025-08-13T00:27:58.655137309Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Aug 13 00:27:58.656275 containerd[1443]: time="2025-08-13T00:27:58.656217850Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:27:58.659515 containerd[1443]: time="2025-08-13T00:27:58.659455820Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:27:58.660389 containerd[1443]: time="2025-08-13T00:27:58.660342308Z" 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.194489749s" Aug 13 00:27:58.660389 containerd[1443]: time="2025-08-13T00:27:58.660377970Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Aug 13 00:27:58.662913 containerd[1443]: time="2025-08-13T00:27:58.662816839Z" level=info msg="CreateContainer within sandbox \"b0115532cae79167618a3ce818d128631ca0368520d5a221a7a751b624cca72d\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Aug 13 00:27:58.680280 containerd[1443]: time="2025-08-13T00:27:58.680214937Z" level=info msg="CreateContainer within sandbox \"b0115532cae79167618a3ce818d128631ca0368520d5a221a7a751b624cca72d\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"ca96deb8e18bcf41a4c456e2f5931dfaf5c4a8c168eb2092f0c87a39ff9c4507\"" Aug 13 00:27:58.681053 containerd[1443]: time="2025-08-13T00:27:58.681014480Z" level=info msg="StartContainer for \"ca96deb8e18bcf41a4c456e2f5931dfaf5c4a8c168eb2092f0c87a39ff9c4507\"" Aug 13 00:27:58.706893 systemd[1]: Started cri-containerd-ca96deb8e18bcf41a4c456e2f5931dfaf5c4a8c168eb2092f0c87a39ff9c4507.scope - libcontainer container ca96deb8e18bcf41a4c456e2f5931dfaf5c4a8c168eb2092f0c87a39ff9c4507. Aug 13 00:27:58.733974 containerd[1443]: time="2025-08-13T00:27:58.733916752Z" level=info msg="StartContainer for \"ca96deb8e18bcf41a4c456e2f5931dfaf5c4a8c168eb2092f0c87a39ff9c4507\" returns successfully" Aug 13 00:27:58.739929 systemd[1]: cri-containerd-ca96deb8e18bcf41a4c456e2f5931dfaf5c4a8c168eb2092f0c87a39ff9c4507.scope: Deactivated successfully. Aug 13 00:27:58.914098 containerd[1443]: time="2025-08-13T00:27:58.913884643Z" level=info msg="shim disconnected" id=ca96deb8e18bcf41a4c456e2f5931dfaf5c4a8c168eb2092f0c87a39ff9c4507 namespace=k8s.io Aug 13 00:27:58.914098 containerd[1443]: time="2025-08-13T00:27:58.913940826Z" level=warning msg="cleaning up after shim disconnected" id=ca96deb8e18bcf41a4c456e2f5931dfaf5c4a8c168eb2092f0c87a39ff9c4507 namespace=k8s.io Aug 13 00:27:58.914098 containerd[1443]: time="2025-08-13T00:27:58.913949260Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 13 00:27:59.028380 kubelet[1697]: E0813 00:27:59.028321 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:27:59.178993 kubelet[1697]: E0813 00:27:59.178892 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:27:59.179642 kubelet[1697]: E0813 00:27:59.179604 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:27:59.179849 containerd[1443]: time="2025-08-13T00:27:59.179668240Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Aug 13 00:27:59.190194 kubelet[1697]: I0813 00:27:59.190089 1697 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-9vg2w" podStartSLOduration=4.985439464 podStartE2EDuration="6.190058168s" podCreationTimestamp="2025-08-13 00:27:53 +0000 UTC" firstStartedPulling="2025-08-13 00:27:56.260948444 +0000 UTC m=+4.235674630" lastFinishedPulling="2025-08-13 00:27:57.465567188 +0000 UTC m=+5.440293334" observedRunningTime="2025-08-13 00:27:58.189433327 +0000 UTC m=+6.164159513" watchObservedRunningTime="2025-08-13 00:27:59.190058168 +0000 UTC m=+7.164784314" Aug 13 00:28:00.028945 kubelet[1697]: E0813 00:28:00.028895 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:00.353925 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1560526866.mount: Deactivated successfully. Aug 13 00:28:00.862777 containerd[1443]: time="2025-08-13T00:28:00.862701847Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:00.863501 containerd[1443]: time="2025-08-13T00:28:00.863462849Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Aug 13 00:28:00.864501 containerd[1443]: time="2025-08-13T00:28:00.864450943Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:00.867799 containerd[1443]: time="2025-08-13T00:28:00.867743387Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:00.869206 containerd[1443]: time="2025-08-13T00:28:00.869169899Z" 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.689441496s" Aug 13 00:28:00.869268 containerd[1443]: time="2025-08-13T00:28:00.869206450Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Aug 13 00:28:00.873796 containerd[1443]: time="2025-08-13T00:28:00.873638009Z" level=info msg="CreateContainer within sandbox \"b0115532cae79167618a3ce818d128631ca0368520d5a221a7a751b624cca72d\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Aug 13 00:28:00.886116 containerd[1443]: time="2025-08-13T00:28:00.886050595Z" level=info msg="CreateContainer within sandbox \"b0115532cae79167618a3ce818d128631ca0368520d5a221a7a751b624cca72d\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"f772a9e188ed1ab49002c2c649e2e5a8e72380429eeeb73749316e97e448e34d\"" Aug 13 00:28:00.886884 containerd[1443]: time="2025-08-13T00:28:00.886847667Z" level=info msg="StartContainer for \"f772a9e188ed1ab49002c2c649e2e5a8e72380429eeeb73749316e97e448e34d\"" Aug 13 00:28:00.917036 systemd[1]: Started cri-containerd-f772a9e188ed1ab49002c2c649e2e5a8e72380429eeeb73749316e97e448e34d.scope - libcontainer container f772a9e188ed1ab49002c2c649e2e5a8e72380429eeeb73749316e97e448e34d. Aug 13 00:28:00.940494 containerd[1443]: time="2025-08-13T00:28:00.940406053Z" level=info msg="StartContainer for \"f772a9e188ed1ab49002c2c649e2e5a8e72380429eeeb73749316e97e448e34d\" returns successfully" Aug 13 00:28:00.965633 systemd[1]: cri-containerd-f772a9e188ed1ab49002c2c649e2e5a8e72380429eeeb73749316e97e448e34d.scope: Deactivated successfully. Aug 13 00:28:01.024136 kubelet[1697]: I0813 00:28:01.024014 1697 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Aug 13 00:28:01.029732 kubelet[1697]: E0813 00:28:01.029687 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:01.083945 containerd[1443]: time="2025-08-13T00:28:01.083874715Z" level=info msg="shim disconnected" id=f772a9e188ed1ab49002c2c649e2e5a8e72380429eeeb73749316e97e448e34d namespace=k8s.io Aug 13 00:28:01.083945 containerd[1443]: time="2025-08-13T00:28:01.083927816Z" level=warning msg="cleaning up after shim disconnected" id=f772a9e188ed1ab49002c2c649e2e5a8e72380429eeeb73749316e97e448e34d namespace=k8s.io Aug 13 00:28:01.083945 containerd[1443]: time="2025-08-13T00:28:01.083936319Z" level=info msg="cleaning up dead shim" namespace=k8s.io Aug 13 00:28:01.184478 kubelet[1697]: E0813 00:28:01.184432 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:28:01.185771 containerd[1443]: time="2025-08-13T00:28:01.185726611Z" level=info msg="CreateContainer within sandbox \"b0115532cae79167618a3ce818d128631ca0368520d5a221a7a751b624cca72d\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Aug 13 00:28:01.200078 containerd[1443]: time="2025-08-13T00:28:01.199980313Z" level=info msg="CreateContainer within sandbox \"b0115532cae79167618a3ce818d128631ca0368520d5a221a7a751b624cca72d\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"9b7bd014115a5a02e5c4dea9e9480999fecbc69981b846e1a06ee089062d1069\"" Aug 13 00:28:01.200812 containerd[1443]: time="2025-08-13T00:28:01.200519032Z" level=info msg="StartContainer for \"9b7bd014115a5a02e5c4dea9e9480999fecbc69981b846e1a06ee089062d1069\"" Aug 13 00:28:01.225857 systemd[1]: Started cri-containerd-9b7bd014115a5a02e5c4dea9e9480999fecbc69981b846e1a06ee089062d1069.scope - libcontainer container 9b7bd014115a5a02e5c4dea9e9480999fecbc69981b846e1a06ee089062d1069. Aug 13 00:28:01.253000 containerd[1443]: time="2025-08-13T00:28:01.252909852Z" level=info msg="StartContainer for \"9b7bd014115a5a02e5c4dea9e9480999fecbc69981b846e1a06ee089062d1069\" returns successfully" Aug 13 00:28:01.260672 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-f772a9e188ed1ab49002c2c649e2e5a8e72380429eeeb73749316e97e448e34d-rootfs.mount: Deactivated successfully. Aug 13 00:28:02.030592 kubelet[1697]: E0813 00:28:02.030510 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:02.188309 kubelet[1697]: E0813 00:28:02.188269 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:28:02.422773 systemd-networkd[1374]: flannel.1: Link UP Aug 13 00:28:02.422779 systemd-networkd[1374]: flannel.1: Gained carrier Aug 13 00:28:03.030706 kubelet[1697]: E0813 00:28:03.030660 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:03.189906 kubelet[1697]: E0813 00:28:03.189872 1697 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Aug 13 00:28:03.670898 systemd-networkd[1374]: flannel.1: Gained IPv6LL Aug 13 00:28:04.031592 kubelet[1697]: E0813 00:28:04.031455 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:04.504165 kubelet[1697]: I0813 00:28:04.504109 1697 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-5m9wx" podStartSLOduration=6.903939234 podStartE2EDuration="11.504088458s" podCreationTimestamp="2025-08-13 00:27:53 +0000 UTC" firstStartedPulling="2025-08-13 00:27:56.270454168 +0000 UTC m=+4.245180354" lastFinishedPulling="2025-08-13 00:28:00.870603392 +0000 UTC m=+8.845329578" observedRunningTime="2025-08-13 00:28:02.216615988 +0000 UTC m=+10.191342134" watchObservedRunningTime="2025-08-13 00:28:04.504088458 +0000 UTC m=+12.478814644" Aug 13 00:28:04.511989 systemd[1]: Created slice kubepods-besteffort-pod1558cbf7_4d34_420e_a32a_7c6c223a7c46.slice - libcontainer container kubepods-besteffort-pod1558cbf7_4d34_420e_a32a_7c6c223a7c46.slice. Aug 13 00:28:04.627075 kubelet[1697]: I0813 00:28:04.627028 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ksv84\" (UniqueName: \"kubernetes.io/projected/1558cbf7-4d34-420e-a32a-7c6c223a7c46-kube-api-access-ksv84\") pod \"nginx-deployment-7fcdb87857-cczbb\" (UID: \"1558cbf7-4d34-420e-a32a-7c6c223a7c46\") " pod="default/nginx-deployment-7fcdb87857-cczbb" Aug 13 00:28:04.816794 containerd[1443]: time="2025-08-13T00:28:04.816670811Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-cczbb,Uid:1558cbf7-4d34-420e-a32a-7c6c223a7c46,Namespace:default,Attempt:0,}" Aug 13 00:28:04.895242 systemd-networkd[1374]: cni0: Link UP Aug 13 00:28:04.895249 systemd-networkd[1374]: cni0: Gained carrier Aug 13 00:28:04.896325 systemd-networkd[1374]: cni0: Lost carrier Aug 13 00:28:04.900440 systemd-networkd[1374]: veth822feaf5: Link UP Aug 13 00:28:04.906228 kernel: cni0: port 1(veth822feaf5) entered blocking state Aug 13 00:28:04.906325 kernel: cni0: port 1(veth822feaf5) entered disabled state Aug 13 00:28:04.906342 kernel: veth822feaf5: entered allmulticast mode Aug 13 00:28:04.906366 kernel: veth822feaf5: entered promiscuous mode Aug 13 00:28:04.906382 kernel: cni0: port 1(veth822feaf5) entered blocking state Aug 13 00:28:04.906396 kernel: cni0: port 1(veth822feaf5) entered forwarding state Aug 13 00:28:04.907929 kernel: cni0: port 1(veth822feaf5) entered disabled state Aug 13 00:28:04.919455 systemd-networkd[1374]: veth822feaf5: Gained carrier Aug 13 00:28:04.920540 kernel: cni0: port 1(veth822feaf5) entered blocking state Aug 13 00:28:04.920569 kernel: cni0: port 1(veth822feaf5) entered forwarding state Aug 13 00:28:04.919682 systemd-networkd[1374]: cni0: Gained carrier Aug 13 00:28:04.922038 containerd[1443]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000016938), "name":"cbr0", "type":"bridge"} Aug 13 00:28:04.922038 containerd[1443]: delegateAdd: netconf sent to delegate plugin: Aug 13 00:28:04.945398 containerd[1443]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-08-13T00:28:04.945309135Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 00:28:04.945398 containerd[1443]: time="2025-08-13T00:28:04.945365837Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 00:28:04.945398 containerd[1443]: time="2025-08-13T00:28:04.945381505Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:28:04.945640 containerd[1443]: time="2025-08-13T00:28:04.945465696Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:28:04.958218 systemd[1]: run-containerd-runc-k8s.io-38341898e01e561ff58c32bd165fea079db0ae9f51e32b002fc98f20ee896932-runc.OxDjoR.mount: Deactivated successfully. Aug 13 00:28:04.974840 systemd[1]: Started cri-containerd-38341898e01e561ff58c32bd165fea079db0ae9f51e32b002fc98f20ee896932.scope - libcontainer container 38341898e01e561ff58c32bd165fea079db0ae9f51e32b002fc98f20ee896932. Aug 13 00:28:04.984496 systemd-resolved[1315]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 13 00:28:05.000106 containerd[1443]: time="2025-08-13T00:28:05.000050388Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-cczbb,Uid:1558cbf7-4d34-420e-a32a-7c6c223a7c46,Namespace:default,Attempt:0,} returns sandbox id \"38341898e01e561ff58c32bd165fea079db0ae9f51e32b002fc98f20ee896932\"" Aug 13 00:28:05.003053 containerd[1443]: time="2025-08-13T00:28:05.001205096Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 13 00:28:05.031910 kubelet[1697]: E0813 00:28:05.031858 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:06.032882 kubelet[1697]: E0813 00:28:06.032833 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:06.359823 systemd-networkd[1374]: cni0: Gained IPv6LL Aug 13 00:28:06.870859 systemd-networkd[1374]: veth822feaf5: Gained IPv6LL Aug 13 00:28:07.001909 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4075583383.mount: Deactivated successfully. Aug 13 00:28:07.033178 kubelet[1697]: E0813 00:28:07.033102 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:07.709179 containerd[1443]: time="2025-08-13T00:28:07.709131769Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:07.709828 containerd[1443]: time="2025-08-13T00:28:07.709789278Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69963857" Aug 13 00:28:07.710787 containerd[1443]: time="2025-08-13T00:28:07.710743383Z" level=info msg="ImageCreate event name:\"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:07.713566 containerd[1443]: time="2025-08-13T00:28:07.713531810Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:a6969d434cb816d30787e9f7ab16b632e12dc05a2c8f4dae701d83ef2199c985\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:07.715000 containerd[1443]: time="2025-08-13T00:28:07.714958322Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:a6969d434cb816d30787e9f7ab16b632e12dc05a2c8f4dae701d83ef2199c985\", size \"69963735\" in 2.713720893s" Aug 13 00:28:07.715043 containerd[1443]: time="2025-08-13T00:28:07.715002695Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\"" Aug 13 00:28:07.716874 containerd[1443]: time="2025-08-13T00:28:07.716843625Z" level=info msg="CreateContainer within sandbox \"38341898e01e561ff58c32bd165fea079db0ae9f51e32b002fc98f20ee896932\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Aug 13 00:28:07.726729 containerd[1443]: time="2025-08-13T00:28:07.726685236Z" level=info msg="CreateContainer within sandbox \"38341898e01e561ff58c32bd165fea079db0ae9f51e32b002fc98f20ee896932\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"2b9bcef7a0bf203513737d4500cf5b379267a9dd7857e361206779d6867e1652\"" Aug 13 00:28:07.727376 containerd[1443]: time="2025-08-13T00:28:07.727340623Z" level=info msg="StartContainer for \"2b9bcef7a0bf203513737d4500cf5b379267a9dd7857e361206779d6867e1652\"" Aug 13 00:28:07.764848 systemd[1]: Started cri-containerd-2b9bcef7a0bf203513737d4500cf5b379267a9dd7857e361206779d6867e1652.scope - libcontainer container 2b9bcef7a0bf203513737d4500cf5b379267a9dd7857e361206779d6867e1652. Aug 13 00:28:07.784457 containerd[1443]: time="2025-08-13T00:28:07.784416125Z" level=info msg="StartContainer for \"2b9bcef7a0bf203513737d4500cf5b379267a9dd7857e361206779d6867e1652\" returns successfully" Aug 13 00:28:08.033833 kubelet[1697]: E0813 00:28:08.033699 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:08.216404 kubelet[1697]: I0813 00:28:08.216307 1697 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-cczbb" podStartSLOduration=1.501425261 podStartE2EDuration="4.216290751s" podCreationTimestamp="2025-08-13 00:28:04 +0000 UTC" firstStartedPulling="2025-08-13 00:28:05.00084982 +0000 UTC m=+12.975576006" lastFinishedPulling="2025-08-13 00:28:07.71571531 +0000 UTC m=+15.690441496" observedRunningTime="2025-08-13 00:28:08.216207223 +0000 UTC m=+16.190933369" watchObservedRunningTime="2025-08-13 00:28:08.216290751 +0000 UTC m=+16.191016897" Aug 13 00:28:09.034264 kubelet[1697]: E0813 00:28:09.034220 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:10.035086 kubelet[1697]: E0813 00:28:10.035037 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:10.953460 systemd[1]: Created slice kubepods-besteffort-pod69349e52_11ed_4ab7_b6bd_ac5cdf32c12f.slice - libcontainer container kubepods-besteffort-pod69349e52_11ed_4ab7_b6bd_ac5cdf32c12f.slice. Aug 13 00:28:11.035288 kubelet[1697]: E0813 00:28:11.035247 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:11.062608 kubelet[1697]: I0813 00:28:11.062572 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-55gtk\" (UniqueName: \"kubernetes.io/projected/69349e52-11ed-4ab7-b6bd-ac5cdf32c12f-kube-api-access-55gtk\") pod \"nfs-server-provisioner-0\" (UID: \"69349e52-11ed-4ab7-b6bd-ac5cdf32c12f\") " pod="default/nfs-server-provisioner-0" Aug 13 00:28:11.062608 kubelet[1697]: I0813 00:28:11.062609 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/69349e52-11ed-4ab7-b6bd-ac5cdf32c12f-data\") pod \"nfs-server-provisioner-0\" (UID: \"69349e52-11ed-4ab7-b6bd-ac5cdf32c12f\") " pod="default/nfs-server-provisioner-0" Aug 13 00:28:11.257065 containerd[1443]: time="2025-08-13T00:28:11.256957989Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:69349e52-11ed-4ab7-b6bd-ac5cdf32c12f,Namespace:default,Attempt:0,}" Aug 13 00:28:11.281155 systemd-networkd[1374]: vethf96d8f2e: Link UP Aug 13 00:28:11.282782 kernel: cni0: port 2(vethf96d8f2e) entered blocking state Aug 13 00:28:11.282879 kernel: cni0: port 2(vethf96d8f2e) entered disabled state Aug 13 00:28:11.283012 kernel: vethf96d8f2e: entered allmulticast mode Aug 13 00:28:11.283053 kernel: vethf96d8f2e: entered promiscuous mode Aug 13 00:28:11.289343 systemd-networkd[1374]: vethf96d8f2e: Gained carrier Aug 13 00:28:11.289754 kernel: cni0: port 2(vethf96d8f2e) entered blocking state Aug 13 00:28:11.289801 kernel: cni0: port 2(vethf96d8f2e) entered forwarding state Aug 13 00:28:11.291671 containerd[1443]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Aug 13 00:28:11.291671 containerd[1443]: delegateAdd: netconf sent to delegate plugin: Aug 13 00:28:11.308189 containerd[1443]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-08-13T00:28:11.308100828Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 00:28:11.308189 containerd[1443]: time="2025-08-13T00:28:11.308154466Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 00:28:11.308189 containerd[1443]: time="2025-08-13T00:28:11.308169276Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:28:11.308459 containerd[1443]: time="2025-08-13T00:28:11.308256457Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:28:11.327857 systemd[1]: Started cri-containerd-20f822652f9b8529f238b1783c3ff8b468167a1ab4606e9c041f357818a65b53.scope - libcontainer container 20f822652f9b8529f238b1783c3ff8b468167a1ab4606e9c041f357818a65b53. Aug 13 00:28:11.337940 systemd-resolved[1315]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 13 00:28:11.354565 containerd[1443]: time="2025-08-13T00:28:11.354524264Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:69349e52-11ed-4ab7-b6bd-ac5cdf32c12f,Namespace:default,Attempt:0,} returns sandbox id \"20f822652f9b8529f238b1783c3ff8b468167a1ab4606e9c041f357818a65b53\"" Aug 13 00:28:11.356001 containerd[1443]: time="2025-08-13T00:28:11.355973084Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Aug 13 00:28:12.036122 kubelet[1697]: E0813 00:28:12.036053 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:13.022456 kubelet[1697]: E0813 00:28:13.022414 1697 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:13.037073 kubelet[1697]: E0813 00:28:13.037041 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:13.114397 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3796525622.mount: Deactivated successfully. Aug 13 00:28:13.142796 systemd-networkd[1374]: vethf96d8f2e: Gained IPv6LL Aug 13 00:28:14.040156 kubelet[1697]: E0813 00:28:14.037868 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:14.490438 containerd[1443]: time="2025-08-13T00:28:14.490362890Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:14.490903 containerd[1443]: time="2025-08-13T00:28:14.490846398Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Aug 13 00:28:14.491872 containerd[1443]: time="2025-08-13T00:28:14.491833183Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:14.495690 containerd[1443]: time="2025-08-13T00:28:14.494786496Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:14.495980 containerd[1443]: time="2025-08-13T00:28:14.495941721Z" 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.139927491s" Aug 13 00:28:14.496021 containerd[1443]: time="2025-08-13T00:28:14.495980460Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Aug 13 00:28:14.498433 containerd[1443]: time="2025-08-13T00:28:14.498385914Z" level=info msg="CreateContainer within sandbox \"20f822652f9b8529f238b1783c3ff8b468167a1ab4606e9c041f357818a65b53\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Aug 13 00:28:14.509153 containerd[1443]: time="2025-08-13T00:28:14.509101768Z" level=info msg="CreateContainer within sandbox \"20f822652f9b8529f238b1783c3ff8b468167a1ab4606e9c041f357818a65b53\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"da027b7784d15ed6cb5531038fed8bf95830a2579817dc7353a1a67d88505870\"" Aug 13 00:28:14.509796 containerd[1443]: time="2025-08-13T00:28:14.509609128Z" level=info msg="StartContainer for \"da027b7784d15ed6cb5531038fed8bf95830a2579817dc7353a1a67d88505870\"" Aug 13 00:28:14.539876 systemd[1]: Started cri-containerd-da027b7784d15ed6cb5531038fed8bf95830a2579817dc7353a1a67d88505870.scope - libcontainer container da027b7784d15ed6cb5531038fed8bf95830a2579817dc7353a1a67d88505870. Aug 13 00:28:14.564729 containerd[1443]: time="2025-08-13T00:28:14.562980700Z" level=info msg="StartContainer for \"da027b7784d15ed6cb5531038fed8bf95830a2579817dc7353a1a67d88505870\" returns successfully" Aug 13 00:28:15.040619 kubelet[1697]: E0813 00:28:15.040573 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:16.041612 kubelet[1697]: E0813 00:28:16.041565 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:17.041927 kubelet[1697]: E0813 00:28:17.041865 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:18.042932 kubelet[1697]: E0813 00:28:18.042876 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:19.043359 kubelet[1697]: E0813 00:28:19.043279 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:20.043747 kubelet[1697]: E0813 00:28:20.043693 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:21.044151 kubelet[1697]: E0813 00:28:21.044080 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:22.044620 kubelet[1697]: E0813 00:28:22.044563 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:23.045330 kubelet[1697]: E0813 00:28:23.045277 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:24.046214 kubelet[1697]: E0813 00:28:24.046163 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:24.186825 kubelet[1697]: I0813 00:28:24.186518 1697 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.045314221 podStartE2EDuration="14.186500053s" podCreationTimestamp="2025-08-13 00:28:10 +0000 UTC" firstStartedPulling="2025-08-13 00:28:11.355708258 +0000 UTC m=+19.330434444" lastFinishedPulling="2025-08-13 00:28:14.49689413 +0000 UTC m=+22.471620276" observedRunningTime="2025-08-13 00:28:15.227503619 +0000 UTC m=+23.202229805" watchObservedRunningTime="2025-08-13 00:28:24.186500053 +0000 UTC m=+32.161226239" Aug 13 00:28:24.193486 systemd[1]: Created slice kubepods-besteffort-poddb8b7eb2_f2c0_491e_ad0b_7c982b0efd6e.slice - libcontainer container kubepods-besteffort-poddb8b7eb2_f2c0_491e_ad0b_7c982b0efd6e.slice. Aug 13 00:28:24.238415 kubelet[1697]: I0813 00:28:24.238369 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-q9rds\" (UniqueName: \"kubernetes.io/projected/db8b7eb2-f2c0-491e-ad0b-7c982b0efd6e-kube-api-access-q9rds\") pod \"test-pod-1\" (UID: \"db8b7eb2-f2c0-491e-ad0b-7c982b0efd6e\") " pod="default/test-pod-1" Aug 13 00:28:24.238415 kubelet[1697]: I0813 00:28:24.238419 1697 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-19305ad9-8bfb-4ea5-ae4a-124072b72978\" (UniqueName: \"kubernetes.io/nfs/db8b7eb2-f2c0-491e-ad0b-7c982b0efd6e-pvc-19305ad9-8bfb-4ea5-ae4a-124072b72978\") pod \"test-pod-1\" (UID: \"db8b7eb2-f2c0-491e-ad0b-7c982b0efd6e\") " pod="default/test-pod-1" Aug 13 00:28:24.362703 kernel: FS-Cache: Loaded Aug 13 00:28:24.387994 kernel: RPC: Registered named UNIX socket transport module. Aug 13 00:28:24.388105 kernel: RPC: Registered udp transport module. Aug 13 00:28:24.388124 kernel: RPC: Registered tcp transport module. Aug 13 00:28:24.388811 kernel: RPC: Registered tcp-with-tls transport module. Aug 13 00:28:24.388846 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 13 00:28:24.574752 kernel: NFS: Registering the id_resolver key type Aug 13 00:28:24.574866 kernel: Key type id_resolver registered Aug 13 00:28:24.574888 kernel: Key type id_legacy registered Aug 13 00:28:24.614334 nfsidmap[2741]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Aug 13 00:28:24.618229 nfsidmap[2744]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Aug 13 00:28:24.796075 containerd[1443]: time="2025-08-13T00:28:24.795973484Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:db8b7eb2-f2c0-491e-ad0b-7c982b0efd6e,Namespace:default,Attempt:0,}" Aug 13 00:28:24.812382 systemd-networkd[1374]: veth18d41f6f: Link UP Aug 13 00:28:24.815939 kernel: cni0: port 3(veth18d41f6f) entered blocking state Aug 13 00:28:24.816004 kernel: cni0: port 3(veth18d41f6f) entered disabled state Aug 13 00:28:24.816024 kernel: veth18d41f6f: entered allmulticast mode Aug 13 00:28:24.816040 kernel: veth18d41f6f: entered promiscuous mode Aug 13 00:28:24.819668 kernel: cni0: port 3(veth18d41f6f) entered blocking state Aug 13 00:28:24.819724 kernel: cni0: port 3(veth18d41f6f) entered forwarding state Aug 13 00:28:24.820132 systemd-networkd[1374]: veth18d41f6f: Gained carrier Aug 13 00:28:24.822347 containerd[1443]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Aug 13 00:28:24.822347 containerd[1443]: delegateAdd: netconf sent to delegate plugin: Aug 13 00:28:24.838803 containerd[1443]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-08-13T00:28:24.838411166Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 13 00:28:24.838803 containerd[1443]: time="2025-08-13T00:28:24.838776719Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 13 00:28:24.838803 containerd[1443]: time="2025-08-13T00:28:24.838788202Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:28:24.838967 containerd[1443]: time="2025-08-13T00:28:24.838862465Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 13 00:28:24.856813 systemd[1]: Started cri-containerd-cd95e9836ed60d330ddec3b32ae17cd4423ce9c122e93b0b25a0d3f790155eea.scope - libcontainer container cd95e9836ed60d330ddec3b32ae17cd4423ce9c122e93b0b25a0d3f790155eea. Aug 13 00:28:24.866016 systemd-resolved[1315]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 13 00:28:24.882282 containerd[1443]: time="2025-08-13T00:28:24.882234795Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:db8b7eb2-f2c0-491e-ad0b-7c982b0efd6e,Namespace:default,Attempt:0,} returns sandbox id \"cd95e9836ed60d330ddec3b32ae17cd4423ce9c122e93b0b25a0d3f790155eea\"" Aug 13 00:28:24.883285 containerd[1443]: time="2025-08-13T00:28:24.883260430Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Aug 13 00:28:25.047230 kubelet[1697]: E0813 00:28:25.047104 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:25.174291 containerd[1443]: time="2025-08-13T00:28:25.174235557Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Aug 13 00:28:25.174811 containerd[1443]: time="2025-08-13T00:28:25.174774234Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Aug 13 00:28:25.178086 containerd[1443]: time="2025-08-13T00:28:25.178045987Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:a6969d434cb816d30787e9f7ab16b632e12dc05a2c8f4dae701d83ef2199c985\", size \"69963735\" in 294.753267ms" Aug 13 00:28:25.178086 containerd[1443]: time="2025-08-13T00:28:25.178086599Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:07abd578947db789c018f907bed24fcc55d80455e9614b35a065bf3af4f3ac27\"" Aug 13 00:28:25.180074 containerd[1443]: time="2025-08-13T00:28:25.179916332Z" level=info msg="CreateContainer within sandbox \"cd95e9836ed60d330ddec3b32ae17cd4423ce9c122e93b0b25a0d3f790155eea\" for container &ContainerMetadata{Name:test,Attempt:0,}" Aug 13 00:28:25.191301 containerd[1443]: time="2025-08-13T00:28:25.191250076Z" level=info msg="CreateContainer within sandbox \"cd95e9836ed60d330ddec3b32ae17cd4423ce9c122e93b0b25a0d3f790155eea\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"4f19a6a36e1827d6d80588353109c48c56bd7a063dc9e9b1865bf4487e1813df\"" Aug 13 00:28:25.191791 containerd[1443]: time="2025-08-13T00:28:25.191754463Z" level=info msg="StartContainer for \"4f19a6a36e1827d6d80588353109c48c56bd7a063dc9e9b1865bf4487e1813df\"" Aug 13 00:28:25.213830 systemd[1]: Started cri-containerd-4f19a6a36e1827d6d80588353109c48c56bd7a063dc9e9b1865bf4487e1813df.scope - libcontainer container 4f19a6a36e1827d6d80588353109c48c56bd7a063dc9e9b1865bf4487e1813df. Aug 13 00:28:25.234887 containerd[1443]: time="2025-08-13T00:28:25.234808411Z" level=info msg="StartContainer for \"4f19a6a36e1827d6d80588353109c48c56bd7a063dc9e9b1865bf4487e1813df\" returns successfully" Aug 13 00:28:25.248111 kubelet[1697]: I0813 00:28:25.248048 1697 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=13.952293787 podStartE2EDuration="14.24801498s" podCreationTimestamp="2025-08-13 00:28:11 +0000 UTC" firstStartedPulling="2025-08-13 00:28:24.883001591 +0000 UTC m=+32.857727777" lastFinishedPulling="2025-08-13 00:28:25.178722784 +0000 UTC m=+33.153448970" observedRunningTime="2025-08-13 00:28:25.247199022 +0000 UTC m=+33.221925208" watchObservedRunningTime="2025-08-13 00:28:25.24801498 +0000 UTC m=+33.222741166" Aug 13 00:28:26.047785 kubelet[1697]: E0813 00:28:26.047740 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:26.454853 systemd-networkd[1374]: veth18d41f6f: Gained IPv6LL Aug 13 00:28:27.048140 kubelet[1697]: E0813 00:28:27.048079 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:28.048266 kubelet[1697]: E0813 00:28:28.048214 1697 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Aug 13 00:28:28.123419 update_engine[1424]: I20250813 00:28:28.122732 1424 update_attempter.cc:509] Updating boot flags... Aug 13 00:28:28.150710 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (2915) Aug 13 00:28:28.180771 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 41 scanned by (udev-worker) (2916)