Sep 6 00:13:01.874236 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 6 00:13:01.874257 kernel: Linux version 6.6.103-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 Fri Sep 5 22:30:47 -00 2025 Sep 6 00:13:01.874267 kernel: KASLR enabled Sep 6 00:13:01.874273 kernel: efi: EFI v2.7 by EDK II Sep 6 00:13:01.874279 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdba86018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Sep 6 00:13:01.874284 kernel: random: crng init done Sep 6 00:13:01.874291 kernel: ACPI: Early table checksum verification disabled Sep 6 00:13:01.874297 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Sep 6 00:13:01.874303 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 6 00:13:01.874311 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 6 00:13:01.874317 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 6 00:13:01.874323 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 6 00:13:01.874329 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 6 00:13:01.874335 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 6 00:13:01.874342 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 6 00:13:01.874350 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 6 00:13:01.874357 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 6 00:13:01.874363 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 6 00:13:01.874369 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 6 00:13:01.874376 kernel: NUMA: Failed to initialise from firmware Sep 6 00:13:01.874382 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 6 00:13:01.874389 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Sep 6 00:13:01.874395 kernel: Zone ranges: Sep 6 00:13:01.874409 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 6 00:13:01.874416 kernel: DMA32 empty Sep 6 00:13:01.874425 kernel: Normal empty Sep 6 00:13:01.874431 kernel: Movable zone start for each node Sep 6 00:13:01.874437 kernel: Early memory node ranges Sep 6 00:13:01.874444 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Sep 6 00:13:01.874451 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Sep 6 00:13:01.874457 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Sep 6 00:13:01.874463 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 6 00:13:01.874469 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 6 00:13:01.874476 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 6 00:13:01.874482 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 6 00:13:01.874489 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 6 00:13:01.874496 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 6 00:13:01.874503 kernel: psci: probing for conduit method from ACPI. Sep 6 00:13:01.874510 kernel: psci: PSCIv1.1 detected in firmware. Sep 6 00:13:01.874516 kernel: psci: Using standard PSCI v0.2 function IDs Sep 6 00:13:01.874525 kernel: psci: Trusted OS migration not required Sep 6 00:13:01.874531 kernel: psci: SMC Calling Convention v1.1 Sep 6 00:13:01.874539 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 6 00:13:01.874547 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Sep 6 00:13:01.874554 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Sep 6 00:13:01.874560 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 6 00:13:01.874567 kernel: Detected PIPT I-cache on CPU0 Sep 6 00:13:01.874574 kernel: CPU features: detected: GIC system register CPU interface Sep 6 00:13:01.874580 kernel: CPU features: detected: Hardware dirty bit management Sep 6 00:13:01.874587 kernel: CPU features: detected: Spectre-v4 Sep 6 00:13:01.874594 kernel: CPU features: detected: Spectre-BHB Sep 6 00:13:01.874600 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 6 00:13:01.874607 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 6 00:13:01.874614 kernel: CPU features: detected: ARM erratum 1418040 Sep 6 00:13:01.874621 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 6 00:13:01.874628 kernel: alternatives: applying boot alternatives Sep 6 00:13:01.874635 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=ac831c89fe9ee7829b7371dadfb138f8d0e2b31ae3a5a920e0eba13bbab016c3 Sep 6 00:13:01.874643 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 6 00:13:01.874649 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 6 00:13:01.874656 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 6 00:13:01.874663 kernel: Fallback order for Node 0: 0 Sep 6 00:13:01.874670 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Sep 6 00:13:01.874677 kernel: Policy zone: DMA Sep 6 00:13:01.874683 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 6 00:13:01.874691 kernel: software IO TLB: area num 4. Sep 6 00:13:01.874698 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Sep 6 00:13:01.874705 kernel: Memory: 2386404K/2572288K available (10304K kernel code, 2186K rwdata, 8108K rodata, 39424K init, 897K bss, 185884K reserved, 0K cma-reserved) Sep 6 00:13:01.874724 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 6 00:13:01.874731 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 6 00:13:01.874738 kernel: rcu: RCU event tracing is enabled. Sep 6 00:13:01.874746 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 6 00:13:01.874753 kernel: Trampoline variant of Tasks RCU enabled. Sep 6 00:13:01.874759 kernel: Tracing variant of Tasks RCU enabled. Sep 6 00:13:01.874766 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 6 00:13:01.874773 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 6 00:13:01.874781 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 6 00:13:01.874788 kernel: GICv3: 256 SPIs implemented Sep 6 00:13:01.874795 kernel: GICv3: 0 Extended SPIs implemented Sep 6 00:13:01.874802 kernel: Root IRQ handler: gic_handle_irq Sep 6 00:13:01.874808 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 6 00:13:01.874815 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 6 00:13:01.874822 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 6 00:13:01.874829 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Sep 6 00:13:01.874836 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Sep 6 00:13:01.874844 kernel: GICv3: using LPI property table @0x00000000400f0000 Sep 6 00:13:01.874850 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Sep 6 00:13:01.874857 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 6 00:13:01.874865 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 6 00:13:01.874872 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 6 00:13:01.874879 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 6 00:13:01.874886 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 6 00:13:01.874893 kernel: arm-pv: using stolen time PV Sep 6 00:13:01.874901 kernel: Console: colour dummy device 80x25 Sep 6 00:13:01.874908 kernel: ACPI: Core revision 20230628 Sep 6 00:13:01.874915 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 6 00:13:01.874922 kernel: pid_max: default: 32768 minimum: 301 Sep 6 00:13:01.874929 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 6 00:13:01.874937 kernel: landlock: Up and running. Sep 6 00:13:01.874944 kernel: SELinux: Initializing. Sep 6 00:13:01.874950 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 6 00:13:01.874957 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 6 00:13:01.874964 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 6 00:13:01.874971 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 6 00:13:01.874978 kernel: rcu: Hierarchical SRCU implementation. Sep 6 00:13:01.874985 kernel: rcu: Max phase no-delay instances is 400. Sep 6 00:13:01.874992 kernel: Platform MSI: ITS@0x8080000 domain created Sep 6 00:13:01.875000 kernel: PCI/MSI: ITS@0x8080000 domain created Sep 6 00:13:01.875006 kernel: Remapping and enabling EFI services. Sep 6 00:13:01.875023 kernel: smp: Bringing up secondary CPUs ... Sep 6 00:13:01.875030 kernel: Detected PIPT I-cache on CPU1 Sep 6 00:13:01.875037 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 6 00:13:01.875044 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Sep 6 00:13:01.875051 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 6 00:13:01.875058 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 6 00:13:01.875065 kernel: Detected PIPT I-cache on CPU2 Sep 6 00:13:01.875072 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 6 00:13:01.875080 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Sep 6 00:13:01.875087 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 6 00:13:01.875098 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 6 00:13:01.875106 kernel: Detected PIPT I-cache on CPU3 Sep 6 00:13:01.875114 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 6 00:13:01.875121 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Sep 6 00:13:01.875128 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 6 00:13:01.875135 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 6 00:13:01.875143 kernel: smp: Brought up 1 node, 4 CPUs Sep 6 00:13:01.875151 kernel: SMP: Total of 4 processors activated. Sep 6 00:13:01.875158 kernel: CPU features: detected: 32-bit EL0 Support Sep 6 00:13:01.875166 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 6 00:13:01.875173 kernel: CPU features: detected: Common not Private translations Sep 6 00:13:01.875180 kernel: CPU features: detected: CRC32 instructions Sep 6 00:13:01.875187 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 6 00:13:01.875195 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 6 00:13:01.875202 kernel: CPU features: detected: LSE atomic instructions Sep 6 00:13:01.875211 kernel: CPU features: detected: Privileged Access Never Sep 6 00:13:01.875218 kernel: CPU features: detected: RAS Extension Support Sep 6 00:13:01.875226 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 6 00:13:01.875233 kernel: CPU: All CPU(s) started at EL1 Sep 6 00:13:01.875240 kernel: alternatives: applying system-wide alternatives Sep 6 00:13:01.875247 kernel: devtmpfs: initialized Sep 6 00:13:01.875255 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 6 00:13:01.875262 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 6 00:13:01.875270 kernel: pinctrl core: initialized pinctrl subsystem Sep 6 00:13:01.875278 kernel: SMBIOS 3.0.0 present. Sep 6 00:13:01.875285 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Sep 6 00:13:01.875292 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 6 00:13:01.875300 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 6 00:13:01.875307 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 6 00:13:01.875315 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 6 00:13:01.875323 kernel: audit: initializing netlink subsys (disabled) Sep 6 00:13:01.875331 kernel: audit: type=2000 audit(0.023:1): state=initialized audit_enabled=0 res=1 Sep 6 00:13:01.875338 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 6 00:13:01.875347 kernel: cpuidle: using governor menu Sep 6 00:13:01.875354 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 6 00:13:01.875361 kernel: ASID allocator initialised with 32768 entries Sep 6 00:13:01.875369 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 6 00:13:01.875376 kernel: Serial: AMBA PL011 UART driver Sep 6 00:13:01.875384 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 6 00:13:01.875391 kernel: Modules: 0 pages in range for non-PLT usage Sep 6 00:13:01.875399 kernel: Modules: 509008 pages in range for PLT usage Sep 6 00:13:01.875411 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 6 00:13:01.875420 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 6 00:13:01.875427 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 6 00:13:01.875435 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 6 00:13:01.875442 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 6 00:13:01.875449 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 6 00:13:01.875456 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 6 00:13:01.875463 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 6 00:13:01.875470 kernel: ACPI: Added _OSI(Module Device) Sep 6 00:13:01.875478 kernel: ACPI: Added _OSI(Processor Device) Sep 6 00:13:01.875486 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 6 00:13:01.875498 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 6 00:13:01.875505 kernel: ACPI: Interpreter enabled Sep 6 00:13:01.875513 kernel: ACPI: Using GIC for interrupt routing Sep 6 00:13:01.875520 kernel: ACPI: MCFG table detected, 1 entries Sep 6 00:13:01.875527 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 6 00:13:01.875534 kernel: printk: console [ttyAMA0] enabled Sep 6 00:13:01.875542 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 6 00:13:01.875679 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 6 00:13:01.875758 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 6 00:13:01.875824 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 6 00:13:01.875891 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 6 00:13:01.875958 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 6 00:13:01.875968 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 6 00:13:01.875976 kernel: PCI host bridge to bus 0000:00 Sep 6 00:13:01.876086 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 6 00:13:01.876156 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 6 00:13:01.876213 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 6 00:13:01.876272 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 6 00:13:01.876358 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Sep 6 00:13:01.876444 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Sep 6 00:13:01.876513 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Sep 6 00:13:01.876581 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Sep 6 00:13:01.876646 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Sep 6 00:13:01.876714 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Sep 6 00:13:01.876781 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Sep 6 00:13:01.876845 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Sep 6 00:13:01.876903 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 6 00:13:01.876961 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 6 00:13:01.877033 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 6 00:13:01.877044 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 6 00:13:01.877052 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 6 00:13:01.877060 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 6 00:13:01.877067 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 6 00:13:01.877075 kernel: iommu: Default domain type: Translated Sep 6 00:13:01.877082 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 6 00:13:01.877090 kernel: efivars: Registered efivars operations Sep 6 00:13:01.877097 kernel: vgaarb: loaded Sep 6 00:13:01.877107 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 6 00:13:01.877115 kernel: VFS: Disk quotas dquot_6.6.0 Sep 6 00:13:01.877122 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 6 00:13:01.877129 kernel: pnp: PnP ACPI init Sep 6 00:13:01.877204 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 6 00:13:01.877215 kernel: pnp: PnP ACPI: found 1 devices Sep 6 00:13:01.877222 kernel: NET: Registered PF_INET protocol family Sep 6 00:13:01.877230 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 6 00:13:01.877239 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 6 00:13:01.877246 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 6 00:13:01.877254 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 6 00:13:01.877261 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 6 00:13:01.877269 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 6 00:13:01.877276 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 6 00:13:01.877283 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 6 00:13:01.877291 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 6 00:13:01.877298 kernel: PCI: CLS 0 bytes, default 64 Sep 6 00:13:01.877307 kernel: kvm [1]: HYP mode not available Sep 6 00:13:01.877314 kernel: Initialise system trusted keyrings Sep 6 00:13:01.877322 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 6 00:13:01.877329 kernel: Key type asymmetric registered Sep 6 00:13:01.877336 kernel: Asymmetric key parser 'x509' registered Sep 6 00:13:01.877358 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 6 00:13:01.877365 kernel: io scheduler mq-deadline registered Sep 6 00:13:01.877372 kernel: io scheduler kyber registered Sep 6 00:13:01.877380 kernel: io scheduler bfq registered Sep 6 00:13:01.877389 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 6 00:13:01.877396 kernel: ACPI: button: Power Button [PWRB] Sep 6 00:13:01.877409 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 6 00:13:01.877486 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 6 00:13:01.877497 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 6 00:13:01.877504 kernel: thunder_xcv, ver 1.0 Sep 6 00:13:01.877512 kernel: thunder_bgx, ver 1.0 Sep 6 00:13:01.877519 kernel: nicpf, ver 1.0 Sep 6 00:13:01.877527 kernel: nicvf, ver 1.0 Sep 6 00:13:01.877605 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 6 00:13:01.877667 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-06T00:13:01 UTC (1757117581) Sep 6 00:13:01.877677 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 6 00:13:01.877684 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Sep 6 00:13:01.877692 kernel: watchdog: Delayed init of the lockup detector failed: -19 Sep 6 00:13:01.877699 kernel: watchdog: Hard watchdog permanently disabled Sep 6 00:13:01.877706 kernel: NET: Registered PF_INET6 protocol family Sep 6 00:13:01.877714 kernel: Segment Routing with IPv6 Sep 6 00:13:01.877724 kernel: In-situ OAM (IOAM) with IPv6 Sep 6 00:13:01.877731 kernel: NET: Registered PF_PACKET protocol family Sep 6 00:13:01.877738 kernel: Key type dns_resolver registered Sep 6 00:13:01.877746 kernel: registered taskstats version 1 Sep 6 00:13:01.877753 kernel: Loading compiled-in X.509 certificates Sep 6 00:13:01.877760 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.103-flatcar: 5b16e1dfa86dac534548885fd675b87757ff9e20' Sep 6 00:13:01.877768 kernel: Key type .fscrypt registered Sep 6 00:13:01.877775 kernel: Key type fscrypt-provisioning registered Sep 6 00:13:01.877782 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 6 00:13:01.877791 kernel: ima: Allocated hash algorithm: sha1 Sep 6 00:13:01.877798 kernel: ima: No architecture policies found Sep 6 00:13:01.877806 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 6 00:13:01.877813 kernel: clk: Disabling unused clocks Sep 6 00:13:01.877821 kernel: Freeing unused kernel memory: 39424K Sep 6 00:13:01.877828 kernel: Run /init as init process Sep 6 00:13:01.877835 kernel: with arguments: Sep 6 00:13:01.877842 kernel: /init Sep 6 00:13:01.877849 kernel: with environment: Sep 6 00:13:01.877858 kernel: HOME=/ Sep 6 00:13:01.877865 kernel: TERM=linux Sep 6 00:13:01.877872 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 6 00:13:01.877881 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) Sep 6 00:13:01.877890 systemd[1]: Detected virtualization kvm. Sep 6 00:13:01.877899 systemd[1]: Detected architecture arm64. Sep 6 00:13:01.877906 systemd[1]: Running in initrd. Sep 6 00:13:01.877916 systemd[1]: No hostname configured, using default hostname. Sep 6 00:13:01.877923 systemd[1]: Hostname set to . Sep 6 00:13:01.877931 systemd[1]: Initializing machine ID from VM UUID. Sep 6 00:13:01.877939 systemd[1]: Queued start job for default target initrd.target. Sep 6 00:13:01.877947 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 6 00:13:01.877954 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 6 00:13:01.877963 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 6 00:13:01.877972 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 6 00:13:01.877982 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 6 00:13:01.877990 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 6 00:13:01.878000 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 6 00:13:01.878016 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 6 00:13:01.878025 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 6 00:13:01.878033 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 6 00:13:01.878041 systemd[1]: Reached target paths.target - Path Units. Sep 6 00:13:01.878051 systemd[1]: Reached target slices.target - Slice Units. Sep 6 00:13:01.878059 systemd[1]: Reached target swap.target - Swaps. Sep 6 00:13:01.878067 systemd[1]: Reached target timers.target - Timer Units. Sep 6 00:13:01.878075 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 6 00:13:01.878082 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 6 00:13:01.878091 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 6 00:13:01.878099 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 6 00:13:01.878107 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 6 00:13:01.878114 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 6 00:13:01.878124 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 6 00:13:01.878132 systemd[1]: Reached target sockets.target - Socket Units. Sep 6 00:13:01.878139 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 6 00:13:01.878147 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 6 00:13:01.878155 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 6 00:13:01.878163 systemd[1]: Starting systemd-fsck-usr.service... Sep 6 00:13:01.878171 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 6 00:13:01.878179 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 6 00:13:01.878188 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 6 00:13:01.878196 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 6 00:13:01.878204 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 6 00:13:01.878216 systemd[1]: Finished systemd-fsck-usr.service. Sep 6 00:13:01.878225 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 6 00:13:01.878250 systemd-journald[238]: Collecting audit messages is disabled. Sep 6 00:13:01.878270 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 6 00:13:01.878278 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 6 00:13:01.878287 systemd-journald[238]: Journal started Sep 6 00:13:01.878308 systemd-journald[238]: Runtime Journal (/run/log/journal/41eec53719a248d6a83b1a0fa9165862) is 5.9M, max 47.3M, 41.4M free. Sep 6 00:13:01.868930 systemd-modules-load[240]: Inserted module 'overlay' Sep 6 00:13:01.881737 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 6 00:13:01.881755 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 6 00:13:01.883226 systemd-modules-load[240]: Inserted module 'br_netfilter' Sep 6 00:13:01.885268 kernel: Bridge firewalling registered Sep 6 00:13:01.885286 systemd[1]: Started systemd-journald.service - Journal Service. Sep 6 00:13:01.886250 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 6 00:13:01.889611 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 6 00:13:01.892227 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 6 00:13:01.894801 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 6 00:13:01.901234 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 6 00:13:01.904208 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 6 00:13:01.905169 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 6 00:13:01.906129 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 6 00:13:01.911248 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 6 00:13:01.918392 dracut-cmdline[272]: dracut-dracut-053 Sep 6 00:13:01.922084 dracut-cmdline[272]: 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=ac831c89fe9ee7829b7371dadfb138f8d0e2b31ae3a5a920e0eba13bbab016c3 Sep 6 00:13:01.920188 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 6 00:13:01.945730 systemd-resolved[284]: Positive Trust Anchors: Sep 6 00:13:01.945747 systemd-resolved[284]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 6 00:13:01.945779 systemd-resolved[284]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 6 00:13:01.950345 systemd-resolved[284]: Defaulting to hostname 'linux'. Sep 6 00:13:01.951300 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 6 00:13:01.953881 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 6 00:13:01.995049 kernel: SCSI subsystem initialized Sep 6 00:13:02.000021 kernel: Loading iSCSI transport class v2.0-870. Sep 6 00:13:02.013054 kernel: iscsi: registered transport (tcp) Sep 6 00:13:02.026036 kernel: iscsi: registered transport (qla4xxx) Sep 6 00:13:02.026067 kernel: QLogic iSCSI HBA Driver Sep 6 00:13:02.069233 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 6 00:13:02.077206 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 6 00:13:02.092491 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 6 00:13:02.092553 kernel: device-mapper: uevent: version 1.0.3 Sep 6 00:13:02.093365 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 6 00:13:02.139039 kernel: raid6: neonx8 gen() 15767 MB/s Sep 6 00:13:02.156029 kernel: raid6: neonx4 gen() 15669 MB/s Sep 6 00:13:02.173026 kernel: raid6: neonx2 gen() 13215 MB/s Sep 6 00:13:02.190023 kernel: raid6: neonx1 gen() 10476 MB/s Sep 6 00:13:02.207026 kernel: raid6: int64x8 gen() 6959 MB/s Sep 6 00:13:02.224028 kernel: raid6: int64x4 gen() 7337 MB/s Sep 6 00:13:02.241024 kernel: raid6: int64x2 gen() 6125 MB/s Sep 6 00:13:02.258025 kernel: raid6: int64x1 gen() 5049 MB/s Sep 6 00:13:02.258050 kernel: raid6: using algorithm neonx8 gen() 15767 MB/s Sep 6 00:13:02.275029 kernel: raid6: .... xor() 12053 MB/s, rmw enabled Sep 6 00:13:02.275052 kernel: raid6: using neon recovery algorithm Sep 6 00:13:02.280027 kernel: xor: measuring software checksum speed Sep 6 00:13:02.280044 kernel: 8regs : 19731 MB/sec Sep 6 00:13:02.281053 kernel: 32regs : 18046 MB/sec Sep 6 00:13:02.281066 kernel: arm64_neon : 27079 MB/sec Sep 6 00:13:02.281076 kernel: xor: using function: arm64_neon (27079 MB/sec) Sep 6 00:13:02.333040 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 6 00:13:02.345894 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 6 00:13:02.358153 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 6 00:13:02.369529 systemd-udevd[463]: Using default interface naming scheme 'v255'. Sep 6 00:13:02.372719 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 6 00:13:02.375634 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 6 00:13:02.389111 dracut-pre-trigger[470]: rd.md=0: removing MD RAID activation Sep 6 00:13:02.415388 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 6 00:13:02.424141 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 6 00:13:02.463352 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 6 00:13:02.470343 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 6 00:13:02.484771 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 6 00:13:02.486736 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 6 00:13:02.488652 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 6 00:13:02.490826 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 6 00:13:02.501162 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 6 00:13:02.514006 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 6 00:13:02.522028 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 6 00:13:02.529472 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 6 00:13:02.530070 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 6 00:13:02.538668 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 6 00:13:02.538691 kernel: GPT:9289727 != 19775487 Sep 6 00:13:02.538702 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 6 00:13:02.538711 kernel: GPT:9289727 != 19775487 Sep 6 00:13:02.538728 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 6 00:13:02.538737 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 6 00:13:02.530219 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 6 00:13:02.533335 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 6 00:13:02.539474 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 6 00:13:02.539628 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 6 00:13:02.542091 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 6 00:13:02.555257 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 6 00:13:02.568705 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 6 00:13:02.571486 kernel: BTRFS: device fsid 045c118e-b098-46f0-884a-43665575c70e devid 1 transid 37 /dev/vda3 scanned by (udev-worker) (514) Sep 6 00:13:02.571519 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (508) Sep 6 00:13:02.573783 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 6 00:13:02.580824 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 6 00:13:02.587174 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 6 00:13:02.588084 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 6 00:13:02.593754 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 6 00:13:02.608173 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 6 00:13:02.611165 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 6 00:13:02.616515 disk-uuid[552]: Primary Header is updated. Sep 6 00:13:02.616515 disk-uuid[552]: Secondary Entries is updated. Sep 6 00:13:02.616515 disk-uuid[552]: Secondary Header is updated. Sep 6 00:13:02.620029 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 6 00:13:02.632703 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 6 00:13:03.628048 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 6 00:13:03.628641 disk-uuid[555]: The operation has completed successfully. Sep 6 00:13:03.651713 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 6 00:13:03.651809 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 6 00:13:03.673180 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 6 00:13:03.676014 sh[574]: Success Sep 6 00:13:03.686027 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Sep 6 00:13:03.714100 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 6 00:13:03.730480 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 6 00:13:03.731869 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 6 00:13:03.742878 kernel: BTRFS info (device dm-0): first mount of filesystem 045c118e-b098-46f0-884a-43665575c70e Sep 6 00:13:03.742915 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 6 00:13:03.742926 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 6 00:13:03.742936 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 6 00:13:03.744161 kernel: BTRFS info (device dm-0): using free space tree Sep 6 00:13:03.747501 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 6 00:13:03.748652 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 6 00:13:03.749386 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 6 00:13:03.751450 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 6 00:13:03.761733 kernel: BTRFS info (device vda6): first mount of filesystem 7395d4d5-ecb1-4acb-b5a4-3e846eddb858 Sep 6 00:13:03.761766 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 6 00:13:03.761777 kernel: BTRFS info (device vda6): using free space tree Sep 6 00:13:03.764122 kernel: BTRFS info (device vda6): auto enabling async discard Sep 6 00:13:03.771369 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 6 00:13:03.772304 kernel: BTRFS info (device vda6): last unmount of filesystem 7395d4d5-ecb1-4acb-b5a4-3e846eddb858 Sep 6 00:13:03.778035 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 6 00:13:03.788206 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 6 00:13:03.846919 ignition[673]: Ignition 2.19.0 Sep 6 00:13:03.846930 ignition[673]: Stage: fetch-offline Sep 6 00:13:03.848741 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 6 00:13:03.846963 ignition[673]: no configs at "/usr/lib/ignition/base.d" Sep 6 00:13:03.846972 ignition[673]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 6 00:13:03.847140 ignition[673]: parsed url from cmdline: "" Sep 6 00:13:03.847143 ignition[673]: no config URL provided Sep 6 00:13:03.847147 ignition[673]: reading system config file "/usr/lib/ignition/user.ign" Sep 6 00:13:03.847155 ignition[673]: no config at "/usr/lib/ignition/user.ign" Sep 6 00:13:03.847180 ignition[673]: op(1): [started] loading QEMU firmware config module Sep 6 00:13:03.847184 ignition[673]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 6 00:13:03.853119 ignition[673]: op(1): [finished] loading QEMU firmware config module Sep 6 00:13:03.857195 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 6 00:13:03.862863 ignition[673]: parsing config with SHA512: 8151d5e5893e5d213e9631ed4b54bb25962b4fe598150f82103a98d5a96133a62453a59b0d81f61f3cbd5936386345959aacb328053e599cbd8ac1f5da4ae418 Sep 6 00:13:03.865917 unknown[673]: fetched base config from "system" Sep 6 00:13:03.865933 unknown[673]: fetched user config from "qemu" Sep 6 00:13:03.866246 ignition[673]: fetch-offline: fetch-offline passed Sep 6 00:13:03.866309 ignition[673]: Ignition finished successfully Sep 6 00:13:03.869400 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 6 00:13:03.874820 systemd-networkd[769]: lo: Link UP Sep 6 00:13:03.874831 systemd-networkd[769]: lo: Gained carrier Sep 6 00:13:03.875631 systemd-networkd[769]: Enumeration completed Sep 6 00:13:03.875720 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 6 00:13:03.876076 systemd-networkd[769]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 6 00:13:03.876079 systemd-networkd[769]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 6 00:13:03.877211 systemd[1]: Reached target network.target - Network. Sep 6 00:13:03.877326 systemd-networkd[769]: eth0: Link UP Sep 6 00:13:03.877330 systemd-networkd[769]: eth0: Gained carrier Sep 6 00:13:03.877337 systemd-networkd[769]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 6 00:13:03.878494 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 6 00:13:03.893181 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 6 00:13:03.903764 ignition[772]: Ignition 2.19.0 Sep 6 00:13:03.903773 ignition[772]: Stage: kargs Sep 6 00:13:03.904072 systemd-networkd[769]: eth0: DHCPv4 address 10.0.0.136/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 6 00:13:03.903941 ignition[772]: no configs at "/usr/lib/ignition/base.d" Sep 6 00:13:03.903951 ignition[772]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 6 00:13:03.907431 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 6 00:13:03.904696 ignition[772]: kargs: kargs passed Sep 6 00:13:03.904743 ignition[772]: Ignition finished successfully Sep 6 00:13:03.913189 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 6 00:13:03.923918 ignition[781]: Ignition 2.19.0 Sep 6 00:13:03.923929 ignition[781]: Stage: disks Sep 6 00:13:03.924122 ignition[781]: no configs at "/usr/lib/ignition/base.d" Sep 6 00:13:03.924135 ignition[781]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 6 00:13:03.924823 ignition[781]: disks: disks passed Sep 6 00:13:03.926753 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 6 00:13:03.924869 ignition[781]: Ignition finished successfully Sep 6 00:13:03.928373 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 6 00:13:03.929452 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 6 00:13:03.930854 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 6 00:13:03.932045 systemd[1]: Reached target sysinit.target - System Initialization. Sep 6 00:13:03.933477 systemd[1]: Reached target basic.target - Basic System. Sep 6 00:13:03.948229 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 6 00:13:03.958441 systemd-fsck[791]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 6 00:13:03.962166 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 6 00:13:03.966362 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 6 00:13:04.018038 kernel: EXT4-fs (vda9): mounted filesystem 72e55cb0-8368-4871-a3a0-8637412e72e8 r/w with ordered data mode. Quota mode: none. Sep 6 00:13:04.018558 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 6 00:13:04.019688 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 6 00:13:04.032149 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 6 00:13:04.033823 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 6 00:13:04.034883 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 6 00:13:04.034972 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 6 00:13:04.035039 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 6 00:13:04.041243 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 6 00:13:04.045640 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (799) Sep 6 00:13:04.045696 kernel: BTRFS info (device vda6): first mount of filesystem 7395d4d5-ecb1-4acb-b5a4-3e846eddb858 Sep 6 00:13:04.045709 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 6 00:13:04.045719 kernel: BTRFS info (device vda6): using free space tree Sep 6 00:13:04.044509 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 6 00:13:04.049026 kernel: BTRFS info (device vda6): auto enabling async discard Sep 6 00:13:04.050373 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 6 00:13:04.079697 initrd-setup-root[823]: cut: /sysroot/etc/passwd: No such file or directory Sep 6 00:13:04.083980 initrd-setup-root[830]: cut: /sysroot/etc/group: No such file or directory Sep 6 00:13:04.087662 initrd-setup-root[837]: cut: /sysroot/etc/shadow: No such file or directory Sep 6 00:13:04.091199 initrd-setup-root[844]: cut: /sysroot/etc/gshadow: No such file or directory Sep 6 00:13:04.160698 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 6 00:13:04.186916 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 6 00:13:04.188507 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 6 00:13:04.194025 kernel: BTRFS info (device vda6): last unmount of filesystem 7395d4d5-ecb1-4acb-b5a4-3e846eddb858 Sep 6 00:13:04.209830 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 6 00:13:04.211782 ignition[913]: INFO : Ignition 2.19.0 Sep 6 00:13:04.211782 ignition[913]: INFO : Stage: mount Sep 6 00:13:04.211782 ignition[913]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 6 00:13:04.211782 ignition[913]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 6 00:13:04.215456 ignition[913]: INFO : mount: mount passed Sep 6 00:13:04.215456 ignition[913]: INFO : Ignition finished successfully Sep 6 00:13:04.216286 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 6 00:13:04.222166 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 6 00:13:04.741128 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 6 00:13:04.750179 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 6 00:13:04.758713 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (925) Sep 6 00:13:04.758754 kernel: BTRFS info (device vda6): first mount of filesystem 7395d4d5-ecb1-4acb-b5a4-3e846eddb858 Sep 6 00:13:04.758766 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 6 00:13:04.759463 kernel: BTRFS info (device vda6): using free space tree Sep 6 00:13:04.762017 kernel: BTRFS info (device vda6): auto enabling async discard Sep 6 00:13:04.763050 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 6 00:13:04.778488 ignition[942]: INFO : Ignition 2.19.0 Sep 6 00:13:04.778488 ignition[942]: INFO : Stage: files Sep 6 00:13:04.779866 ignition[942]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 6 00:13:04.779866 ignition[942]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 6 00:13:04.779866 ignition[942]: DEBUG : files: compiled without relabeling support, skipping Sep 6 00:13:04.782534 ignition[942]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 6 00:13:04.782534 ignition[942]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 6 00:13:04.785039 ignition[942]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 6 00:13:04.786191 ignition[942]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 6 00:13:04.787488 unknown[942]: wrote ssh authorized keys file for user: core Sep 6 00:13:04.788504 ignition[942]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 6 00:13:04.790366 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 6 00:13:04.792646 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 6 00:13:04.792646 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 6 00:13:04.792646 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 6 00:13:04.792646 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 6 00:13:04.792646 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 6 00:13:04.792646 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 6 00:13:04.792646 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Sep 6 00:13:05.470275 systemd-networkd[769]: eth0: Gained IPv6LL Sep 6 00:13:05.795670 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 6 00:13:06.290086 ignition[942]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 6 00:13:06.290086 ignition[942]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 6 00:13:06.294205 ignition[942]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 6 00:13:06.294205 ignition[942]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 6 00:13:06.294205 ignition[942]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 6 00:13:06.294205 ignition[942]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 6 00:13:06.320279 ignition[942]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 6 00:13:06.325405 ignition[942]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 6 00:13:06.327570 ignition[942]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 6 00:13:06.327570 ignition[942]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 6 00:13:06.327570 ignition[942]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 6 00:13:06.327570 ignition[942]: INFO : files: files passed Sep 6 00:13:06.327570 ignition[942]: INFO : Ignition finished successfully Sep 6 00:13:06.329062 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 6 00:13:06.345362 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 6 00:13:06.347618 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 6 00:13:06.349561 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 6 00:13:06.349645 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 6 00:13:06.358167 initrd-setup-root-after-ignition[970]: grep: /sysroot/oem/oem-release: No such file or directory Sep 6 00:13:06.361274 initrd-setup-root-after-ignition[972]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 6 00:13:06.361274 initrd-setup-root-after-ignition[972]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 6 00:13:06.364199 initrd-setup-root-after-ignition[976]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 6 00:13:06.365306 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 6 00:13:06.366730 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 6 00:13:06.373162 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 6 00:13:06.399970 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 6 00:13:06.400098 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 6 00:13:06.401902 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 6 00:13:06.402847 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 6 00:13:06.403680 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 6 00:13:06.404533 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 6 00:13:06.423095 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 6 00:13:06.425535 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 6 00:13:06.439919 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 6 00:13:06.441175 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 6 00:13:06.442776 systemd[1]: Stopped target timers.target - Timer Units. Sep 6 00:13:06.444317 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 6 00:13:06.444453 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 6 00:13:06.446487 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 6 00:13:06.449478 systemd[1]: Stopped target basic.target - Basic System. Sep 6 00:13:06.450805 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 6 00:13:06.452199 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 6 00:13:06.453772 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 6 00:13:06.455306 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 6 00:13:06.456845 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 6 00:13:06.458452 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 6 00:13:06.459998 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 6 00:13:06.461450 systemd[1]: Stopped target swap.target - Swaps. Sep 6 00:13:06.462713 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 6 00:13:06.462862 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 6 00:13:06.464681 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 6 00:13:06.466235 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 6 00:13:06.467722 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 6 00:13:06.471112 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 6 00:13:06.472083 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 6 00:13:06.472200 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 6 00:13:06.474600 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 6 00:13:06.474710 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 6 00:13:06.476184 systemd[1]: Stopped target paths.target - Path Units. Sep 6 00:13:06.477474 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 6 00:13:06.478126 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 6 00:13:06.479728 systemd[1]: Stopped target slices.target - Slice Units. Sep 6 00:13:06.481991 systemd[1]: Stopped target sockets.target - Socket Units. Sep 6 00:13:06.483097 systemd[1]: iscsid.socket: Deactivated successfully. Sep 6 00:13:06.483186 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 6 00:13:06.484814 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 6 00:13:06.484894 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 6 00:13:06.486240 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 6 00:13:06.486354 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 6 00:13:06.487942 systemd[1]: ignition-files.service: Deactivated successfully. Sep 6 00:13:06.488054 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 6 00:13:06.499302 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 6 00:13:06.501037 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 6 00:13:06.501702 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 6 00:13:06.501832 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 6 00:13:06.503839 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 6 00:13:06.503959 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 6 00:13:06.509480 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 6 00:13:06.510349 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 6 00:13:06.514618 ignition[996]: INFO : Ignition 2.19.0 Sep 6 00:13:06.514618 ignition[996]: INFO : Stage: umount Sep 6 00:13:06.516268 ignition[996]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 6 00:13:06.516268 ignition[996]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 6 00:13:06.516268 ignition[996]: INFO : umount: umount passed Sep 6 00:13:06.516268 ignition[996]: INFO : Ignition finished successfully Sep 6 00:13:06.517341 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 6 00:13:06.517449 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 6 00:13:06.519964 systemd[1]: Stopped target network.target - Network. Sep 6 00:13:06.520875 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 6 00:13:06.520935 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 6 00:13:06.522290 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 6 00:13:06.522332 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 6 00:13:06.524770 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 6 00:13:06.524815 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 6 00:13:06.526116 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 6 00:13:06.526153 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 6 00:13:06.527626 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 6 00:13:06.529552 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 6 00:13:06.531646 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 6 00:13:06.538086 systemd-networkd[769]: eth0: DHCPv6 lease lost Sep 6 00:13:06.540248 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 6 00:13:06.542111 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 6 00:13:06.543471 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 6 00:13:06.543679 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 6 00:13:06.546444 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 6 00:13:06.546488 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 6 00:13:06.558156 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 6 00:13:06.558859 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 6 00:13:06.558917 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 6 00:13:06.560556 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 6 00:13:06.560594 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 6 00:13:06.562470 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 6 00:13:06.562519 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 6 00:13:06.564119 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 6 00:13:06.564159 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 6 00:13:06.565952 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 6 00:13:06.574877 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 6 00:13:06.575531 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 6 00:13:06.578710 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 6 00:13:06.578853 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 6 00:13:06.582516 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 6 00:13:06.582597 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 6 00:13:06.584400 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 6 00:13:06.584458 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 6 00:13:06.585880 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 6 00:13:06.585912 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 6 00:13:06.587349 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 6 00:13:06.587406 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 6 00:13:06.589478 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 6 00:13:06.589517 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 6 00:13:06.591529 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 6 00:13:06.591574 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 6 00:13:06.593836 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 6 00:13:06.593880 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 6 00:13:06.604184 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 6 00:13:06.604963 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 6 00:13:06.605032 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 6 00:13:06.606848 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 6 00:13:06.606890 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 6 00:13:06.608550 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 6 00:13:06.608591 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 6 00:13:06.610390 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 6 00:13:06.610436 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 6 00:13:06.612197 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 6 00:13:06.614052 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 6 00:13:06.616685 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 6 00:13:06.618516 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 6 00:13:06.627623 systemd[1]: Switching root. Sep 6 00:13:06.657454 systemd-journald[238]: Journal stopped Sep 6 00:13:07.350938 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). Sep 6 00:13:07.350991 kernel: SELinux: policy capability network_peer_controls=1 Sep 6 00:13:07.351005 kernel: SELinux: policy capability open_perms=1 Sep 6 00:13:07.351111 kernel: SELinux: policy capability extended_socket_class=1 Sep 6 00:13:07.351124 kernel: SELinux: policy capability always_check_network=0 Sep 6 00:13:07.351134 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 6 00:13:07.351182 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 6 00:13:07.351197 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 6 00:13:07.351207 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 6 00:13:07.351216 kernel: audit: type=1403 audit(1757117586.811:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 6 00:13:07.351227 systemd[1]: Successfully loaded SELinux policy in 35.635ms. Sep 6 00:13:07.351252 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 10.530ms. Sep 6 00:13:07.351264 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) Sep 6 00:13:07.351275 systemd[1]: Detected virtualization kvm. Sep 6 00:13:07.351285 systemd[1]: Detected architecture arm64. Sep 6 00:13:07.351295 systemd[1]: Detected first boot. Sep 6 00:13:07.351305 systemd[1]: Initializing machine ID from VM UUID. Sep 6 00:13:07.351317 zram_generator::config[1041]: No configuration found. Sep 6 00:13:07.351328 systemd[1]: Populated /etc with preset unit settings. Sep 6 00:13:07.351338 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 6 00:13:07.351349 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 6 00:13:07.351359 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 6 00:13:07.351371 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 6 00:13:07.351392 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 6 00:13:07.351404 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 6 00:13:07.351417 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 6 00:13:07.351427 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 6 00:13:07.351438 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 6 00:13:07.351451 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 6 00:13:07.351461 systemd[1]: Created slice user.slice - User and Session Slice. Sep 6 00:13:07.351472 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 6 00:13:07.351483 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 6 00:13:07.351493 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 6 00:13:07.351509 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 6 00:13:07.351521 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 6 00:13:07.351531 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 6 00:13:07.351541 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 6 00:13:07.351552 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 6 00:13:07.351562 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 6 00:13:07.351573 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 6 00:13:07.351583 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 6 00:13:07.351595 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 6 00:13:07.351606 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 6 00:13:07.351617 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 6 00:13:07.351627 systemd[1]: Reached target slices.target - Slice Units. Sep 6 00:13:07.351637 systemd[1]: Reached target swap.target - Swaps. Sep 6 00:13:07.351648 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 6 00:13:07.351658 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 6 00:13:07.351686 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 6 00:13:07.351699 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 6 00:13:07.351710 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 6 00:13:07.351723 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 6 00:13:07.351734 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 6 00:13:07.351744 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 6 00:13:07.351755 systemd[1]: Mounting media.mount - External Media Directory... Sep 6 00:13:07.351765 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 6 00:13:07.351775 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 6 00:13:07.351786 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 6 00:13:07.351797 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 6 00:13:07.351809 systemd[1]: Reached target machines.target - Containers. Sep 6 00:13:07.351831 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 6 00:13:07.351842 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 6 00:13:07.351853 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 6 00:13:07.351864 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 6 00:13:07.351874 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 6 00:13:07.351884 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 6 00:13:07.351894 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 6 00:13:07.351905 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 6 00:13:07.351917 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 6 00:13:07.351928 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 6 00:13:07.351940 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 6 00:13:07.351950 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 6 00:13:07.351961 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 6 00:13:07.351972 systemd[1]: Stopped systemd-fsck-usr.service. Sep 6 00:13:07.351981 kernel: loop: module loaded Sep 6 00:13:07.351995 kernel: fuse: init (API version 7.39) Sep 6 00:13:07.352007 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 6 00:13:07.352116 kernel: ACPI: bus type drm_connector registered Sep 6 00:13:07.352127 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 6 00:13:07.352138 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 6 00:13:07.352148 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 6 00:13:07.352159 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 6 00:13:07.352169 systemd[1]: verity-setup.service: Deactivated successfully. Sep 6 00:13:07.352179 systemd[1]: Stopped verity-setup.service. Sep 6 00:13:07.352213 systemd-journald[1105]: Collecting audit messages is disabled. Sep 6 00:13:07.352238 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 6 00:13:07.352248 systemd-journald[1105]: Journal started Sep 6 00:13:07.352270 systemd-journald[1105]: Runtime Journal (/run/log/journal/41eec53719a248d6a83b1a0fa9165862) is 5.9M, max 47.3M, 41.4M free. Sep 6 00:13:07.162619 systemd[1]: Queued start job for default target multi-user.target. Sep 6 00:13:07.180466 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 6 00:13:07.180819 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 6 00:13:07.355398 systemd[1]: Started systemd-journald.service - Journal Service. Sep 6 00:13:07.355973 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 6 00:13:07.357244 systemd[1]: Mounted media.mount - External Media Directory. Sep 6 00:13:07.358178 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 6 00:13:07.359141 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 6 00:13:07.360104 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 6 00:13:07.363044 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 6 00:13:07.364182 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 6 00:13:07.365506 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 6 00:13:07.365656 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 6 00:13:07.366957 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 6 00:13:07.367139 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 6 00:13:07.368234 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 6 00:13:07.368369 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 6 00:13:07.369543 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 6 00:13:07.369688 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 6 00:13:07.370994 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 6 00:13:07.371159 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 6 00:13:07.372211 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 6 00:13:07.372336 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 6 00:13:07.373418 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 6 00:13:07.374628 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 6 00:13:07.375895 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 6 00:13:07.387863 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 6 00:13:07.396160 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 6 00:13:07.398081 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 6 00:13:07.398929 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 6 00:13:07.398968 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 6 00:13:07.400752 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 6 00:13:07.402872 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 6 00:13:07.404897 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 6 00:13:07.405880 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 6 00:13:07.407260 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 6 00:13:07.409072 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 6 00:13:07.410061 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 6 00:13:07.413182 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 6 00:13:07.415761 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 6 00:13:07.416668 systemd-journald[1105]: Time spent on flushing to /var/log/journal/41eec53719a248d6a83b1a0fa9165862 is 30.464ms for 836 entries. Sep 6 00:13:07.416668 systemd-journald[1105]: System Journal (/var/log/journal/41eec53719a248d6a83b1a0fa9165862) is 8.0M, max 195.6M, 187.6M free. Sep 6 00:13:07.461136 systemd-journald[1105]: Received client request to flush runtime journal. Sep 6 00:13:07.461191 kernel: loop0: detected capacity change from 0 to 114328 Sep 6 00:13:07.418425 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 6 00:13:07.422192 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 6 00:13:07.425299 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 6 00:13:07.429002 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 6 00:13:07.430490 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 6 00:13:07.431525 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 6 00:13:07.433205 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 6 00:13:07.437461 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 6 00:13:07.447288 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 6 00:13:07.448219 systemd-tmpfiles[1153]: ACLs are not supported, ignoring. Sep 6 00:13:07.448251 systemd-tmpfiles[1153]: ACLs are not supported, ignoring. Sep 6 00:13:07.455180 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 6 00:13:07.459008 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 6 00:13:07.462337 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 6 00:13:07.472251 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 6 00:13:07.474180 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 6 00:13:07.476285 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 6 00:13:07.476934 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 6 00:13:07.485060 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 6 00:13:07.496279 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 6 00:13:07.498245 udevadm[1162]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 6 00:13:07.509037 kernel: loop1: detected capacity change from 0 to 211168 Sep 6 00:13:07.521084 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 6 00:13:07.527176 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 6 00:13:07.539102 systemd-tmpfiles[1175]: ACLs are not supported, ignoring. Sep 6 00:13:07.539120 systemd-tmpfiles[1175]: ACLs are not supported, ignoring. Sep 6 00:13:07.543440 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 6 00:13:07.544432 kernel: loop2: detected capacity change from 0 to 114432 Sep 6 00:13:07.581387 kernel: loop3: detected capacity change from 0 to 114328 Sep 6 00:13:07.585059 kernel: loop4: detected capacity change from 0 to 211168 Sep 6 00:13:07.591057 kernel: loop5: detected capacity change from 0 to 114432 Sep 6 00:13:07.594595 (sd-merge)[1179]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 6 00:13:07.594993 (sd-merge)[1179]: Merged extensions into '/usr'. Sep 6 00:13:07.598410 systemd[1]: Reloading requested from client PID 1152 ('systemd-sysext') (unit systemd-sysext.service)... Sep 6 00:13:07.598424 systemd[1]: Reloading... Sep 6 00:13:07.653170 zram_generator::config[1202]: No configuration found. Sep 6 00:13:07.728877 ldconfig[1147]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 6 00:13:07.747912 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 6 00:13:07.783330 systemd[1]: Reloading finished in 184 ms. Sep 6 00:13:07.821069 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 6 00:13:07.822274 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 6 00:13:07.836188 systemd[1]: Starting ensure-sysext.service... Sep 6 00:13:07.837988 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 6 00:13:07.845924 systemd[1]: Reloading requested from client PID 1239 ('systemctl') (unit ensure-sysext.service)... Sep 6 00:13:07.845941 systemd[1]: Reloading... Sep 6 00:13:07.859824 systemd-tmpfiles[1240]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 6 00:13:07.860099 systemd-tmpfiles[1240]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 6 00:13:07.860726 systemd-tmpfiles[1240]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 6 00:13:07.860941 systemd-tmpfiles[1240]: ACLs are not supported, ignoring. Sep 6 00:13:07.860995 systemd-tmpfiles[1240]: ACLs are not supported, ignoring. Sep 6 00:13:07.863307 systemd-tmpfiles[1240]: Detected autofs mount point /boot during canonicalization of boot. Sep 6 00:13:07.863319 systemd-tmpfiles[1240]: Skipping /boot Sep 6 00:13:07.869916 systemd-tmpfiles[1240]: Detected autofs mount point /boot during canonicalization of boot. Sep 6 00:13:07.869931 systemd-tmpfiles[1240]: Skipping /boot Sep 6 00:13:07.902037 zram_generator::config[1267]: No configuration found. Sep 6 00:13:07.983329 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 6 00:13:08.019131 systemd[1]: Reloading finished in 172 ms. Sep 6 00:13:08.034100 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 6 00:13:08.041412 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 6 00:13:08.050044 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 6 00:13:08.052931 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 6 00:13:08.055621 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 6 00:13:08.061290 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 6 00:13:08.074367 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 6 00:13:08.079368 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 6 00:13:08.081264 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 6 00:13:08.085115 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 6 00:13:08.086773 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 6 00:13:08.089220 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 6 00:13:08.092955 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 6 00:13:08.095160 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 6 00:13:08.096363 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 6 00:13:08.099223 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 6 00:13:08.101640 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 6 00:13:08.101792 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 6 00:13:08.103203 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 6 00:13:08.103345 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 6 00:13:08.104739 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 6 00:13:08.104858 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 6 00:13:08.111765 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 6 00:13:08.116308 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 6 00:13:08.117382 augenrules[1334]: No rules Sep 6 00:13:08.118500 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 6 00:13:08.123264 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 6 00:13:08.123908 systemd-udevd[1314]: Using default interface naming scheme 'v255'. Sep 6 00:13:08.125101 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 6 00:13:08.126408 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 6 00:13:08.127957 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 6 00:13:08.129705 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 6 00:13:08.131393 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 6 00:13:08.132676 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 6 00:13:08.132798 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 6 00:13:08.134338 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 6 00:13:08.134495 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 6 00:13:08.136029 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 6 00:13:08.136147 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 6 00:13:08.140502 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 6 00:13:08.148202 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 6 00:13:08.158565 systemd[1]: Finished ensure-sysext.service. Sep 6 00:13:08.163969 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 6 00:13:08.179253 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 6 00:13:08.184188 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 6 00:13:08.188412 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 6 00:13:08.193041 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1361) Sep 6 00:13:08.194422 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 6 00:13:08.196210 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 6 00:13:08.198677 systemd-resolved[1308]: Positive Trust Anchors: Sep 6 00:13:08.198693 systemd-resolved[1308]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 6 00:13:08.198725 systemd-resolved[1308]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 6 00:13:08.200181 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 6 00:13:08.205350 systemd-resolved[1308]: Defaulting to hostname 'linux'. Sep 6 00:13:08.218997 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 6 00:13:08.220150 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 6 00:13:08.220433 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 6 00:13:08.222921 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 6 00:13:08.223714 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 6 00:13:08.225517 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 6 00:13:08.225920 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 6 00:13:08.227575 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 6 00:13:08.227928 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 6 00:13:08.229735 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 6 00:13:08.230037 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 6 00:13:08.238448 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 6 00:13:08.250282 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 6 00:13:08.253771 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 6 00:13:08.257513 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 6 00:13:08.258421 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 6 00:13:08.258479 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 6 00:13:08.273947 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 6 00:13:08.289260 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 6 00:13:08.300268 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 6 00:13:08.308097 systemd-networkd[1379]: lo: Link UP Sep 6 00:13:08.308107 systemd-networkd[1379]: lo: Gained carrier Sep 6 00:13:08.308219 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 6 00:13:08.308813 systemd-networkd[1379]: Enumeration completed Sep 6 00:13:08.309356 systemd-networkd[1379]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 6 00:13:08.309364 systemd-networkd[1379]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 6 00:13:08.309367 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 6 00:13:08.310037 systemd-networkd[1379]: eth0: Link UP Sep 6 00:13:08.310043 systemd-networkd[1379]: eth0: Gained carrier Sep 6 00:13:08.310056 systemd-networkd[1379]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 6 00:13:08.310754 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 6 00:13:08.311896 systemd[1]: Reached target network.target - Network. Sep 6 00:13:08.312758 systemd[1]: Reached target time-set.target - System Time Set. Sep 6 00:13:08.314941 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 6 00:13:08.322990 lvm[1399]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 6 00:13:08.329228 systemd-networkd[1379]: eth0: DHCPv4 address 10.0.0.136/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 6 00:13:08.329900 systemd-timesyncd[1381]: Network configuration changed, trying to establish connection. Sep 6 00:13:08.331026 systemd-timesyncd[1381]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 6 00:13:08.331081 systemd-timesyncd[1381]: Initial clock synchronization to Sat 2025-09-06 00:13:08.494933 UTC. Sep 6 00:13:08.341729 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 6 00:13:08.350551 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 6 00:13:08.351750 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 6 00:13:08.352649 systemd[1]: Reached target sysinit.target - System Initialization. Sep 6 00:13:08.353532 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 6 00:13:08.354489 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 6 00:13:08.355583 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 6 00:13:08.356494 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 6 00:13:08.357441 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 6 00:13:08.358348 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 6 00:13:08.358386 systemd[1]: Reached target paths.target - Path Units. Sep 6 00:13:08.359029 systemd[1]: Reached target timers.target - Timer Units. Sep 6 00:13:08.360669 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 6 00:13:08.362910 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 6 00:13:08.371928 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 6 00:13:08.374065 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 6 00:13:08.375324 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 6 00:13:08.376229 systemd[1]: Reached target sockets.target - Socket Units. Sep 6 00:13:08.376930 systemd[1]: Reached target basic.target - Basic System. Sep 6 00:13:08.377720 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 6 00:13:08.377750 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 6 00:13:08.378667 systemd[1]: Starting containerd.service - containerd container runtime... Sep 6 00:13:08.380467 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 6 00:13:08.383171 lvm[1408]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 6 00:13:08.384163 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 6 00:13:08.386266 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 6 00:13:08.388162 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 6 00:13:08.389159 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 6 00:13:08.389800 jq[1411]: false Sep 6 00:13:08.392135 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 6 00:13:08.396206 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 6 00:13:08.400646 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 6 00:13:08.402754 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 6 00:13:08.403528 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 6 00:13:08.404878 systemd[1]: Starting update-engine.service - Update Engine... Sep 6 00:13:08.411189 extend-filesystems[1412]: Found loop3 Sep 6 00:13:08.411189 extend-filesystems[1412]: Found loop4 Sep 6 00:13:08.411189 extend-filesystems[1412]: Found loop5 Sep 6 00:13:08.411189 extend-filesystems[1412]: Found vda Sep 6 00:13:08.411189 extend-filesystems[1412]: Found vda1 Sep 6 00:13:08.411189 extend-filesystems[1412]: Found vda2 Sep 6 00:13:08.411189 extend-filesystems[1412]: Found vda3 Sep 6 00:13:08.411189 extend-filesystems[1412]: Found usr Sep 6 00:13:08.411189 extend-filesystems[1412]: Found vda4 Sep 6 00:13:08.411189 extend-filesystems[1412]: Found vda6 Sep 6 00:13:08.411189 extend-filesystems[1412]: Found vda7 Sep 6 00:13:08.411189 extend-filesystems[1412]: Found vda9 Sep 6 00:13:08.411189 extend-filesystems[1412]: Checking size of /dev/vda9 Sep 6 00:13:08.410154 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 6 00:13:08.435902 update_engine[1420]: I20250906 00:13:08.429113 1420 main.cc:92] Flatcar Update Engine starting Sep 6 00:13:08.436258 extend-filesystems[1412]: Resized partition /dev/vda9 Sep 6 00:13:08.440964 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 6 00:13:08.440991 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 37 scanned by (udev-worker) (1349) Sep 6 00:13:08.423505 dbus-daemon[1410]: [system] SELinux support is enabled Sep 6 00:13:08.412630 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 6 00:13:08.441509 extend-filesystems[1433]: resize2fs 1.47.1 (20-May-2024) Sep 6 00:13:08.442690 update_engine[1420]: I20250906 00:13:08.439800 1420 update_check_scheduler.cc:74] Next update check in 9m5s Sep 6 00:13:08.424943 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 6 00:13:08.442923 jq[1421]: true Sep 6 00:13:08.430973 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 6 00:13:08.431149 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 6 00:13:08.431404 systemd[1]: motdgen.service: Deactivated successfully. Sep 6 00:13:08.431534 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 6 00:13:08.435628 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 6 00:13:08.435774 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 6 00:13:08.462055 jq[1434]: true Sep 6 00:13:08.463434 (ntainerd)[1435]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 6 00:13:08.470863 systemd[1]: Started update-engine.service - Update Engine. Sep 6 00:13:08.473558 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 6 00:13:08.473592 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 6 00:13:08.475074 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 6 00:13:08.475096 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 6 00:13:08.483036 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 6 00:13:08.487484 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 6 00:13:08.501388 extend-filesystems[1433]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 6 00:13:08.501388 extend-filesystems[1433]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 6 00:13:08.501388 extend-filesystems[1433]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 6 00:13:08.508000 extend-filesystems[1412]: Resized filesystem in /dev/vda9 Sep 6 00:13:08.505310 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 6 00:13:08.505348 systemd-logind[1418]: Watching system buttons on /dev/input/event0 (Power Button) Sep 6 00:13:08.505543 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 6 00:13:08.505892 systemd-logind[1418]: New seat seat0. Sep 6 00:13:08.511230 systemd[1]: Started systemd-logind.service - User Login Management. Sep 6 00:13:08.514362 bash[1460]: Updated "/home/core/.ssh/authorized_keys" Sep 6 00:13:08.517581 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 6 00:13:08.519629 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 6 00:13:08.524052 locksmithd[1450]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 6 00:13:08.626420 containerd[1435]: time="2025-09-06T00:13:08.626311240Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Sep 6 00:13:08.652075 containerd[1435]: time="2025-09-06T00:13:08.652023240Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 6 00:13:08.653408 containerd[1435]: time="2025-09-06T00:13:08.653356640Z" 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.103-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 6 00:13:08.653408 containerd[1435]: time="2025-09-06T00:13:08.653400240Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 6 00:13:08.653457 containerd[1435]: time="2025-09-06T00:13:08.653418200Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 6 00:13:08.653602 containerd[1435]: time="2025-09-06T00:13:08.653574880Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 6 00:13:08.653602 containerd[1435]: time="2025-09-06T00:13:08.653599200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 6 00:13:08.653676 containerd[1435]: time="2025-09-06T00:13:08.653657120Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 6 00:13:08.653676 containerd[1435]: time="2025-09-06T00:13:08.653672960Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 6 00:13:08.653852 containerd[1435]: time="2025-09-06T00:13:08.653823760Z" 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 Sep 6 00:13:08.653852 containerd[1435]: time="2025-09-06T00:13:08.653845760Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 6 00:13:08.653902 containerd[1435]: time="2025-09-06T00:13:08.653858200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 6 00:13:08.653902 containerd[1435]: time="2025-09-06T00:13:08.653867720Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 6 00:13:08.653958 containerd[1435]: time="2025-09-06T00:13:08.653936360Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 6 00:13:08.654184 containerd[1435]: time="2025-09-06T00:13:08.654152200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 6 00:13:08.654279 containerd[1435]: time="2025-09-06T00:13:08.654259800Z" 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 Sep 6 00:13:08.654279 containerd[1435]: time="2025-09-06T00:13:08.654277920Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 6 00:13:08.654375 containerd[1435]: time="2025-09-06T00:13:08.654354080Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 6 00:13:08.654424 containerd[1435]: time="2025-09-06T00:13:08.654410440Z" level=info msg="metadata content store policy set" policy=shared Sep 6 00:13:08.657946 containerd[1435]: time="2025-09-06T00:13:08.657905600Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 6 00:13:08.657975 containerd[1435]: time="2025-09-06T00:13:08.657957880Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 6 00:13:08.658006 containerd[1435]: time="2025-09-06T00:13:08.657974320Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 6 00:13:08.658006 containerd[1435]: time="2025-09-06T00:13:08.657991360Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 6 00:13:08.658055 containerd[1435]: time="2025-09-06T00:13:08.658004360Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 6 00:13:08.658181 containerd[1435]: time="2025-09-06T00:13:08.658163120Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 6 00:13:08.658455 containerd[1435]: time="2025-09-06T00:13:08.658424800Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 6 00:13:08.658613 containerd[1435]: time="2025-09-06T00:13:08.658593160Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 6 00:13:08.658645 containerd[1435]: time="2025-09-06T00:13:08.658617760Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 6 00:13:08.658645 containerd[1435]: time="2025-09-06T00:13:08.658631040Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 6 00:13:08.658681 containerd[1435]: time="2025-09-06T00:13:08.658646000Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 6 00:13:08.658681 containerd[1435]: time="2025-09-06T00:13:08.658660400Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 6 00:13:08.658681 containerd[1435]: time="2025-09-06T00:13:08.658672400Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 6 00:13:08.658732 containerd[1435]: time="2025-09-06T00:13:08.658686200Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 6 00:13:08.658732 containerd[1435]: time="2025-09-06T00:13:08.658700040Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 6 00:13:08.658732 containerd[1435]: time="2025-09-06T00:13:08.658712880Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 6 00:13:08.658732 containerd[1435]: time="2025-09-06T00:13:08.658724720Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 6 00:13:08.658795 containerd[1435]: time="2025-09-06T00:13:08.658736680Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 6 00:13:08.658795 containerd[1435]: time="2025-09-06T00:13:08.658756760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658795 containerd[1435]: time="2025-09-06T00:13:08.658772320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658795 containerd[1435]: time="2025-09-06T00:13:08.658785120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658863 containerd[1435]: time="2025-09-06T00:13:08.658803880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658863 containerd[1435]: time="2025-09-06T00:13:08.658816720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658863 containerd[1435]: time="2025-09-06T00:13:08.658829320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658863 containerd[1435]: time="2025-09-06T00:13:08.658840520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658863 containerd[1435]: time="2025-09-06T00:13:08.658852760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658977 containerd[1435]: time="2025-09-06T00:13:08.658864680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658977 containerd[1435]: time="2025-09-06T00:13:08.658878840Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658977 containerd[1435]: time="2025-09-06T00:13:08.658889800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658977 containerd[1435]: time="2025-09-06T00:13:08.658901560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658977 containerd[1435]: time="2025-09-06T00:13:08.658914280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658977 containerd[1435]: time="2025-09-06T00:13:08.658937280Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 6 00:13:08.658977 containerd[1435]: time="2025-09-06T00:13:08.658958200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.658977 containerd[1435]: time="2025-09-06T00:13:08.658971000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.659132 containerd[1435]: time="2025-09-06T00:13:08.658982400Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 6 00:13:08.659132 containerd[1435]: time="2025-09-06T00:13:08.659122560Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 6 00:13:08.659168 containerd[1435]: time="2025-09-06T00:13:08.659141080Z" 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 Sep 6 00:13:08.659168 containerd[1435]: time="2025-09-06T00:13:08.659152560Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 6 00:13:08.659312 containerd[1435]: time="2025-09-06T00:13:08.659166120Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 6 00:13:08.659332 containerd[1435]: time="2025-09-06T00:13:08.659311360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.659332 containerd[1435]: time="2025-09-06T00:13:08.659327320Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 6 00:13:08.659379 containerd[1435]: time="2025-09-06T00:13:08.659337400Z" level=info msg="NRI interface is disabled by configuration." Sep 6 00:13:08.659379 containerd[1435]: time="2025-09-06T00:13:08.659347600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 6 00:13:08.659757 containerd[1435]: time="2025-09-06T00:13:08.659686600Z" 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}" Sep 6 00:13:08.659757 containerd[1435]: time="2025-09-06T00:13:08.659752280Z" level=info msg="Connect containerd service" Sep 6 00:13:08.659880 containerd[1435]: time="2025-09-06T00:13:08.659777360Z" level=info msg="using legacy CRI server" Sep 6 00:13:08.659880 containerd[1435]: time="2025-09-06T00:13:08.659784040Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 6 00:13:08.659880 containerd[1435]: time="2025-09-06T00:13:08.659857880Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.661599960Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.661813080Z" level=info msg="Start subscribing containerd event" Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.661862640Z" level=info msg="Start recovering state" Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.661934360Z" level=info msg="Start event monitor" Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.661944720Z" level=info msg="Start snapshots syncer" Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.661952960Z" level=info msg="Start cni network conf syncer for default" Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.661959680Z" level=info msg="Start streaming server" Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.662069720Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.662110880Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 6 00:13:08.664032 containerd[1435]: time="2025-09-06T00:13:08.662161840Z" level=info msg="containerd successfully booted in 0.037448s" Sep 6 00:13:08.662248 systemd[1]: Started containerd.service - containerd container runtime. Sep 6 00:13:09.470021 sshd_keygen[1430]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 6 00:13:09.494258 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 6 00:13:09.507476 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 6 00:13:09.513749 systemd[1]: issuegen.service: Deactivated successfully. Sep 6 00:13:09.513977 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 6 00:13:09.517309 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 6 00:13:09.531061 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 6 00:13:09.533579 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 6 00:13:09.535509 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 6 00:13:09.536620 systemd[1]: Reached target getty.target - Login Prompts. Sep 6 00:13:09.890117 systemd-networkd[1379]: eth0: Gained IPv6LL Sep 6 00:13:09.892717 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 6 00:13:09.896340 systemd[1]: Reached target network-online.target - Network is Online. Sep 6 00:13:09.909342 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 6 00:13:09.911703 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 6 00:13:09.913758 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 6 00:13:09.934796 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 6 00:13:09.946147 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 6 00:13:09.948087 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 6 00:13:09.949720 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 6 00:13:10.586889 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 6 00:13:10.588370 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 6 00:13:10.599272 systemd[1]: Startup finished in 573ms (kernel) + 5.114s (initrd) + 3.824s (userspace) = 9.512s. Sep 6 00:13:10.599476 (kubelet)[1516]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 6 00:13:10.987966 kubelet[1516]: E0906 00:13:10.987447 1516 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Sep 6 00:13:10.990485 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 6 00:13:10.990630 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 6 00:13:14.078781 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 6 00:13:14.079871 systemd[1]: Started sshd@0-10.0.0.136:22-10.0.0.1:33634.service - OpenSSH per-connection server daemon (10.0.0.1:33634). Sep 6 00:13:14.147927 sshd[1529]: Accepted publickey for core from 10.0.0.1 port 33634 ssh2: RSA SHA256:E7E9sF+nY9ImF9J6oXtqDQFV+WdmWbsw1aLuJ7lYdh8 Sep 6 00:13:14.150424 sshd[1529]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 6 00:13:14.164563 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 6 00:13:14.175709 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 6 00:13:14.179153 systemd-logind[1418]: New session 1 of user core. Sep 6 00:13:14.191554 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 6 00:13:14.205322 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 6 00:13:14.208153 (systemd)[1533]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 6 00:13:14.288377 systemd[1533]: Queued start job for default target default.target. Sep 6 00:13:14.300991 systemd[1533]: Created slice app.slice - User Application Slice. Sep 6 00:13:14.301036 systemd[1533]: Reached target paths.target - Paths. Sep 6 00:13:14.301050 systemd[1533]: Reached target timers.target - Timers. Sep 6 00:13:14.302358 systemd[1533]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 6 00:13:14.312725 systemd[1533]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 6 00:13:14.312793 systemd[1533]: Reached target sockets.target - Sockets. Sep 6 00:13:14.312805 systemd[1533]: Reached target basic.target - Basic System. Sep 6 00:13:14.312841 systemd[1533]: Reached target default.target - Main User Target. Sep 6 00:13:14.312874 systemd[1533]: Startup finished in 99ms. Sep 6 00:13:14.313127 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 6 00:13:14.314461 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 6 00:13:14.375980 systemd[1]: Started sshd@1-10.0.0.136:22-10.0.0.1:33648.service - OpenSSH per-connection server daemon (10.0.0.1:33648). Sep 6 00:13:14.422769 sshd[1544]: Accepted publickey for core from 10.0.0.1 port 33648 ssh2: RSA SHA256:E7E9sF+nY9ImF9J6oXtqDQFV+WdmWbsw1aLuJ7lYdh8 Sep 6 00:13:14.424212 sshd[1544]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 6 00:13:14.428716 systemd-logind[1418]: New session 2 of user core. Sep 6 00:13:14.438188 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 6 00:13:14.493587 sshd[1544]: pam_unix(sshd:session): session closed for user core Sep 6 00:13:14.508444 systemd[1]: sshd@1-10.0.0.136:22-10.0.0.1:33648.service: Deactivated successfully. Sep 6 00:13:14.510472 systemd[1]: session-2.scope: Deactivated successfully. Sep 6 00:13:14.512123 systemd-logind[1418]: Session 2 logged out. Waiting for processes to exit. Sep 6 00:13:14.522404 systemd[1]: Started sshd@2-10.0.0.136:22-10.0.0.1:33652.service - OpenSSH per-connection server daemon (10.0.0.1:33652). Sep 6 00:13:14.526177 systemd-logind[1418]: Removed session 2. Sep 6 00:13:14.560270 sshd[1551]: Accepted publickey for core from 10.0.0.1 port 33652 ssh2: RSA SHA256:E7E9sF+nY9ImF9J6oXtqDQFV+WdmWbsw1aLuJ7lYdh8 Sep 6 00:13:14.561442 sshd[1551]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 6 00:13:14.567057 systemd-logind[1418]: New session 3 of user core. Sep 6 00:13:14.575201 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 6 00:13:14.628506 sshd[1551]: pam_unix(sshd:session): session closed for user core Sep 6 00:13:14.644378 systemd[1]: sshd@2-10.0.0.136:22-10.0.0.1:33652.service: Deactivated successfully. Sep 6 00:13:14.647769 systemd[1]: session-3.scope: Deactivated successfully. Sep 6 00:13:14.649235 systemd-logind[1418]: Session 3 logged out. Waiting for processes to exit. Sep 6 00:13:14.659359 systemd[1]: Started sshd@3-10.0.0.136:22-10.0.0.1:33662.service - OpenSSH per-connection server daemon (10.0.0.1:33662). Sep 6 00:13:14.663312 systemd-logind[1418]: Removed session 3. Sep 6 00:13:14.693599 sshd[1558]: Accepted publickey for core from 10.0.0.1 port 33662 ssh2: RSA SHA256:E7E9sF+nY9ImF9J6oXtqDQFV+WdmWbsw1aLuJ7lYdh8 Sep 6 00:13:14.695306 sshd[1558]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 6 00:13:14.699083 systemd-logind[1418]: New session 4 of user core. Sep 6 00:13:14.710196 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 6 00:13:14.770418 sshd[1558]: pam_unix(sshd:session): session closed for user core Sep 6 00:13:14.781892 systemd[1]: sshd@3-10.0.0.136:22-10.0.0.1:33662.service: Deactivated successfully. Sep 6 00:13:14.783718 systemd[1]: session-4.scope: Deactivated successfully. Sep 6 00:13:14.784785 systemd-logind[1418]: Session 4 logged out. Waiting for processes to exit. Sep 6 00:13:14.785926 systemd[1]: Started sshd@4-10.0.0.136:22-10.0.0.1:33666.service - OpenSSH per-connection server daemon (10.0.0.1:33666). Sep 6 00:13:14.786728 systemd-logind[1418]: Removed session 4. Sep 6 00:13:14.837663 sshd[1565]: Accepted publickey for core from 10.0.0.1 port 33666 ssh2: RSA SHA256:E7E9sF+nY9ImF9J6oXtqDQFV+WdmWbsw1aLuJ7lYdh8 Sep 6 00:13:14.839096 sshd[1565]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 6 00:13:14.843096 systemd-logind[1418]: New session 5 of user core. Sep 6 00:13:14.849167 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 6 00:13:14.908599 sudo[1568]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 6 00:13:14.908917 sudo[1568]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 6 00:13:14.941400 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 6 00:13:14.958130 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 6 00:13:14.959123 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 6 00:13:15.477387 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 6 00:13:15.488278 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 6 00:13:15.515207 systemd[1]: Reloading requested from client PID 1612 ('systemctl') (unit session-5.scope)... Sep 6 00:13:15.515223 systemd[1]: Reloading... Sep 6 00:13:15.599069 zram_generator::config[1650]: No configuration found. Sep 6 00:13:15.827978 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 6 00:13:15.884137 systemd[1]: Reloading finished in 368 ms. Sep 6 00:13:15.924805 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 6 00:13:15.925000 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 6 00:13:15.925366 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 6 00:13:15.936533 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 6 00:13:16.065843 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 6 00:13:16.069346 (kubelet)[1695]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 6 00:13:16.114294 kubelet[1695]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 6 00:13:16.114294 kubelet[1695]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Sep 6 00:13:16.114294 kubelet[1695]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 6 00:13:16.114594 kubelet[1695]: I0906 00:13:16.114318 1695 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 6 00:13:16.922116 kubelet[1695]: I0906 00:13:16.922063 1695 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Sep 6 00:13:16.922116 kubelet[1695]: I0906 00:13:16.922099 1695 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 6 00:13:16.922397 kubelet[1695]: I0906 00:13:16.922378 1695 server.go:956] "Client rotation is on, will bootstrap in background" Sep 6 00:13:16.945758 kubelet[1695]: I0906 00:13:16.945721 1695 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 6 00:13:16.955228 kubelet[1695]: E0906 00:13:16.955189 1695 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Sep 6 00:13:16.955228 kubelet[1695]: I0906 00:13:16.955227 1695 server.go:1423] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Sep 6 00:13:16.961054 kubelet[1695]: I0906 00:13:16.960827 1695 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 6 00:13:16.962014 kubelet[1695]: I0906 00:13:16.961960 1695 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 6 00:13:16.962214 kubelet[1695]: I0906 00:13:16.962009 1695 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.136","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 6 00:13:16.962320 kubelet[1695]: I0906 00:13:16.962255 1695 topology_manager.go:138] "Creating topology manager with none policy" Sep 6 00:13:16.962320 kubelet[1695]: I0906 00:13:16.962265 1695 container_manager_linux.go:303] "Creating device plugin manager" Sep 6 00:13:16.962485 kubelet[1695]: I0906 00:13:16.962466 1695 state_mem.go:36] "Initialized new in-memory state store" Sep 6 00:13:16.965943 kubelet[1695]: I0906 00:13:16.965913 1695 kubelet.go:480] "Attempting to sync node with API server" Sep 6 00:13:16.965943 kubelet[1695]: I0906 00:13:16.965944 1695 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 6 00:13:16.966036 kubelet[1695]: I0906 00:13:16.965973 1695 kubelet.go:386] "Adding apiserver pod source" Sep 6 00:13:16.967060 kubelet[1695]: I0906 00:13:16.967008 1695 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 6 00:13:16.967236 kubelet[1695]: E0906 00:13:16.967156 1695 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:16.967236 kubelet[1695]: E0906 00:13:16.967213 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:16.968114 kubelet[1695]: I0906 00:13:16.968078 1695 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Sep 6 00:13:16.971382 kubelet[1695]: I0906 00:13:16.970528 1695 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Sep 6 00:13:16.971382 kubelet[1695]: W0906 00:13:16.970705 1695 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 6 00:13:16.976514 kubelet[1695]: I0906 00:13:16.976355 1695 watchdog_linux.go:99] "Systemd watchdog is not enabled" Sep 6 00:13:16.976514 kubelet[1695]: I0906 00:13:16.976499 1695 server.go:1289] "Started kubelet" Sep 6 00:13:16.977674 kubelet[1695]: I0906 00:13:16.977129 1695 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Sep 6 00:13:16.978434 kubelet[1695]: I0906 00:13:16.978174 1695 server.go:317] "Adding debug handlers to kubelet server" Sep 6 00:13:16.979324 kubelet[1695]: I0906 00:13:16.978934 1695 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 6 00:13:16.979324 kubelet[1695]: I0906 00:13:16.979269 1695 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 6 00:13:16.979954 kubelet[1695]: I0906 00:13:16.979930 1695 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 6 00:13:16.984479 kubelet[1695]: I0906 00:13:16.984438 1695 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 6 00:13:16.986516 kubelet[1695]: I0906 00:13:16.986481 1695 volume_manager.go:297] "Starting Kubelet Volume Manager" Sep 6 00:13:16.986751 kubelet[1695]: E0906 00:13:16.986698 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:16.989864 kubelet[1695]: I0906 00:13:16.989841 1695 factory.go:223] Registration of the systemd container factory successfully Sep 6 00:13:16.990557 kubelet[1695]: I0906 00:13:16.990417 1695 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Sep 6 00:13:16.991408 kubelet[1695]: I0906 00:13:16.991377 1695 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Sep 6 00:13:16.991463 kubelet[1695]: I0906 00:13:16.991448 1695 reconciler.go:26] "Reconciler: start to sync state" Sep 6 00:13:16.991602 kubelet[1695]: E0906 00:13:16.990367 1695 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.136.18628925145ed0de default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.136,UID:10.0.0.136,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.136,},FirstTimestamp:2025-09-06 00:13:16.97646819 +0000 UTC m=+0.903522218,LastTimestamp:2025-09-06 00:13:16.97646819 +0000 UTC m=+0.903522218,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.136,}" Sep 6 00:13:16.991737 kubelet[1695]: E0906 00:13:16.991717 1695 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 6 00:13:16.991925 kubelet[1695]: E0906 00:13:16.991732 1695 reflector.go:200] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Sep 6 00:13:16.994324 kubelet[1695]: E0906 00:13:16.994170 1695 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.0.0.136\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Sep 6 00:13:16.997582 kubelet[1695]: I0906 00:13:16.997557 1695 factory.go:223] Registration of the containerd container factory successfully Sep 6 00:13:17.002111 kubelet[1695]: E0906 00:13:17.002061 1695 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.136\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Sep 6 00:13:17.008404 kubelet[1695]: E0906 00:13:17.008366 1695 reflector.go:200] "Failed to watch" err="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" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" Sep 6 00:13:17.008857 kubelet[1695]: E0906 00:13:17.008476 1695 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.136.186289251544a61d default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.136,UID:10.0.0.136,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.0.0.136,},FirstTimestamp:2025-09-06 00:13:16.991530525 +0000 UTC m=+0.918584473,LastTimestamp:2025-09-06 00:13:16.991530525 +0000 UTC m=+0.918584473,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.136,}" Sep 6 00:13:17.011670 kubelet[1695]: I0906 00:13:17.011639 1695 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 6 00:13:17.011670 kubelet[1695]: I0906 00:13:17.011655 1695 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 6 00:13:17.011670 kubelet[1695]: I0906 00:13:17.011671 1695 state_mem.go:36] "Initialized new in-memory state store" Sep 6 00:13:17.012775 kubelet[1695]: E0906 00:13:17.012397 1695 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.136.1862892516651877 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.136,UID:10.0.0.136,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientMemory,Message:Node 10.0.0.136 status is now: NodeHasSufficientMemory,Source:EventSource{Component:kubelet,Host:10.0.0.136,},FirstTimestamp:2025-09-06 00:13:17.010434167 +0000 UTC m=+0.937488114,LastTimestamp:2025-09-06 00:13:17.010434167 +0000 UTC m=+0.937488114,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.136,}" Sep 6 00:13:17.087112 kubelet[1695]: E0906 00:13:17.087063 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:17.103792 kubelet[1695]: I0906 00:13:17.103429 1695 policy_none.go:49] "None policy: Start" Sep 6 00:13:17.103792 kubelet[1695]: I0906 00:13:17.103459 1695 memory_manager.go:186] "Starting memorymanager" policy="None" Sep 6 00:13:17.103792 kubelet[1695]: I0906 00:13:17.103472 1695 state_mem.go:35] "Initializing new in-memory state store" Sep 6 00:13:17.114273 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 6 00:13:17.126222 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 6 00:13:17.132272 kubelet[1695]: I0906 00:13:17.132219 1695 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Sep 6 00:13:17.133380 kubelet[1695]: I0906 00:13:17.133347 1695 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Sep 6 00:13:17.133380 kubelet[1695]: I0906 00:13:17.133375 1695 status_manager.go:230] "Starting to sync pod status with apiserver" Sep 6 00:13:17.133501 kubelet[1695]: I0906 00:13:17.133399 1695 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 6 00:13:17.133501 kubelet[1695]: I0906 00:13:17.133406 1695 kubelet.go:2436] "Starting kubelet main sync loop" Sep 6 00:13:17.133501 kubelet[1695]: E0906 00:13:17.133455 1695 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Sep 6 00:13:17.137726 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 6 00:13:17.140432 kubelet[1695]: E0906 00:13:17.140401 1695 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Sep 6 00:13:17.141151 kubelet[1695]: I0906 00:13:17.140653 1695 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 6 00:13:17.141151 kubelet[1695]: I0906 00:13:17.140671 1695 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 6 00:13:17.141151 kubelet[1695]: I0906 00:13:17.140891 1695 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 6 00:13:17.142433 kubelet[1695]: E0906 00:13:17.142412 1695 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 6 00:13:17.142498 kubelet[1695]: E0906 00:13:17.142449 1695 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.136\" not found" Sep 6 00:13:17.210736 kubelet[1695]: E0906 00:13:17.210037 1695 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.136\" not found" node="10.0.0.136" Sep 6 00:13:17.243372 kubelet[1695]: I0906 00:13:17.243343 1695 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.136" Sep 6 00:13:17.251020 kubelet[1695]: I0906 00:13:17.250990 1695 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.136" Sep 6 00:13:17.251119 kubelet[1695]: E0906 00:13:17.251039 1695 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.136\": node \"10.0.0.136\" not found" Sep 6 00:13:17.257449 kubelet[1695]: I0906 00:13:17.257423 1695 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 6 00:13:17.257977 containerd[1435]: time="2025-09-06T00:13:17.257838321Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 6 00:13:17.258259 kubelet[1695]: I0906 00:13:17.258060 1695 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 6 00:13:17.262723 kubelet[1695]: E0906 00:13:17.262673 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:17.363155 kubelet[1695]: E0906 00:13:17.363109 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:17.463807 kubelet[1695]: E0906 00:13:17.463691 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:17.564366 kubelet[1695]: E0906 00:13:17.564327 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:17.616012 sudo[1568]: pam_unix(sudo:session): session closed for user root Sep 6 00:13:17.618938 sshd[1565]: pam_unix(sshd:session): session closed for user core Sep 6 00:13:17.621861 systemd[1]: sshd@4-10.0.0.136:22-10.0.0.1:33666.service: Deactivated successfully. Sep 6 00:13:17.623471 systemd[1]: session-5.scope: Deactivated successfully. Sep 6 00:13:17.625033 systemd-logind[1418]: Session 5 logged out. Waiting for processes to exit. Sep 6 00:13:17.628077 systemd-logind[1418]: Removed session 5. Sep 6 00:13:17.665214 kubelet[1695]: E0906 00:13:17.665161 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:17.766296 kubelet[1695]: E0906 00:13:17.766160 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:17.867111 kubelet[1695]: E0906 00:13:17.867057 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:17.926403 kubelet[1695]: I0906 00:13:17.926133 1695 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 6 00:13:17.926403 kubelet[1695]: I0906 00:13:17.926354 1695 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Sep 6 00:13:17.967696 kubelet[1695]: E0906 00:13:17.967630 1695 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.136\" not found" Sep 6 00:13:17.969601 kubelet[1695]: E0906 00:13:17.967678 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:18.968957 kubelet[1695]: I0906 00:13:18.968849 1695 apiserver.go:52] "Watching apiserver" Sep 6 00:13:18.969981 kubelet[1695]: E0906 00:13:18.969856 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:18.990760 systemd[1]: Created slice kubepods-besteffort-pod8b71b32f_eb79_4a87_aeda_abe79d5336b2.slice - libcontainer container kubepods-besteffort-pod8b71b32f_eb79_4a87_aeda_abe79d5336b2.slice. Sep 6 00:13:18.995046 kubelet[1695]: I0906 00:13:18.994724 1695 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Sep 6 00:13:19.011065 kubelet[1695]: I0906 00:13:19.009581 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/1588cc5f-4d7f-4aef-b015-084d093b9bdc-run\") pod \"kube-flannel-ds-tc8qk\" (UID: \"1588cc5f-4d7f-4aef-b015-084d093b9bdc\") " pod="kube-flannel/kube-flannel-ds-tc8qk" Sep 6 00:13:19.011065 kubelet[1695]: I0906 00:13:19.009622 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/1588cc5f-4d7f-4aef-b015-084d093b9bdc-cni-plugin\") pod \"kube-flannel-ds-tc8qk\" (UID: \"1588cc5f-4d7f-4aef-b015-084d093b9bdc\") " pod="kube-flannel/kube-flannel-ds-tc8qk" Sep 6 00:13:19.011065 kubelet[1695]: I0906 00:13:19.009695 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/1588cc5f-4d7f-4aef-b015-084d093b9bdc-cni\") pod \"kube-flannel-ds-tc8qk\" (UID: \"1588cc5f-4d7f-4aef-b015-084d093b9bdc\") " pod="kube-flannel/kube-flannel-ds-tc8qk" Sep 6 00:13:19.011065 kubelet[1695]: I0906 00:13:19.009718 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/1588cc5f-4d7f-4aef-b015-084d093b9bdc-flannel-cfg\") pod \"kube-flannel-ds-tc8qk\" (UID: \"1588cc5f-4d7f-4aef-b015-084d093b9bdc\") " pod="kube-flannel/kube-flannel-ds-tc8qk" Sep 6 00:13:19.011065 kubelet[1695]: I0906 00:13:19.009743 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/1588cc5f-4d7f-4aef-b015-084d093b9bdc-xtables-lock\") pod \"kube-flannel-ds-tc8qk\" (UID: \"1588cc5f-4d7f-4aef-b015-084d093b9bdc\") " pod="kube-flannel/kube-flannel-ds-tc8qk" Sep 6 00:13:19.011298 kubelet[1695]: I0906 00:13:19.009762 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/8b71b32f-eb79-4a87-aeda-abe79d5336b2-xtables-lock\") pod \"kube-proxy-d9p2n\" (UID: \"8b71b32f-eb79-4a87-aeda-abe79d5336b2\") " pod="kube-system/kube-proxy-d9p2n" Sep 6 00:13:19.011298 kubelet[1695]: I0906 00:13:19.009779 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/8b71b32f-eb79-4a87-aeda-abe79d5336b2-lib-modules\") pod \"kube-proxy-d9p2n\" (UID: \"8b71b32f-eb79-4a87-aeda-abe79d5336b2\") " pod="kube-system/kube-proxy-d9p2n" Sep 6 00:13:19.011298 kubelet[1695]: I0906 00:13:19.009794 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-cg9z4\" (UniqueName: \"kubernetes.io/projected/1588cc5f-4d7f-4aef-b015-084d093b9bdc-kube-api-access-cg9z4\") pod \"kube-flannel-ds-tc8qk\" (UID: \"1588cc5f-4d7f-4aef-b015-084d093b9bdc\") " pod="kube-flannel/kube-flannel-ds-tc8qk" Sep 6 00:13:19.011298 kubelet[1695]: I0906 00:13:19.009828 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/8b71b32f-eb79-4a87-aeda-abe79d5336b2-kube-proxy\") pod \"kube-proxy-d9p2n\" (UID: \"8b71b32f-eb79-4a87-aeda-abe79d5336b2\") " pod="kube-system/kube-proxy-d9p2n" Sep 6 00:13:19.011298 kubelet[1695]: I0906 00:13:19.009847 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9xtkp\" (UniqueName: \"kubernetes.io/projected/8b71b32f-eb79-4a87-aeda-abe79d5336b2-kube-api-access-9xtkp\") pod \"kube-proxy-d9p2n\" (UID: \"8b71b32f-eb79-4a87-aeda-abe79d5336b2\") " pod="kube-system/kube-proxy-d9p2n" Sep 6 00:13:19.014329 systemd[1]: Created slice kubepods-burstable-pod1588cc5f_4d7f_4aef_b015_084d093b9bdc.slice - libcontainer container kubepods-burstable-pod1588cc5f_4d7f_4aef_b015_084d093b9bdc.slice. Sep 6 00:13:19.307133 kubelet[1695]: E0906 00:13:19.306888 1695 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" Sep 6 00:13:19.308971 containerd[1435]: time="2025-09-06T00:13:19.308849372Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-d9p2n,Uid:8b71b32f-eb79-4a87-aeda-abe79d5336b2,Namespace:kube-system,Attempt:0,}" Sep 6 00:13:19.328656 kubelet[1695]: E0906 00:13:19.328616 1695 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" Sep 6 00:13:19.329339 containerd[1435]: time="2025-09-06T00:13:19.329291955Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-tc8qk,Uid:1588cc5f-4d7f-4aef-b015-084d093b9bdc,Namespace:kube-flannel,Attempt:0,}" Sep 6 00:13:19.971150 kubelet[1695]: E0906 00:13:19.971111 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:19.976041 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3762506185.mount: Deactivated successfully. Sep 6 00:13:19.981904 containerd[1435]: time="2025-09-06T00:13:19.981862611Z" 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\"}" Sep 6 00:13:19.984123 containerd[1435]: time="2025-09-06T00:13:19.983766666Z" 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\"}" Sep 6 00:13:19.985383 containerd[1435]: time="2025-09-06T00:13:19.985347627Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Sep 6 00:13:19.986790 containerd[1435]: time="2025-09-06T00:13:19.986468721Z" level=info msg="ImageCreate event name:\"sha256:4e42fb3c9d90ed7895bc04a9d96fe3102a65b521f485cc5a4f3dd818afef9cef\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 6 00:13:19.987688 containerd[1435]: time="2025-09-06T00:13:19.987662123Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Sep 6 00:13:19.990396 containerd[1435]: time="2025-09-06T00:13:19.990365143Z" 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\"}" Sep 6 00:13:19.991692 containerd[1435]: time="2025-09-06T00:13:19.991665961Z" 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 662.300009ms" Sep 6 00:13:19.992357 containerd[1435]: time="2025-09-06T00:13:19.992011052Z" 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 682.920226ms" Sep 6 00:13:20.075951 containerd[1435]: time="2025-09-06T00:13:20.075745690Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 6 00:13:20.075951 containerd[1435]: time="2025-09-06T00:13:20.075808545Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 6 00:13:20.075951 containerd[1435]: time="2025-09-06T00:13:20.075823616Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:20.076646 containerd[1435]: time="2025-09-06T00:13:20.076571448Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:20.080220 containerd[1435]: time="2025-09-06T00:13:20.079413397Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 6 00:13:20.080220 containerd[1435]: time="2025-09-06T00:13:20.079454792Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 6 00:13:20.080220 containerd[1435]: time="2025-09-06T00:13:20.079464557Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:20.080220 containerd[1435]: time="2025-09-06T00:13:20.079535772Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:20.162203 systemd[1]: Started cri-containerd-2263d21cc8961f14aa73c6d4581d654b79fb2e0665908873bf7964603f5b0829.scope - libcontainer container 2263d21cc8961f14aa73c6d4581d654b79fb2e0665908873bf7964603f5b0829. Sep 6 00:13:20.163529 systemd[1]: Started cri-containerd-787c70728b40bb5e3a43ee6249d898d2f3dfa7f6d494432de1178d15eef4300e.scope - libcontainer container 787c70728b40bb5e3a43ee6249d898d2f3dfa7f6d494432de1178d15eef4300e. Sep 6 00:13:20.187121 containerd[1435]: time="2025-09-06T00:13:20.186980567Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-d9p2n,Uid:8b71b32f-eb79-4a87-aeda-abe79d5336b2,Namespace:kube-system,Attempt:0,} returns sandbox id \"787c70728b40bb5e3a43ee6249d898d2f3dfa7f6d494432de1178d15eef4300e\"" Sep 6 00:13:20.191223 kubelet[1695]: E0906 00:13:20.188394 1695 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" Sep 6 00:13:20.191503 containerd[1435]: time="2025-09-06T00:13:20.191471059Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.4\"" Sep 6 00:13:20.198668 containerd[1435]: time="2025-09-06T00:13:20.198610835Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-tc8qk,Uid:1588cc5f-4d7f-4aef-b015-084d093b9bdc,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"2263d21cc8961f14aa73c6d4581d654b79fb2e0665908873bf7964603f5b0829\"" Sep 6 00:13:20.200484 kubelet[1695]: E0906 00:13:20.200439 1695 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" Sep 6 00:13:20.971857 kubelet[1695]: E0906 00:13:20.971801 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:21.238485 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1343688353.mount: Deactivated successfully. Sep 6 00:13:21.470522 containerd[1435]: time="2025-09-06T00:13:21.470473195Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:21.471671 containerd[1435]: time="2025-09-06T00:13:21.471643890Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.4: active requests=0, bytes read=28199961" Sep 6 00:13:21.472633 containerd[1435]: time="2025-09-06T00:13:21.472603436Z" level=info msg="ImageCreate event name:\"sha256:e19c0cda155dad39120317830ddb8b2bc22070f2c6a97973e96fb09ef504ee64\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:21.474930 containerd[1435]: time="2025-09-06T00:13:21.474903293Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:bb04e9247da3aaeb96406b4d530a79fc865695b6807353dd1a28871df0d7f837\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:21.475615 containerd[1435]: time="2025-09-06T00:13:21.475570838Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.4\" with image id \"sha256:e19c0cda155dad39120317830ddb8b2bc22070f2c6a97973e96fb09ef504ee64\", repo tag \"registry.k8s.io/kube-proxy:v1.33.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:bb04e9247da3aaeb96406b4d530a79fc865695b6807353dd1a28871df0d7f837\", size \"28198978\" in 1.284054773s" Sep 6 00:13:21.475646 containerd[1435]: time="2025-09-06T00:13:21.475612249Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.4\" returns image reference \"sha256:e19c0cda155dad39120317830ddb8b2bc22070f2c6a97973e96fb09ef504ee64\"" Sep 6 00:13:21.477540 containerd[1435]: time="2025-09-06T00:13:21.477285731Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Sep 6 00:13:21.479952 containerd[1435]: time="2025-09-06T00:13:21.479923699Z" level=info msg="CreateContainer within sandbox \"787c70728b40bb5e3a43ee6249d898d2f3dfa7f6d494432de1178d15eef4300e\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 6 00:13:21.497239 containerd[1435]: time="2025-09-06T00:13:21.497139657Z" level=info msg="CreateContainer within sandbox \"787c70728b40bb5e3a43ee6249d898d2f3dfa7f6d494432de1178d15eef4300e\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"57f48956459455d31bb018ba416f3c4bc33a126a908ce7614accba983ac0eba1\"" Sep 6 00:13:21.497867 containerd[1435]: time="2025-09-06T00:13:21.497825678Z" level=info msg="StartContainer for \"57f48956459455d31bb018ba416f3c4bc33a126a908ce7614accba983ac0eba1\"" Sep 6 00:13:21.529206 systemd[1]: Started cri-containerd-57f48956459455d31bb018ba416f3c4bc33a126a908ce7614accba983ac0eba1.scope - libcontainer container 57f48956459455d31bb018ba416f3c4bc33a126a908ce7614accba983ac0eba1. Sep 6 00:13:21.559633 containerd[1435]: time="2025-09-06T00:13:21.559583206Z" level=info msg="StartContainer for \"57f48956459455d31bb018ba416f3c4bc33a126a908ce7614accba983ac0eba1\" returns successfully" Sep 6 00:13:21.972508 kubelet[1695]: E0906 00:13:21.972390 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:22.144993 kubelet[1695]: E0906 00:13:22.144961 1695 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" Sep 6 00:13:22.156798 kubelet[1695]: I0906 00:13:22.156589 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-d9p2n" podStartSLOduration=3.871125144 podStartE2EDuration="5.156573164s" podCreationTimestamp="2025-09-06 00:13:17 +0000 UTC" firstStartedPulling="2025-09-06 00:13:20.191101724 +0000 UTC m=+4.118155671" lastFinishedPulling="2025-09-06 00:13:21.476549784 +0000 UTC m=+5.403603691" observedRunningTime="2025-09-06 00:13:22.156170193 +0000 UTC m=+6.083224140" watchObservedRunningTime="2025-09-06 00:13:22.156573164 +0000 UTC m=+6.083627071" Sep 6 00:13:22.381529 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3419130124.mount: Deactivated successfully. Sep 6 00:13:22.421066 containerd[1435]: time="2025-09-06T00:13:22.421005159Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:22.421900 containerd[1435]: time="2025-09-06T00:13:22.421481433Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Sep 6 00:13:22.423041 containerd[1435]: time="2025-09-06T00:13:22.422583039Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:22.427143 containerd[1435]: time="2025-09-06T00:13:22.427068789Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:22.428362 containerd[1435]: time="2025-09-06T00:13:22.428322864Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"5125394\" in 951.004725ms" Sep 6 00:13:22.428362 containerd[1435]: time="2025-09-06T00:13:22.428363289Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Sep 6 00:13:22.434808 containerd[1435]: time="2025-09-06T00:13:22.434750243Z" level=info msg="CreateContainer within sandbox \"2263d21cc8961f14aa73c6d4581d654b79fb2e0665908873bf7964603f5b0829\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 6 00:13:22.454924 containerd[1435]: time="2025-09-06T00:13:22.454878588Z" level=info msg="CreateContainer within sandbox \"2263d21cc8961f14aa73c6d4581d654b79fb2e0665908873bf7964603f5b0829\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"2ba294a83ecef4ab1d34bbc61220b139a7eb6408505fe4bc18448650580d8ea0\"" Sep 6 00:13:22.455687 containerd[1435]: time="2025-09-06T00:13:22.455623349Z" level=info msg="StartContainer for \"2ba294a83ecef4ab1d34bbc61220b139a7eb6408505fe4bc18448650580d8ea0\"" Sep 6 00:13:22.483187 systemd[1]: Started cri-containerd-2ba294a83ecef4ab1d34bbc61220b139a7eb6408505fe4bc18448650580d8ea0.scope - libcontainer container 2ba294a83ecef4ab1d34bbc61220b139a7eb6408505fe4bc18448650580d8ea0. Sep 6 00:13:22.503543 systemd[1]: cri-containerd-2ba294a83ecef4ab1d34bbc61220b139a7eb6408505fe4bc18448650580d8ea0.scope: Deactivated successfully. Sep 6 00:13:22.526129 containerd[1435]: time="2025-09-06T00:13:22.526076953Z" level=info msg="StartContainer for \"2ba294a83ecef4ab1d34bbc61220b139a7eb6408505fe4bc18448650580d8ea0\" returns successfully" Sep 6 00:13:22.689255 containerd[1435]: time="2025-09-06T00:13:22.689121859Z" level=info msg="shim disconnected" id=2ba294a83ecef4ab1d34bbc61220b139a7eb6408505fe4bc18448650580d8ea0 namespace=k8s.io Sep 6 00:13:22.689255 containerd[1435]: time="2025-09-06T00:13:22.689178985Z" level=warning msg="cleaning up after shim disconnected" id=2ba294a83ecef4ab1d34bbc61220b139a7eb6408505fe4bc18448650580d8ea0 namespace=k8s.io Sep 6 00:13:22.689255 containerd[1435]: time="2025-09-06T00:13:22.689188941Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 6 00:13:22.974766 kubelet[1695]: E0906 00:13:22.973208 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:23.148216 kubelet[1695]: E0906 00:13:23.148081 1695 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" Sep 6 00:13:23.149199 kubelet[1695]: E0906 00:13:23.148726 1695 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" Sep 6 00:13:23.149550 containerd[1435]: time="2025-09-06T00:13:23.149454108Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Sep 6 00:13:23.974034 kubelet[1695]: E0906 00:13:23.973968 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:24.637920 containerd[1435]: time="2025-09-06T00:13:24.637857938Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:24.639231 containerd[1435]: time="2025-09-06T00:13:24.639184598Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Sep 6 00:13:24.640724 containerd[1435]: time="2025-09-06T00:13:24.640092102Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:24.646043 containerd[1435]: time="2025-09-06T00:13:24.644929567Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:24.646043 containerd[1435]: time="2025-09-06T00:13:24.645912719Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32412118\" in 1.496405848s" Sep 6 00:13:24.646043 containerd[1435]: time="2025-09-06T00:13:24.645942280Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Sep 6 00:13:24.650175 containerd[1435]: time="2025-09-06T00:13:24.650136170Z" level=info msg="CreateContainer within sandbox \"2263d21cc8961f14aa73c6d4581d654b79fb2e0665908873bf7964603f5b0829\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 6 00:13:24.662742 containerd[1435]: time="2025-09-06T00:13:24.662675282Z" level=info msg="CreateContainer within sandbox \"2263d21cc8961f14aa73c6d4581d654b79fb2e0665908873bf7964603f5b0829\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"86e56496aaf5b144e70b9b5468dd1740a67545d6b3dfd82f30d50322771bc5ce\"" Sep 6 00:13:24.663269 containerd[1435]: time="2025-09-06T00:13:24.663222311Z" level=info msg="StartContainer for \"86e56496aaf5b144e70b9b5468dd1740a67545d6b3dfd82f30d50322771bc5ce\"" Sep 6 00:13:24.690198 systemd[1]: Started cri-containerd-86e56496aaf5b144e70b9b5468dd1740a67545d6b3dfd82f30d50322771bc5ce.scope - libcontainer container 86e56496aaf5b144e70b9b5468dd1740a67545d6b3dfd82f30d50322771bc5ce. Sep 6 00:13:24.709342 systemd[1]: cri-containerd-86e56496aaf5b144e70b9b5468dd1740a67545d6b3dfd82f30d50322771bc5ce.scope: Deactivated successfully. Sep 6 00:13:24.710617 containerd[1435]: time="2025-09-06T00:13:24.710576948Z" level=info msg="StartContainer for \"86e56496aaf5b144e70b9b5468dd1740a67545d6b3dfd82f30d50322771bc5ce\" returns successfully" Sep 6 00:13:24.719644 kubelet[1695]: I0906 00:13:24.719604 1695 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Sep 6 00:13:24.725736 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-86e56496aaf5b144e70b9b5468dd1740a67545d6b3dfd82f30d50322771bc5ce-rootfs.mount: Deactivated successfully. Sep 6 00:13:24.832343 containerd[1435]: time="2025-09-06T00:13:24.832284542Z" level=info msg="shim disconnected" id=86e56496aaf5b144e70b9b5468dd1740a67545d6b3dfd82f30d50322771bc5ce namespace=k8s.io Sep 6 00:13:24.832343 containerd[1435]: time="2025-09-06T00:13:24.832342060Z" level=warning msg="cleaning up after shim disconnected" id=86e56496aaf5b144e70b9b5468dd1740a67545d6b3dfd82f30d50322771bc5ce namespace=k8s.io Sep 6 00:13:24.832343 containerd[1435]: time="2025-09-06T00:13:24.832350323Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 6 00:13:24.974950 kubelet[1695]: E0906 00:13:24.974820 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:25.153124 kubelet[1695]: E0906 00:13:25.152700 1695 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" Sep 6 00:13:25.156196 containerd[1435]: time="2025-09-06T00:13:25.156160509Z" level=info msg="CreateContainer within sandbox \"2263d21cc8961f14aa73c6d4581d654b79fb2e0665908873bf7964603f5b0829\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 6 00:13:25.166493 containerd[1435]: time="2025-09-06T00:13:25.166448471Z" level=info msg="CreateContainer within sandbox \"2263d21cc8961f14aa73c6d4581d654b79fb2e0665908873bf7964603f5b0829\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"74c48dce7f2180fb3f3afc05a2f810e7c54b1a01465aa86a349cde936dd6aba8\"" Sep 6 00:13:25.167001 containerd[1435]: time="2025-09-06T00:13:25.166933482Z" level=info msg="StartContainer for \"74c48dce7f2180fb3f3afc05a2f810e7c54b1a01465aa86a349cde936dd6aba8\"" Sep 6 00:13:25.191171 systemd[1]: Started cri-containerd-74c48dce7f2180fb3f3afc05a2f810e7c54b1a01465aa86a349cde936dd6aba8.scope - libcontainer container 74c48dce7f2180fb3f3afc05a2f810e7c54b1a01465aa86a349cde936dd6aba8. Sep 6 00:13:25.212561 containerd[1435]: time="2025-09-06T00:13:25.212520441Z" level=info msg="StartContainer for \"74c48dce7f2180fb3f3afc05a2f810e7c54b1a01465aa86a349cde936dd6aba8\" returns successfully" Sep 6 00:13:25.975316 kubelet[1695]: E0906 00:13:25.975267 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:26.156253 kubelet[1695]: E0906 00:13:26.156186 1695 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" Sep 6 00:13:26.272304 systemd-networkd[1379]: flannel.1: Link UP Sep 6 00:13:26.272310 systemd-networkd[1379]: flannel.1: Gained carrier Sep 6 00:13:26.975748 kubelet[1695]: E0906 00:13:26.975694 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:27.157699 kubelet[1695]: E0906 00:13:27.157673 1695 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" Sep 6 00:13:27.294183 systemd-networkd[1379]: flannel.1: Gained IPv6LL Sep 6 00:13:27.976223 kubelet[1695]: E0906 00:13:27.976176 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:28.519402 kubelet[1695]: I0906 00:13:28.519349 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-tc8qk" podStartSLOduration=7.073282719 podStartE2EDuration="11.519330316s" podCreationTimestamp="2025-09-06 00:13:17 +0000 UTC" firstStartedPulling="2025-09-06 00:13:20.201224552 +0000 UTC m=+4.128278499" lastFinishedPulling="2025-09-06 00:13:24.647272189 +0000 UTC m=+8.574326096" observedRunningTime="2025-09-06 00:13:26.166056647 +0000 UTC m=+10.093110594" watchObservedRunningTime="2025-09-06 00:13:28.519330316 +0000 UTC m=+12.446384263" Sep 6 00:13:28.526261 systemd[1]: Created slice kubepods-besteffort-pod760be54d_db06_4add_8881_21c82c81ed7e.slice - libcontainer container kubepods-besteffort-pod760be54d_db06_4add_8881_21c82c81ed7e.slice. Sep 6 00:13:28.574716 kubelet[1695]: I0906 00:13:28.574660 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gbxjc\" (UniqueName: \"kubernetes.io/projected/760be54d-db06-4add-8881-21c82c81ed7e-kube-api-access-gbxjc\") pod \"nginx-deployment-7fcdb87857-fjbpk\" (UID: \"760be54d-db06-4add-8881-21c82c81ed7e\") " pod="default/nginx-deployment-7fcdb87857-fjbpk" Sep 6 00:13:28.830181 containerd[1435]: time="2025-09-06T00:13:28.830069305Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-fjbpk,Uid:760be54d-db06-4add-8881-21c82c81ed7e,Namespace:default,Attempt:0,}" Sep 6 00:13:28.850856 systemd-networkd[1379]: cni0: Link UP Sep 6 00:13:28.850861 systemd-networkd[1379]: cni0: Gained carrier Sep 6 00:13:28.851627 systemd-networkd[1379]: cni0: Lost carrier Sep 6 00:13:28.859751 systemd-networkd[1379]: veth8131f34b: Link UP Sep 6 00:13:28.860137 kernel: cni0: port 1(veth8131f34b) entered blocking state Sep 6 00:13:28.860218 kernel: cni0: port 1(veth8131f34b) entered disabled state Sep 6 00:13:28.860239 kernel: veth8131f34b: entered allmulticast mode Sep 6 00:13:28.861039 kernel: veth8131f34b: entered promiscuous mode Sep 6 00:13:28.861069 kernel: cni0: port 1(veth8131f34b) entered blocking state Sep 6 00:13:28.862047 kernel: cni0: port 1(veth8131f34b) entered forwarding state Sep 6 00:13:28.863030 kernel: cni0: port 1(veth8131f34b) entered disabled state Sep 6 00:13:28.869402 kernel: cni0: port 1(veth8131f34b) entered blocking state Sep 6 00:13:28.869469 kernel: cni0: port 1(veth8131f34b) entered forwarding state Sep 6 00:13:28.869606 systemd-networkd[1379]: veth8131f34b: Gained carrier Sep 6 00:13:28.870288 systemd-networkd[1379]: cni0: Gained carrier Sep 6 00:13:28.871971 containerd[1435]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Sep 6 00:13:28.871971 containerd[1435]: delegateAdd: netconf sent to delegate plugin: Sep 6 00:13:28.892332 containerd[1435]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-06T00:13:28.892069079Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 6 00:13:28.892332 containerd[1435]: time="2025-09-06T00:13:28.892127133Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 6 00:13:28.892332 containerd[1435]: time="2025-09-06T00:13:28.892137670Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:28.892332 containerd[1435]: time="2025-09-06T00:13:28.892216117Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:28.913201 systemd[1]: Started cri-containerd-21e0cd221d7b5d89d4b1186099e47d920dd29f871b99060ae50a2851e6dc0cc1.scope - libcontainer container 21e0cd221d7b5d89d4b1186099e47d920dd29f871b99060ae50a2851e6dc0cc1. Sep 6 00:13:28.922165 systemd-resolved[1308]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 6 00:13:28.936991 containerd[1435]: time="2025-09-06T00:13:28.936937999Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-fjbpk,Uid:760be54d-db06-4add-8881-21c82c81ed7e,Namespace:default,Attempt:0,} returns sandbox id \"21e0cd221d7b5d89d4b1186099e47d920dd29f871b99060ae50a2851e6dc0cc1\"" Sep 6 00:13:28.938147 containerd[1435]: time="2025-09-06T00:13:28.938123118Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 6 00:13:28.976856 kubelet[1695]: E0906 00:13:28.976797 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:29.977543 kubelet[1695]: E0906 00:13:29.977396 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:30.302501 systemd-networkd[1379]: veth8131f34b: Gained IPv6LL Sep 6 00:13:30.494143 systemd-networkd[1379]: cni0: Gained IPv6LL Sep 6 00:13:30.591621 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount560032548.mount: Deactivated successfully. Sep 6 00:13:30.978517 kubelet[1695]: E0906 00:13:30.978417 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:31.296688 containerd[1435]: time="2025-09-06T00:13:31.296576196Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:31.298066 containerd[1435]: time="2025-09-06T00:13:31.298005067Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69986522" Sep 6 00:13:31.300302 containerd[1435]: time="2025-09-06T00:13:31.298956139Z" level=info msg="ImageCreate event name:\"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:31.301652 containerd[1435]: time="2025-09-06T00:13:31.301624274Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:31.302715 containerd[1435]: time="2025-09-06T00:13:31.302679459Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 2.364411231s" Sep 6 00:13:31.302777 containerd[1435]: time="2025-09-06T00:13:31.302716779Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 6 00:13:31.309704 containerd[1435]: time="2025-09-06T00:13:31.309668843Z" level=info msg="CreateContainer within sandbox \"21e0cd221d7b5d89d4b1186099e47d920dd29f871b99060ae50a2851e6dc0cc1\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 6 00:13:31.321074 containerd[1435]: time="2025-09-06T00:13:31.320586411Z" level=info msg="CreateContainer within sandbox \"21e0cd221d7b5d89d4b1186099e47d920dd29f871b99060ae50a2851e6dc0cc1\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"305e70f03985f6c823f24ebdf8ec9f72529ec70369f0987c6d5255035e7a6f17\"" Sep 6 00:13:31.321297 containerd[1435]: time="2025-09-06T00:13:31.321267950Z" level=info msg="StartContainer for \"305e70f03985f6c823f24ebdf8ec9f72529ec70369f0987c6d5255035e7a6f17\"" Sep 6 00:13:31.344154 systemd[1]: Started cri-containerd-305e70f03985f6c823f24ebdf8ec9f72529ec70369f0987c6d5255035e7a6f17.scope - libcontainer container 305e70f03985f6c823f24ebdf8ec9f72529ec70369f0987c6d5255035e7a6f17. Sep 6 00:13:31.363755 containerd[1435]: time="2025-09-06T00:13:31.363713570Z" level=info msg="StartContainer for \"305e70f03985f6c823f24ebdf8ec9f72529ec70369f0987c6d5255035e7a6f17\" returns successfully" Sep 6 00:13:31.978897 kubelet[1695]: E0906 00:13:31.978844 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:32.979574 kubelet[1695]: E0906 00:13:32.979532 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:33.980051 kubelet[1695]: E0906 00:13:33.979972 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:34.841628 kubelet[1695]: I0906 00:13:34.841540 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-fjbpk" podStartSLOduration=4.473794604 podStartE2EDuration="6.841522827s" podCreationTimestamp="2025-09-06 00:13:28 +0000 UTC" firstStartedPulling="2025-09-06 00:13:28.93774747 +0000 UTC m=+12.864801417" lastFinishedPulling="2025-09-06 00:13:31.305475693 +0000 UTC m=+15.232529640" observedRunningTime="2025-09-06 00:13:32.17644509 +0000 UTC m=+16.103499037" watchObservedRunningTime="2025-09-06 00:13:34.841522827 +0000 UTC m=+18.768576774" Sep 6 00:13:34.849314 systemd[1]: Created slice kubepods-besteffort-pod1f2f3b1d_cdc3_4806_8c8d_61a17258bebf.slice - libcontainer container kubepods-besteffort-pod1f2f3b1d_cdc3_4806_8c8d_61a17258bebf.slice. Sep 6 00:13:34.908455 kubelet[1695]: I0906 00:13:34.908399 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/1f2f3b1d-cdc3-4806-8c8d-61a17258bebf-data\") pod \"nfs-server-provisioner-0\" (UID: \"1f2f3b1d-cdc3-4806-8c8d-61a17258bebf\") " pod="default/nfs-server-provisioner-0" Sep 6 00:13:34.908455 kubelet[1695]: I0906 00:13:34.908448 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-b27h6\" (UniqueName: \"kubernetes.io/projected/1f2f3b1d-cdc3-4806-8c8d-61a17258bebf-kube-api-access-b27h6\") pod \"nfs-server-provisioner-0\" (UID: \"1f2f3b1d-cdc3-4806-8c8d-61a17258bebf\") " pod="default/nfs-server-provisioner-0" Sep 6 00:13:34.981153 kubelet[1695]: E0906 00:13:34.981108 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:35.151566 containerd[1435]: time="2025-09-06T00:13:35.151460382Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1f2f3b1d-cdc3-4806-8c8d-61a17258bebf,Namespace:default,Attempt:0,}" Sep 6 00:13:35.171377 systemd-networkd[1379]: veth14777417: Link UP Sep 6 00:13:35.172031 kernel: cni0: port 2(veth14777417) entered blocking state Sep 6 00:13:35.172080 kernel: cni0: port 2(veth14777417) entered disabled state Sep 6 00:13:35.175092 kernel: veth14777417: entered allmulticast mode Sep 6 00:13:35.175176 kernel: veth14777417: entered promiscuous mode Sep 6 00:13:35.184437 kernel: cni0: port 2(veth14777417) entered blocking state Sep 6 00:13:35.184498 kernel: cni0: port 2(veth14777417) entered forwarding state Sep 6 00:13:35.184522 systemd-networkd[1379]: veth14777417: Gained carrier Sep 6 00:13:35.186337 containerd[1435]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400009a950), "name":"cbr0", "type":"bridge"} Sep 6 00:13:35.186337 containerd[1435]: delegateAdd: netconf sent to delegate plugin: Sep 6 00:13:35.203184 containerd[1435]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-06T00:13:35.203098044Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 6 00:13:35.203766 containerd[1435]: time="2025-09-06T00:13:35.203166207Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 6 00:13:35.203766 containerd[1435]: time="2025-09-06T00:13:35.203692142Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:35.203949 containerd[1435]: time="2025-09-06T00:13:35.203915684Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:35.221176 systemd[1]: Started cri-containerd-1ee19eac489717aa089d2f4bf1fb66cb0ccc7b10ffc9589d387596af080108d7.scope - libcontainer container 1ee19eac489717aa089d2f4bf1fb66cb0ccc7b10ffc9589d387596af080108d7. Sep 6 00:13:35.231005 systemd-resolved[1308]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 6 00:13:35.250791 containerd[1435]: time="2025-09-06T00:13:35.250738241Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:1f2f3b1d-cdc3-4806-8c8d-61a17258bebf,Namespace:default,Attempt:0,} returns sandbox id \"1ee19eac489717aa089d2f4bf1fb66cb0ccc7b10ffc9589d387596af080108d7\"" Sep 6 00:13:35.252336 containerd[1435]: time="2025-09-06T00:13:35.252298874Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 6 00:13:35.981972 kubelet[1695]: E0906 00:13:35.981934 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:36.382157 systemd-networkd[1379]: veth14777417: Gained IPv6LL Sep 6 00:13:36.853885 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3637400389.mount: Deactivated successfully. Sep 6 00:13:36.966487 kubelet[1695]: E0906 00:13:36.966425 1695 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:36.982881 kubelet[1695]: E0906 00:13:36.982827 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:37.983340 kubelet[1695]: E0906 00:13:37.983297 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:38.282688 containerd[1435]: time="2025-09-06T00:13:38.282550695Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:38.283416 containerd[1435]: time="2025-09-06T00:13:38.283383010Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Sep 6 00:13:38.284501 containerd[1435]: time="2025-09-06T00:13:38.284472994Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:38.288189 containerd[1435]: time="2025-09-06T00:13:38.288156205Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:38.289401 containerd[1435]: time="2025-09-06T00:13:38.289358998Z" 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.03700197s" Sep 6 00:13:38.289462 containerd[1435]: time="2025-09-06T00:13:38.289402576Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 6 00:13:38.293106 containerd[1435]: time="2025-09-06T00:13:38.293065979Z" level=info msg="CreateContainer within sandbox \"1ee19eac489717aa089d2f4bf1fb66cb0ccc7b10ffc9589d387596af080108d7\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 6 00:13:38.304713 containerd[1435]: time="2025-09-06T00:13:38.304578648Z" level=info msg="CreateContainer within sandbox \"1ee19eac489717aa089d2f4bf1fb66cb0ccc7b10ffc9589d387596af080108d7\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"ad9fd6ba0bb3ffc4eb133767ec091a94ef418f2a78a4b1a23b08ae3d749c8583\"" Sep 6 00:13:38.305238 containerd[1435]: time="2025-09-06T00:13:38.305210237Z" level=info msg="StartContainer for \"ad9fd6ba0bb3ffc4eb133767ec091a94ef418f2a78a4b1a23b08ae3d749c8583\"" Sep 6 00:13:38.334197 systemd[1]: Started cri-containerd-ad9fd6ba0bb3ffc4eb133767ec091a94ef418f2a78a4b1a23b08ae3d749c8583.scope - libcontainer container ad9fd6ba0bb3ffc4eb133767ec091a94ef418f2a78a4b1a23b08ae3d749c8583. Sep 6 00:13:38.354546 containerd[1435]: time="2025-09-06T00:13:38.354504497Z" level=info msg="StartContainer for \"ad9fd6ba0bb3ffc4eb133767ec091a94ef418f2a78a4b1a23b08ae3d749c8583\" returns successfully" Sep 6 00:13:38.983800 kubelet[1695]: E0906 00:13:38.983752 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:39.194356 kubelet[1695]: I0906 00:13:39.194095 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.155850402 podStartE2EDuration="5.194078217s" podCreationTimestamp="2025-09-06 00:13:34 +0000 UTC" firstStartedPulling="2025-09-06 00:13:35.251950172 +0000 UTC m=+19.179004079" lastFinishedPulling="2025-09-06 00:13:38.290177947 +0000 UTC m=+22.217231894" observedRunningTime="2025-09-06 00:13:39.193957852 +0000 UTC m=+23.121011799" watchObservedRunningTime="2025-09-06 00:13:39.194078217 +0000 UTC m=+23.121132164" Sep 6 00:13:39.984705 kubelet[1695]: E0906 00:13:39.984656 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:40.985412 kubelet[1695]: E0906 00:13:40.985360 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:41.986348 kubelet[1695]: E0906 00:13:41.986259 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:42.990303 kubelet[1695]: E0906 00:13:42.987165 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:43.619231 systemd[1]: Created slice kubepods-besteffort-pod045580c5_18c6_4b86_b842_9936e23964d9.slice - libcontainer container kubepods-besteffort-pod045580c5_18c6_4b86_b842_9936e23964d9.slice. Sep 6 00:13:43.657314 kubelet[1695]: I0906 00:13:43.657273 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-968b66bb-f3d4-40f0-a8f7-dc3383ea25c8\" (UniqueName: \"kubernetes.io/nfs/045580c5-18c6-4b86-b842-9936e23964d9-pvc-968b66bb-f3d4-40f0-a8f7-dc3383ea25c8\") pod \"test-pod-1\" (UID: \"045580c5-18c6-4b86-b842-9936e23964d9\") " pod="default/test-pod-1" Sep 6 00:13:43.657552 kubelet[1695]: I0906 00:13:43.657505 1695 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-hn48h\" (UniqueName: \"kubernetes.io/projected/045580c5-18c6-4b86-b842-9936e23964d9-kube-api-access-hn48h\") pod \"test-pod-1\" (UID: \"045580c5-18c6-4b86-b842-9936e23964d9\") " pod="default/test-pod-1" Sep 6 00:13:43.783349 kernel: FS-Cache: Loaded Sep 6 00:13:43.810782 kernel: RPC: Registered named UNIX socket transport module. Sep 6 00:13:43.810876 kernel: RPC: Registered udp transport module. Sep 6 00:13:43.810894 kernel: RPC: Registered tcp transport module. Sep 6 00:13:43.810910 kernel: RPC: Registered tcp-with-tls transport module. Sep 6 00:13:43.811253 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 6 00:13:43.987840 kubelet[1695]: E0906 00:13:43.987723 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:43.991105 kernel: NFS: Registering the id_resolver key type Sep 6 00:13:43.991174 kernel: Key type id_resolver registered Sep 6 00:13:43.991197 kernel: Key type id_legacy registered Sep 6 00:13:44.017134 nfsidmap[2696]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 6 00:13:44.022265 nfsidmap[2699]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 6 00:13:44.223304 containerd[1435]: time="2025-09-06T00:13:44.223259603Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:045580c5-18c6-4b86-b842-9936e23964d9,Namespace:default,Attempt:0,}" Sep 6 00:13:44.246862 systemd-networkd[1379]: vethec1a630a: Link UP Sep 6 00:13:44.254102 kernel: cni0: port 3(vethec1a630a) entered blocking state Sep 6 00:13:44.254192 kernel: cni0: port 3(vethec1a630a) entered disabled state Sep 6 00:13:44.254248 kernel: vethec1a630a: entered allmulticast mode Sep 6 00:13:44.254266 kernel: vethec1a630a: entered promiscuous mode Sep 6 00:13:44.270834 kernel: cni0: port 3(vethec1a630a) entered blocking state Sep 6 00:13:44.270920 kernel: cni0: port 3(vethec1a630a) entered forwarding state Sep 6 00:13:44.270921 systemd-networkd[1379]: vethec1a630a: Gained carrier Sep 6 00:13:44.273103 containerd[1435]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40001067f0), "name":"cbr0", "type":"bridge"} Sep 6 00:13:44.273103 containerd[1435]: delegateAdd: netconf sent to delegate plugin: Sep 6 00:13:44.295827 containerd[1435]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-06T00:13:44.295704364Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 6 00:13:44.295827 containerd[1435]: time="2025-09-06T00:13:44.295750734Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 6 00:13:44.295827 containerd[1435]: time="2025-09-06T00:13:44.295805267Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:44.296076 containerd[1435]: time="2025-09-06T00:13:44.295920373Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 6 00:13:44.315180 systemd[1]: Started cri-containerd-c94a6b0c242d62335a5ea0cd937d99111297378e4f7b1d6c5ab33e9e19a12f2c.scope - libcontainer container c94a6b0c242d62335a5ea0cd937d99111297378e4f7b1d6c5ab33e9e19a12f2c. Sep 6 00:13:44.324977 systemd-resolved[1308]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 6 00:13:44.341385 containerd[1435]: time="2025-09-06T00:13:44.341344491Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:045580c5-18c6-4b86-b842-9936e23964d9,Namespace:default,Attempt:0,} returns sandbox id \"c94a6b0c242d62335a5ea0cd937d99111297378e4f7b1d6c5ab33e9e19a12f2c\"" Sep 6 00:13:44.343578 containerd[1435]: time="2025-09-06T00:13:44.342916930Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 6 00:13:44.608111 containerd[1435]: time="2025-09-06T00:13:44.607937485Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 6 00:13:44.612470 containerd[1435]: time="2025-09-06T00:13:44.612434431Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 6 00:13:44.615742 containerd[1435]: time="2025-09-06T00:13:44.615654005Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"69986400\" in 272.705467ms" Sep 6 00:13:44.615742 containerd[1435]: time="2025-09-06T00:13:44.615689013Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 6 00:13:44.620414 containerd[1435]: time="2025-09-06T00:13:44.620289302Z" level=info msg="CreateContainer within sandbox \"c94a6b0c242d62335a5ea0cd937d99111297378e4f7b1d6c5ab33e9e19a12f2c\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 6 00:13:44.630803 containerd[1435]: time="2025-09-06T00:13:44.630767931Z" level=info msg="CreateContainer within sandbox \"c94a6b0c242d62335a5ea0cd937d99111297378e4f7b1d6c5ab33e9e19a12f2c\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"91b869f099231c5b17d07fd68cec614ab978e36a575b8fac29057dbce50238e2\"" Sep 6 00:13:44.631200 containerd[1435]: time="2025-09-06T00:13:44.631178105Z" level=info msg="StartContainer for \"91b869f099231c5b17d07fd68cec614ab978e36a575b8fac29057dbce50238e2\"" Sep 6 00:13:44.656175 systemd[1]: Started cri-containerd-91b869f099231c5b17d07fd68cec614ab978e36a575b8fac29057dbce50238e2.scope - libcontainer container 91b869f099231c5b17d07fd68cec614ab978e36a575b8fac29057dbce50238e2. Sep 6 00:13:44.680860 containerd[1435]: time="2025-09-06T00:13:44.680753410Z" level=info msg="StartContainer for \"91b869f099231c5b17d07fd68cec614ab978e36a575b8fac29057dbce50238e2\" returns successfully" Sep 6 00:13:44.988862 kubelet[1695]: E0906 00:13:44.988725 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:45.221391 kubelet[1695]: I0906 00:13:45.221328 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=9.947192823 podStartE2EDuration="10.221310613s" podCreationTimestamp="2025-09-06 00:13:35 +0000 UTC" firstStartedPulling="2025-09-06 00:13:44.342250578 +0000 UTC m=+28.269304525" lastFinishedPulling="2025-09-06 00:13:44.616368368 +0000 UTC m=+28.543422315" observedRunningTime="2025-09-06 00:13:45.220605461 +0000 UTC m=+29.147659408" watchObservedRunningTime="2025-09-06 00:13:45.221310613 +0000 UTC m=+29.148364560" Sep 6 00:13:45.534252 systemd-networkd[1379]: vethec1a630a: Gained IPv6LL Sep 6 00:13:45.989972 kubelet[1695]: E0906 00:13:45.989858 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 6 00:13:46.990196 kubelet[1695]: E0906 00:13:46.990149 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"