Sep 9 00:30:48.854365 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 9 00:30:48.854392 kernel: Linux version 6.6.104-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 Mon Sep 8 22:48:00 -00 2025 Sep 9 00:30:48.854401 kernel: KASLR enabled Sep 9 00:30:48.854407 kernel: efi: EFI v2.7 by EDK II Sep 9 00:30:48.854413 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdba86018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Sep 9 00:30:48.854418 kernel: random: crng init done Sep 9 00:30:48.854425 kernel: ACPI: Early table checksum verification disabled Sep 9 00:30:48.854431 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Sep 9 00:30:48.854437 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 9 00:30:48.854445 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:30:48.854451 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:30:48.854457 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:30:48.854463 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:30:48.854469 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:30:48.854486 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:30:48.854494 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:30:48.854501 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:30:48.854507 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 9 00:30:48.854513 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 9 00:30:48.854520 kernel: NUMA: Failed to initialise from firmware Sep 9 00:30:48.854526 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 9 00:30:48.854532 kernel: NUMA: NODE_DATA [mem 0xdc957800-0xdc95cfff] Sep 9 00:30:48.854539 kernel: Zone ranges: Sep 9 00:30:48.854545 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 9 00:30:48.854551 kernel: DMA32 empty Sep 9 00:30:48.854558 kernel: Normal empty Sep 9 00:30:48.854564 kernel: Movable zone start for each node Sep 9 00:30:48.854570 kernel: Early memory node ranges Sep 9 00:30:48.854577 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Sep 9 00:30:48.854583 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Sep 9 00:30:48.854590 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Sep 9 00:30:48.854596 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 9 00:30:48.854602 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 9 00:30:48.854608 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 9 00:30:48.854615 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 9 00:30:48.854621 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 9 00:30:48.854627 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 9 00:30:48.854635 kernel: psci: probing for conduit method from ACPI. Sep 9 00:30:48.854641 kernel: psci: PSCIv1.1 detected in firmware. Sep 9 00:30:48.854647 kernel: psci: Using standard PSCI v0.2 function IDs Sep 9 00:30:48.854656 kernel: psci: Trusted OS migration not required Sep 9 00:30:48.854663 kernel: psci: SMC Calling Convention v1.1 Sep 9 00:30:48.854669 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 9 00:30:48.854677 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Sep 9 00:30:48.854684 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Sep 9 00:30:48.854691 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 9 00:30:48.854697 kernel: Detected PIPT I-cache on CPU0 Sep 9 00:30:48.854704 kernel: CPU features: detected: GIC system register CPU interface Sep 9 00:30:48.854711 kernel: CPU features: detected: Hardware dirty bit management Sep 9 00:30:48.854717 kernel: CPU features: detected: Spectre-v4 Sep 9 00:30:48.854724 kernel: CPU features: detected: Spectre-BHB Sep 9 00:30:48.854731 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 9 00:30:48.854738 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 9 00:30:48.854751 kernel: CPU features: detected: ARM erratum 1418040 Sep 9 00:30:48.854758 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 9 00:30:48.854765 kernel: alternatives: applying boot alternatives Sep 9 00:30:48.854773 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=7395fe4f9fb368b2829f9349e2a89e9a9e96b552675d3b261a5a30cf3c6cb15c Sep 9 00:30:48.854780 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 9 00:30:48.854787 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 9 00:30:48.854793 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 9 00:30:48.854800 kernel: Fallback order for Node 0: 0 Sep 9 00:30:48.854807 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Sep 9 00:30:48.854813 kernel: Policy zone: DMA Sep 9 00:30:48.854820 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 9 00:30:48.854828 kernel: software IO TLB: area num 4. Sep 9 00:30:48.854835 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Sep 9 00:30:48.854842 kernel: Memory: 2386400K/2572288K available (10304K kernel code, 2186K rwdata, 8108K rodata, 39424K init, 897K bss, 185888K reserved, 0K cma-reserved) Sep 9 00:30:48.854849 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 9 00:30:48.854856 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 9 00:30:48.854863 kernel: rcu: RCU event tracing is enabled. Sep 9 00:30:48.854870 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 9 00:30:48.854877 kernel: Trampoline variant of Tasks RCU enabled. Sep 9 00:30:48.854884 kernel: Tracing variant of Tasks RCU enabled. Sep 9 00:30:48.854890 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 9 00:30:48.854897 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 9 00:30:48.854905 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 9 00:30:48.854912 kernel: GICv3: 256 SPIs implemented Sep 9 00:30:48.854918 kernel: GICv3: 0 Extended SPIs implemented Sep 9 00:30:48.854925 kernel: Root IRQ handler: gic_handle_irq Sep 9 00:30:48.854931 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 9 00:30:48.854938 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 9 00:30:48.854945 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 9 00:30:48.854952 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Sep 9 00:30:48.854959 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Sep 9 00:30:48.854965 kernel: GICv3: using LPI property table @0x00000000400f0000 Sep 9 00:30:48.854972 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Sep 9 00:30:48.854979 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 9 00:30:48.854987 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 00:30:48.854994 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 9 00:30:48.855001 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 9 00:30:48.855008 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 9 00:30:48.855015 kernel: arm-pv: using stolen time PV Sep 9 00:30:48.855022 kernel: Console: colour dummy device 80x25 Sep 9 00:30:48.855029 kernel: ACPI: Core revision 20230628 Sep 9 00:30:48.855036 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 9 00:30:48.855043 kernel: pid_max: default: 32768 minimum: 301 Sep 9 00:30:48.855050 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 9 00:30:48.855058 kernel: landlock: Up and running. Sep 9 00:30:48.855065 kernel: SELinux: Initializing. Sep 9 00:30:48.855072 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 00:30:48.855079 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 9 00:30:48.855086 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 9 00:30:48.855093 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 9 00:30:48.855100 kernel: rcu: Hierarchical SRCU implementation. Sep 9 00:30:48.855107 kernel: rcu: Max phase no-delay instances is 400. Sep 9 00:30:48.855114 kernel: Platform MSI: ITS@0x8080000 domain created Sep 9 00:30:48.855122 kernel: PCI/MSI: ITS@0x8080000 domain created Sep 9 00:30:48.855129 kernel: Remapping and enabling EFI services. Sep 9 00:30:48.855136 kernel: smp: Bringing up secondary CPUs ... Sep 9 00:30:48.855143 kernel: Detected PIPT I-cache on CPU1 Sep 9 00:30:48.855150 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 9 00:30:48.855157 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Sep 9 00:30:48.855164 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 00:30:48.855170 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 9 00:30:48.855177 kernel: Detected PIPT I-cache on CPU2 Sep 9 00:30:48.855184 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 9 00:30:48.855192 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Sep 9 00:30:48.855199 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 00:30:48.855210 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 9 00:30:48.855218 kernel: Detected PIPT I-cache on CPU3 Sep 9 00:30:48.855226 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 9 00:30:48.855233 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Sep 9 00:30:48.855240 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 9 00:30:48.855247 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 9 00:30:48.855255 kernel: smp: Brought up 1 node, 4 CPUs Sep 9 00:30:48.855263 kernel: SMP: Total of 4 processors activated. Sep 9 00:30:48.855270 kernel: CPU features: detected: 32-bit EL0 Support Sep 9 00:30:48.855278 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 9 00:30:48.855285 kernel: CPU features: detected: Common not Private translations Sep 9 00:30:48.855292 kernel: CPU features: detected: CRC32 instructions Sep 9 00:30:48.855300 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 9 00:30:48.855307 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 9 00:30:48.855314 kernel: CPU features: detected: LSE atomic instructions Sep 9 00:30:48.855322 kernel: CPU features: detected: Privileged Access Never Sep 9 00:30:48.855330 kernel: CPU features: detected: RAS Extension Support Sep 9 00:30:48.855337 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 9 00:30:48.855344 kernel: CPU: All CPU(s) started at EL1 Sep 9 00:30:48.855352 kernel: alternatives: applying system-wide alternatives Sep 9 00:30:48.855359 kernel: devtmpfs: initialized Sep 9 00:30:48.855366 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 9 00:30:48.855374 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 9 00:30:48.855381 kernel: pinctrl core: initialized pinctrl subsystem Sep 9 00:30:48.855389 kernel: SMBIOS 3.0.0 present. Sep 9 00:30:48.855397 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Sep 9 00:30:48.855404 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 9 00:30:48.855411 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 9 00:30:48.855419 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 9 00:30:48.855427 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 9 00:30:48.855434 kernel: audit: initializing netlink subsys (disabled) Sep 9 00:30:48.855441 kernel: audit: type=2000 audit(0.023:1): state=initialized audit_enabled=0 res=1 Sep 9 00:30:48.855449 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 9 00:30:48.855457 kernel: cpuidle: using governor menu Sep 9 00:30:48.855464 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 9 00:30:48.855477 kernel: ASID allocator initialised with 32768 entries Sep 9 00:30:48.855485 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 9 00:30:48.855492 kernel: Serial: AMBA PL011 UART driver Sep 9 00:30:48.855499 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 9 00:30:48.855506 kernel: Modules: 0 pages in range for non-PLT usage Sep 9 00:30:48.855514 kernel: Modules: 509008 pages in range for PLT usage Sep 9 00:30:48.855521 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 9 00:30:48.855530 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 9 00:30:48.855537 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 9 00:30:48.855544 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 9 00:30:48.855551 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 9 00:30:48.855558 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 9 00:30:48.855566 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 9 00:30:48.855573 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 9 00:30:48.855580 kernel: ACPI: Added _OSI(Module Device) Sep 9 00:30:48.855587 kernel: ACPI: Added _OSI(Processor Device) Sep 9 00:30:48.855596 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 9 00:30:48.855603 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 9 00:30:48.855610 kernel: ACPI: Interpreter enabled Sep 9 00:30:48.855618 kernel: ACPI: Using GIC for interrupt routing Sep 9 00:30:48.855625 kernel: ACPI: MCFG table detected, 1 entries Sep 9 00:30:48.855632 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 9 00:30:48.855640 kernel: printk: console [ttyAMA0] enabled Sep 9 00:30:48.855647 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 9 00:30:48.855781 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 9 00:30:48.855858 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 9 00:30:48.855923 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 9 00:30:48.855986 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 9 00:30:48.856049 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 9 00:30:48.856059 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 9 00:30:48.856067 kernel: PCI host bridge to bus 0000:00 Sep 9 00:30:48.856137 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 9 00:30:48.856197 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 9 00:30:48.856256 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 9 00:30:48.856312 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 9 00:30:48.856390 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Sep 9 00:30:48.856483 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Sep 9 00:30:48.856555 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Sep 9 00:30:48.856623 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Sep 9 00:30:48.856690 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Sep 9 00:30:48.856765 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Sep 9 00:30:48.856833 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Sep 9 00:30:48.856904 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Sep 9 00:30:48.856975 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 9 00:30:48.857048 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 9 00:30:48.857114 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 9 00:30:48.857123 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 9 00:30:48.857131 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 9 00:30:48.857138 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 9 00:30:48.857146 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 9 00:30:48.857153 kernel: iommu: Default domain type: Translated Sep 9 00:30:48.857160 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 9 00:30:48.857168 kernel: efivars: Registered efivars operations Sep 9 00:30:48.857175 kernel: vgaarb: loaded Sep 9 00:30:48.857184 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 9 00:30:48.857191 kernel: VFS: Disk quotas dquot_6.6.0 Sep 9 00:30:48.857199 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 9 00:30:48.857206 kernel: pnp: PnP ACPI init Sep 9 00:30:48.857300 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 9 00:30:48.857312 kernel: pnp: PnP ACPI: found 1 devices Sep 9 00:30:48.857319 kernel: NET: Registered PF_INET protocol family Sep 9 00:30:48.857327 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 9 00:30:48.857336 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 9 00:30:48.857344 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 9 00:30:48.857351 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 9 00:30:48.857358 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 9 00:30:48.857365 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 9 00:30:48.857373 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 00:30:48.857380 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 9 00:30:48.857388 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 9 00:30:48.857395 kernel: PCI: CLS 0 bytes, default 64 Sep 9 00:30:48.857403 kernel: kvm [1]: HYP mode not available Sep 9 00:30:48.857410 kernel: Initialise system trusted keyrings Sep 9 00:30:48.857418 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 9 00:30:48.857425 kernel: Key type asymmetric registered Sep 9 00:30:48.857432 kernel: Asymmetric key parser 'x509' registered Sep 9 00:30:48.857439 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 9 00:30:48.857447 kernel: io scheduler mq-deadline registered Sep 9 00:30:48.857454 kernel: io scheduler kyber registered Sep 9 00:30:48.857461 kernel: io scheduler bfq registered Sep 9 00:30:48.857531 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 9 00:30:48.857540 kernel: ACPI: button: Power Button [PWRB] Sep 9 00:30:48.857548 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 9 00:30:48.857624 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 9 00:30:48.857634 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 9 00:30:48.857642 kernel: thunder_xcv, ver 1.0 Sep 9 00:30:48.857649 kernel: thunder_bgx, ver 1.0 Sep 9 00:30:48.857656 kernel: nicpf, ver 1.0 Sep 9 00:30:48.857663 kernel: nicvf, ver 1.0 Sep 9 00:30:48.857739 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 9 00:30:48.857817 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-09T00:30:48 UTC (1757377848) Sep 9 00:30:48.857828 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 9 00:30:48.857835 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Sep 9 00:30:48.857843 kernel: watchdog: Delayed init of the lockup detector failed: -19 Sep 9 00:30:48.857850 kernel: watchdog: Hard watchdog permanently disabled Sep 9 00:30:48.857858 kernel: NET: Registered PF_INET6 protocol family Sep 9 00:30:48.857865 kernel: Segment Routing with IPv6 Sep 9 00:30:48.857875 kernel: In-situ OAM (IOAM) with IPv6 Sep 9 00:30:48.857882 kernel: NET: Registered PF_PACKET protocol family Sep 9 00:30:48.857890 kernel: Key type dns_resolver registered Sep 9 00:30:48.857897 kernel: registered taskstats version 1 Sep 9 00:30:48.857904 kernel: Loading compiled-in X.509 certificates Sep 9 00:30:48.857911 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.104-flatcar: f5b097e6797722e0cc665195a3c415b6be267631' Sep 9 00:30:48.857918 kernel: Key type .fscrypt registered Sep 9 00:30:48.857925 kernel: Key type fscrypt-provisioning registered Sep 9 00:30:48.857933 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 9 00:30:48.857941 kernel: ima: Allocated hash algorithm: sha1 Sep 9 00:30:48.857948 kernel: ima: No architecture policies found Sep 9 00:30:48.857956 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 9 00:30:48.857963 kernel: clk: Disabling unused clocks Sep 9 00:30:48.857970 kernel: Freeing unused kernel memory: 39424K Sep 9 00:30:48.857977 kernel: Run /init as init process Sep 9 00:30:48.857984 kernel: with arguments: Sep 9 00:30:48.857991 kernel: /init Sep 9 00:30:48.857998 kernel: with environment: Sep 9 00:30:48.858006 kernel: HOME=/ Sep 9 00:30:48.858014 kernel: TERM=linux Sep 9 00:30:48.858021 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 9 00:30:48.858030 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 9 00:30:48.858040 systemd[1]: Detected virtualization kvm. Sep 9 00:30:48.858048 systemd[1]: Detected architecture arm64. Sep 9 00:30:48.858056 systemd[1]: Running in initrd. Sep 9 00:30:48.858063 systemd[1]: No hostname configured, using default hostname. Sep 9 00:30:48.858072 systemd[1]: Hostname set to . Sep 9 00:30:48.858080 systemd[1]: Initializing machine ID from VM UUID. Sep 9 00:30:48.858088 systemd[1]: Queued start job for default target initrd.target. Sep 9 00:30:48.858095 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 00:30:48.858103 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 00:30:48.858112 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 9 00:30:48.858120 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 00:30:48.858129 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 9 00:30:48.858137 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 9 00:30:48.858147 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 9 00:30:48.858155 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 9 00:30:48.858162 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 00:30:48.858170 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 00:30:48.858178 systemd[1]: Reached target paths.target - Path Units. Sep 9 00:30:48.858187 systemd[1]: Reached target slices.target - Slice Units. Sep 9 00:30:48.858195 systemd[1]: Reached target swap.target - Swaps. Sep 9 00:30:48.858203 systemd[1]: Reached target timers.target - Timer Units. Sep 9 00:30:48.858211 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 00:30:48.858219 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 00:30:48.858226 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 9 00:30:48.858234 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 9 00:30:48.858242 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 00:30:48.858250 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 00:30:48.858259 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 00:30:48.858267 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 00:30:48.858275 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 9 00:30:48.858282 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 00:30:48.858290 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 9 00:30:48.858298 systemd[1]: Starting systemd-fsck-usr.service... Sep 9 00:30:48.858306 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 00:30:48.858313 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 00:30:48.858323 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:30:48.858331 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 9 00:30:48.858339 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 00:30:48.858347 systemd[1]: Finished systemd-fsck-usr.service. Sep 9 00:30:48.858355 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 00:30:48.858365 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:30:48.858388 systemd-journald[237]: Collecting audit messages is disabled. Sep 9 00:30:48.858407 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 00:30:48.858415 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 9 00:30:48.858425 systemd-journald[237]: Journal started Sep 9 00:30:48.858443 systemd-journald[237]: Runtime Journal (/run/log/journal/df5fe11b2ca34e34bda6c12823c24ea7) is 5.9M, max 47.3M, 41.4M free. Sep 9 00:30:48.838029 systemd-modules-load[238]: Inserted module 'overlay' Sep 9 00:30:48.859907 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 00:30:48.861061 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 00:30:48.863302 kernel: Bridge firewalling registered Sep 9 00:30:48.861067 systemd-modules-load[238]: Inserted module 'br_netfilter' Sep 9 00:30:48.862346 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 00:30:48.868655 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 00:30:48.870084 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 00:30:48.872978 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 00:30:48.878417 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 00:30:48.879761 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 00:30:48.882535 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 00:30:48.885353 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 9 00:30:48.886346 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 00:30:48.889278 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 00:30:48.901503 dracut-cmdline[273]: dracut-dracut-053 Sep 9 00:30:48.901503 dracut-cmdline[273]: 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=7395fe4f9fb368b2829f9349e2a89e9a9e96b552675d3b261a5a30cf3c6cb15c Sep 9 00:30:48.916190 systemd-resolved[275]: Positive Trust Anchors: Sep 9 00:30:48.916209 systemd-resolved[275]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 00:30:48.916241 systemd-resolved[275]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 9 00:30:48.921135 systemd-resolved[275]: Defaulting to hostname 'linux'. Sep 9 00:30:48.922184 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 00:30:48.924456 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 00:30:48.973502 kernel: SCSI subsystem initialized Sep 9 00:30:48.977484 kernel: Loading iSCSI transport class v2.0-870. Sep 9 00:30:48.985501 kernel: iscsi: registered transport (tcp) Sep 9 00:30:48.998651 kernel: iscsi: registered transport (qla4xxx) Sep 9 00:30:48.998670 kernel: QLogic iSCSI HBA Driver Sep 9 00:30:49.040497 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 9 00:30:49.049646 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 9 00:30:49.064640 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 9 00:30:49.064698 kernel: device-mapper: uevent: version 1.0.3 Sep 9 00:30:49.064708 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 9 00:30:49.110511 kernel: raid6: neonx8 gen() 15654 MB/s Sep 9 00:30:49.127499 kernel: raid6: neonx4 gen() 15612 MB/s Sep 9 00:30:49.144491 kernel: raid6: neonx2 gen() 13211 MB/s Sep 9 00:30:49.161491 kernel: raid6: neonx1 gen() 10513 MB/s Sep 9 00:30:49.178487 kernel: raid6: int64x8 gen() 6949 MB/s Sep 9 00:30:49.195490 kernel: raid6: int64x4 gen() 7340 MB/s Sep 9 00:30:49.212487 kernel: raid6: int64x2 gen() 6123 MB/s Sep 9 00:30:49.229488 kernel: raid6: int64x1 gen() 5055 MB/s Sep 9 00:30:49.229501 kernel: raid6: using algorithm neonx8 gen() 15654 MB/s Sep 9 00:30:49.246493 kernel: raid6: .... xor() 12049 MB/s, rmw enabled Sep 9 00:30:49.246506 kernel: raid6: using neon recovery algorithm Sep 9 00:30:49.251754 kernel: xor: measuring software checksum speed Sep 9 00:30:49.251771 kernel: 8regs : 19788 MB/sec Sep 9 00:30:49.252886 kernel: 32regs : 19655 MB/sec Sep 9 00:30:49.252900 kernel: arm64_neon : 27052 MB/sec Sep 9 00:30:49.252909 kernel: xor: using function: arm64_neon (27052 MB/sec) Sep 9 00:30:49.301498 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 9 00:30:49.312133 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 9 00:30:49.321668 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 00:30:49.332245 systemd-udevd[459]: Using default interface naming scheme 'v255'. Sep 9 00:30:49.335442 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 00:30:49.338601 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 9 00:30:49.352578 dracut-pre-trigger[467]: rd.md=0: removing MD RAID activation Sep 9 00:30:49.379496 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 00:30:49.392673 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 00:30:49.445520 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 00:30:49.455638 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 9 00:30:49.465984 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 9 00:30:49.468532 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 00:30:49.470856 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 00:30:49.471842 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 00:30:49.479660 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 9 00:30:49.490876 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 9 00:30:49.503384 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 00:30:49.511450 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 9 00:30:49.511631 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 9 00:30:49.503509 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 00:30:49.517300 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 9 00:30:49.517318 kernel: GPT:9289727 != 19775487 Sep 9 00:30:49.517327 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 9 00:30:49.517337 kernel: GPT:9289727 != 19775487 Sep 9 00:30:49.517346 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 9 00:30:49.517355 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 00:30:49.517331 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 00:30:49.518846 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 00:30:49.518976 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:30:49.521173 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:30:49.529530 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (505) Sep 9 00:30:49.530891 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:30:49.534261 kernel: BTRFS: device fsid 7c1eef97-905d-47ac-bb4a-010204f95541 devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (504) Sep 9 00:30:49.540961 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 9 00:30:49.545372 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:30:49.552920 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 9 00:30:49.559928 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 9 00:30:49.563595 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 9 00:30:49.564558 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 9 00:30:49.580639 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 9 00:30:49.582252 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 00:30:49.587630 disk-uuid[549]: Primary Header is updated. Sep 9 00:30:49.587630 disk-uuid[549]: Secondary Entries is updated. Sep 9 00:30:49.587630 disk-uuid[549]: Secondary Header is updated. Sep 9 00:30:49.590531 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 00:30:49.607042 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 00:30:50.600500 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 9 00:30:50.601089 disk-uuid[550]: The operation has completed successfully. Sep 9 00:30:50.625908 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 9 00:30:50.625999 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 9 00:30:50.650703 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 9 00:30:50.653708 sh[573]: Success Sep 9 00:30:50.666495 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Sep 9 00:30:50.704907 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 9 00:30:50.707720 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 9 00:30:50.709505 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 9 00:30:50.718517 kernel: BTRFS info (device dm-0): first mount of filesystem 7c1eef97-905d-47ac-bb4a-010204f95541 Sep 9 00:30:50.718568 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 9 00:30:50.718579 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 9 00:30:50.718589 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 9 00:30:50.719638 kernel: BTRFS info (device dm-0): using free space tree Sep 9 00:30:50.723143 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 9 00:30:50.724319 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 9 00:30:50.733598 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 9 00:30:50.734942 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 9 00:30:50.743560 kernel: BTRFS info (device vda6): first mount of filesystem 995cc93a-6fc6-4281-a722-821717f17817 Sep 9 00:30:50.743594 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 9 00:30:50.743605 kernel: BTRFS info (device vda6): using free space tree Sep 9 00:30:50.745947 kernel: BTRFS info (device vda6): auto enabling async discard Sep 9 00:30:50.752694 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 9 00:30:50.754253 kernel: BTRFS info (device vda6): last unmount of filesystem 995cc93a-6fc6-4281-a722-821717f17817 Sep 9 00:30:50.759579 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 9 00:30:50.766618 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 9 00:30:50.830352 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 00:30:50.830999 ignition[662]: Ignition 2.19.0 Sep 9 00:30:50.831005 ignition[662]: Stage: fetch-offline Sep 9 00:30:50.831036 ignition[662]: no configs at "/usr/lib/ignition/base.d" Sep 9 00:30:50.831044 ignition[662]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:30:50.831192 ignition[662]: parsed url from cmdline: "" Sep 9 00:30:50.831195 ignition[662]: no config URL provided Sep 9 00:30:50.831199 ignition[662]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 00:30:50.831206 ignition[662]: no config at "/usr/lib/ignition/user.ign" Sep 9 00:30:50.831228 ignition[662]: op(1): [started] loading QEMU firmware config module Sep 9 00:30:50.831232 ignition[662]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 9 00:30:50.839031 ignition[662]: op(1): [finished] loading QEMU firmware config module Sep 9 00:30:50.840618 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 00:30:50.846741 ignition[662]: parsing config with SHA512: 1074a476b527eddf2c96153b32b01c462662b80af1d43a552bf641765d40b9f91b2f7af667bf8ec0ebcdd2caeb8773b395ef9ed78c8fd372c31831106c01fd0a Sep 9 00:30:50.849723 unknown[662]: fetched base config from "system" Sep 9 00:30:50.849740 unknown[662]: fetched user config from "qemu" Sep 9 00:30:50.850013 ignition[662]: fetch-offline: fetch-offline passed Sep 9 00:30:50.850076 ignition[662]: Ignition finished successfully Sep 9 00:30:50.853501 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 00:30:50.859155 systemd-networkd[765]: lo: Link UP Sep 9 00:30:50.859167 systemd-networkd[765]: lo: Gained carrier Sep 9 00:30:50.859819 systemd-networkd[765]: Enumeration completed Sep 9 00:30:50.859898 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 00:30:50.860208 systemd-networkd[765]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 00:30:50.860211 systemd-networkd[765]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 00:30:50.861047 systemd-networkd[765]: eth0: Link UP Sep 9 00:30:50.861050 systemd-networkd[765]: eth0: Gained carrier Sep 9 00:30:50.861057 systemd-networkd[765]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 00:30:50.861697 systemd[1]: Reached target network.target - Network. Sep 9 00:30:50.862828 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 9 00:30:50.866606 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 9 00:30:50.878648 ignition[768]: Ignition 2.19.0 Sep 9 00:30:50.878659 ignition[768]: Stage: kargs Sep 9 00:30:50.878839 ignition[768]: no configs at "/usr/lib/ignition/base.d" Sep 9 00:30:50.879526 systemd-networkd[765]: eth0: DHCPv4 address 10.0.0.113/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 9 00:30:50.878849 ignition[768]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:30:50.879540 ignition[768]: kargs: kargs passed Sep 9 00:30:50.882298 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 9 00:30:50.879584 ignition[768]: Ignition finished successfully Sep 9 00:30:50.891609 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 9 00:30:50.901925 ignition[778]: Ignition 2.19.0 Sep 9 00:30:50.901936 ignition[778]: Stage: disks Sep 9 00:30:50.902140 ignition[778]: no configs at "/usr/lib/ignition/base.d" Sep 9 00:30:50.902149 ignition[778]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:30:50.903763 ignition[778]: disks: disks passed Sep 9 00:30:50.903811 ignition[778]: Ignition finished successfully Sep 9 00:30:50.905972 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 9 00:30:50.907314 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 9 00:30:50.908636 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 9 00:30:50.910217 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 00:30:50.911932 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 00:30:50.913341 systemd[1]: Reached target basic.target - Basic System. Sep 9 00:30:50.929641 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 9 00:30:50.939985 systemd-fsck[788]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 9 00:30:50.945396 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 9 00:30:50.958641 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 9 00:30:50.998309 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 9 00:30:50.999557 kernel: EXT4-fs (vda9): mounted filesystem d987a4c8-1278-4a59-9d40-0c91e08e9423 r/w with ordered data mode. Quota mode: none. Sep 9 00:30:50.999450 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 9 00:30:51.009619 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 00:30:51.011156 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 9 00:30:51.012208 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 9 00:30:51.012270 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 9 00:30:51.012348 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 00:30:51.018804 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (796) Sep 9 00:30:51.018306 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 9 00:30:51.020113 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 9 00:30:51.023503 kernel: BTRFS info (device vda6): first mount of filesystem 995cc93a-6fc6-4281-a722-821717f17817 Sep 9 00:30:51.023520 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 9 00:30:51.023530 kernel: BTRFS info (device vda6): using free space tree Sep 9 00:30:51.025500 kernel: BTRFS info (device vda6): auto enabling async discard Sep 9 00:30:51.027246 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 00:30:51.060725 initrd-setup-root[821]: cut: /sysroot/etc/passwd: No such file or directory Sep 9 00:30:51.064167 initrd-setup-root[828]: cut: /sysroot/etc/group: No such file or directory Sep 9 00:30:51.067823 initrd-setup-root[835]: cut: /sysroot/etc/shadow: No such file or directory Sep 9 00:30:51.071427 initrd-setup-root[842]: cut: /sysroot/etc/gshadow: No such file or directory Sep 9 00:30:51.137937 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 9 00:30:51.148562 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 9 00:30:51.149974 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 9 00:30:51.154540 kernel: BTRFS info (device vda6): last unmount of filesystem 995cc93a-6fc6-4281-a722-821717f17817 Sep 9 00:30:51.172266 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 9 00:30:51.174447 ignition[910]: INFO : Ignition 2.19.0 Sep 9 00:30:51.174447 ignition[910]: INFO : Stage: mount Sep 9 00:30:51.174447 ignition[910]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 00:30:51.174447 ignition[910]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:30:51.177315 ignition[910]: INFO : mount: mount passed Sep 9 00:30:51.177315 ignition[910]: INFO : Ignition finished successfully Sep 9 00:30:51.178529 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 9 00:30:51.183635 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 9 00:30:51.716870 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 9 00:30:51.725648 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 00:30:51.732079 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (926) Sep 9 00:30:51.732111 kernel: BTRFS info (device vda6): first mount of filesystem 995cc93a-6fc6-4281-a722-821717f17817 Sep 9 00:30:51.732123 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 9 00:30:51.732782 kernel: BTRFS info (device vda6): using free space tree Sep 9 00:30:51.735493 kernel: BTRFS info (device vda6): auto enabling async discard Sep 9 00:30:51.737876 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 00:30:51.754279 ignition[943]: INFO : Ignition 2.19.0 Sep 9 00:30:51.754279 ignition[943]: INFO : Stage: files Sep 9 00:30:51.755533 ignition[943]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 00:30:51.755533 ignition[943]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:30:51.755533 ignition[943]: DEBUG : files: compiled without relabeling support, skipping Sep 9 00:30:51.758505 ignition[943]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 9 00:30:51.758505 ignition[943]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 9 00:30:51.758505 ignition[943]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 9 00:30:51.758505 ignition[943]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 9 00:30:51.762867 ignition[943]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 9 00:30:51.762867 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 9 00:30:51.762867 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 9 00:30:51.762867 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 00:30:51.762867 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 00:30:51.762867 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Sep 9 00:30:51.762867 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Sep 9 00:30:51.762867 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Sep 9 00:30:51.762867 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-arm64.raw: attempt #1 Sep 9 00:30:51.758784 unknown[943]: wrote ssh authorized keys file for user: core Sep 9 00:30:52.207763 systemd-networkd[765]: eth0: Gained IPv6LL Sep 9 00:30:52.385084 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 9 00:30:52.835452 ignition[943]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-arm64.raw" Sep 9 00:30:52.835452 ignition[943]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 9 00:30:52.838991 ignition[943]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 9 00:30:52.838991 ignition[943]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 9 00:30:52.838991 ignition[943]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 9 00:30:52.838991 ignition[943]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 9 00:30:52.860426 ignition[943]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 9 00:30:52.864508 ignition[943]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 9 00:30:52.864508 ignition[943]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 9 00:30:52.867209 ignition[943]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 9 00:30:52.867209 ignition[943]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 9 00:30:52.867209 ignition[943]: INFO : files: files passed Sep 9 00:30:52.867209 ignition[943]: INFO : Ignition finished successfully Sep 9 00:30:52.866205 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 9 00:30:52.877743 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 9 00:30:52.880198 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 9 00:30:52.883329 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 9 00:30:52.883436 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 9 00:30:52.890467 initrd-setup-root-after-ignition[972]: grep: /sysroot/oem/oem-release: No such file or directory Sep 9 00:30:52.893822 initrd-setup-root-after-ignition[974]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 00:30:52.893822 initrd-setup-root-after-ignition[974]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 9 00:30:52.896485 initrd-setup-root-after-ignition[978]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 00:30:52.896125 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 00:30:52.897819 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 9 00:30:52.910635 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 9 00:30:52.929629 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 9 00:30:52.930410 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 9 00:30:52.932678 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 9 00:30:52.933490 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 9 00:30:52.935007 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 9 00:30:52.935693 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 9 00:30:52.949917 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 00:30:52.958618 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 9 00:30:52.967006 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 9 00:30:52.968000 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 00:30:52.969574 systemd[1]: Stopped target timers.target - Timer Units. Sep 9 00:30:52.971006 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 9 00:30:52.971115 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 00:30:52.973146 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 9 00:30:52.974716 systemd[1]: Stopped target basic.target - Basic System. Sep 9 00:30:52.976096 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 9 00:30:52.977381 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 00:30:52.978976 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 9 00:30:52.980451 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 9 00:30:52.981973 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 00:30:52.983438 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 9 00:30:52.985074 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 9 00:30:52.986381 systemd[1]: Stopped target swap.target - Swaps. Sep 9 00:30:52.987820 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 9 00:30:52.987936 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 9 00:30:52.990001 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 9 00:30:52.991516 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 00:30:52.993126 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 9 00:30:52.997506 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 00:30:52.998464 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 9 00:30:52.998596 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 9 00:30:53.001046 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 9 00:30:53.001162 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 00:30:53.002682 systemd[1]: Stopped target paths.target - Path Units. Sep 9 00:30:53.003892 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 9 00:30:53.006538 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 00:30:53.007562 systemd[1]: Stopped target slices.target - Slice Units. Sep 9 00:30:53.009203 systemd[1]: Stopped target sockets.target - Socket Units. Sep 9 00:30:53.010399 systemd[1]: iscsid.socket: Deactivated successfully. Sep 9 00:30:53.010500 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 00:30:53.011695 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 9 00:30:53.011787 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 00:30:53.012960 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 9 00:30:53.013060 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 00:30:53.014491 systemd[1]: ignition-files.service: Deactivated successfully. Sep 9 00:30:53.014590 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 9 00:30:53.026647 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 9 00:30:53.027381 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 9 00:30:53.027521 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 00:30:53.032692 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 9 00:30:53.034107 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 9 00:30:53.034238 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 00:30:53.035819 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 9 00:30:53.035914 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 00:30:53.039737 ignition[1000]: INFO : Ignition 2.19.0 Sep 9 00:30:53.039737 ignition[1000]: INFO : Stage: umount Sep 9 00:30:53.039737 ignition[1000]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 00:30:53.039737 ignition[1000]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 9 00:30:53.042765 ignition[1000]: INFO : umount: umount passed Sep 9 00:30:53.042765 ignition[1000]: INFO : Ignition finished successfully Sep 9 00:30:53.041013 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 9 00:30:53.043292 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 9 00:30:53.044780 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 9 00:30:53.044861 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 9 00:30:53.046734 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 9 00:30:53.047408 systemd[1]: Stopped target network.target - Network. Sep 9 00:30:53.048255 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 9 00:30:53.048322 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 9 00:30:53.049711 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 9 00:30:53.049762 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 9 00:30:53.051107 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 9 00:30:53.051145 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 9 00:30:53.052322 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 9 00:30:53.052360 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 9 00:30:53.054040 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 9 00:30:53.055303 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 9 00:30:53.061876 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 9 00:30:53.061977 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 9 00:30:53.062750 systemd-networkd[765]: eth0: DHCPv6 lease lost Sep 9 00:30:53.065738 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 9 00:30:53.065840 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 9 00:30:53.067534 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 9 00:30:53.067585 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 9 00:30:53.072572 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 9 00:30:53.073962 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 9 00:30:53.074020 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 00:30:53.075615 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 9 00:30:53.075654 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 9 00:30:53.077343 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 9 00:30:53.077381 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 9 00:30:53.078811 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 9 00:30:53.078849 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 00:30:53.080609 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 00:30:53.093285 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 9 00:30:53.093419 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 9 00:30:53.096083 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 9 00:30:53.096242 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 00:30:53.098846 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 9 00:30:53.098882 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 9 00:30:53.100312 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 9 00:30:53.100338 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 00:30:53.101827 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 9 00:30:53.101869 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 9 00:30:53.103950 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 9 00:30:53.103985 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 9 00:30:53.105388 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 00:30:53.105427 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 00:30:53.117658 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 9 00:30:53.118431 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 9 00:30:53.118526 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 00:30:53.120327 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 00:30:53.120368 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:30:53.122199 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 9 00:30:53.123515 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 9 00:30:53.125096 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 9 00:30:53.125175 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 9 00:30:53.127054 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 9 00:30:53.131058 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 9 00:30:53.131122 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 9 00:30:53.134250 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 9 00:30:53.145643 systemd[1]: Switching root. Sep 9 00:30:53.164330 systemd-journald[237]: Journal stopped Sep 9 00:30:53.842640 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). Sep 9 00:30:53.842748 kernel: SELinux: policy capability network_peer_controls=1 Sep 9 00:30:53.842765 kernel: SELinux: policy capability open_perms=1 Sep 9 00:30:53.842779 kernel: SELinux: policy capability extended_socket_class=1 Sep 9 00:30:53.842789 kernel: SELinux: policy capability always_check_network=0 Sep 9 00:30:53.842798 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 9 00:30:53.842809 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 9 00:30:53.842818 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 9 00:30:53.842831 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 9 00:30:53.842840 kernel: audit: type=1403 audit(1757377853.288:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 9 00:30:53.842851 systemd[1]: Successfully loaded SELinux policy in 30.922ms. Sep 9 00:30:53.842871 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 10.021ms. Sep 9 00:30:53.842882 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 9 00:30:53.842893 systemd[1]: Detected virtualization kvm. Sep 9 00:30:53.842904 systemd[1]: Detected architecture arm64. Sep 9 00:30:53.842914 systemd[1]: Detected first boot. Sep 9 00:30:53.842926 systemd[1]: Initializing machine ID from VM UUID. Sep 9 00:30:53.842936 zram_generator::config[1044]: No configuration found. Sep 9 00:30:53.842947 systemd[1]: Populated /etc with preset unit settings. Sep 9 00:30:53.842960 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 9 00:30:53.842971 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 9 00:30:53.842983 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 9 00:30:53.842995 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 9 00:30:53.843006 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 9 00:30:53.843016 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 9 00:30:53.843026 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 9 00:30:53.843037 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 9 00:30:53.843047 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 9 00:30:53.843057 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 9 00:30:53.843067 systemd[1]: Created slice user.slice - User and Session Slice. Sep 9 00:30:53.843079 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 00:30:53.843090 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 00:30:53.843100 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 9 00:30:53.843110 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 9 00:30:53.843121 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 9 00:30:53.843131 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 00:30:53.843141 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 9 00:30:53.843192 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 00:30:53.843203 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 9 00:30:53.843217 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 9 00:30:53.843230 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 9 00:30:53.843240 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 9 00:30:53.843251 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 00:30:53.843262 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 00:30:53.843273 systemd[1]: Reached target slices.target - Slice Units. Sep 9 00:30:53.843283 systemd[1]: Reached target swap.target - Swaps. Sep 9 00:30:53.843293 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 9 00:30:53.843306 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 9 00:30:53.843316 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 00:30:53.843326 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 00:30:53.843337 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 00:30:53.843347 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 9 00:30:53.843357 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 9 00:30:53.843367 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 9 00:30:53.843377 systemd[1]: Mounting media.mount - External Media Directory... Sep 9 00:30:53.843387 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 9 00:30:53.843399 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 9 00:30:53.843409 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 9 00:30:53.843420 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 9 00:30:53.843431 systemd[1]: Reached target machines.target - Containers. Sep 9 00:30:53.843441 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 9 00:30:53.843453 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 00:30:53.843463 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 00:30:53.843513 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 9 00:30:53.843530 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 00:30:53.843541 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 00:30:53.843552 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 00:30:53.843563 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 9 00:30:53.843573 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 00:30:53.843584 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 9 00:30:53.843595 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 9 00:30:53.843605 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 9 00:30:53.843616 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 9 00:30:53.843676 kernel: fuse: init (API version 7.39) Sep 9 00:30:53.843721 systemd[1]: Stopped systemd-fsck-usr.service. Sep 9 00:30:53.843734 kernel: ACPI: bus type drm_connector registered Sep 9 00:30:53.843744 kernel: loop: module loaded Sep 9 00:30:53.843754 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 00:30:53.843765 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 00:30:53.843775 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 00:30:53.843786 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 9 00:30:53.843821 systemd-journald[1117]: Collecting audit messages is disabled. Sep 9 00:30:53.843851 systemd-journald[1117]: Journal started Sep 9 00:30:53.843871 systemd-journald[1117]: Runtime Journal (/run/log/journal/df5fe11b2ca34e34bda6c12823c24ea7) is 5.9M, max 47.3M, 41.4M free. Sep 9 00:30:53.645665 systemd[1]: Queued start job for default target multi-user.target. Sep 9 00:30:53.667892 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 9 00:30:53.668261 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 9 00:30:53.847072 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 00:30:53.847120 systemd[1]: verity-setup.service: Deactivated successfully. Sep 9 00:30:53.847134 systemd[1]: Stopped verity-setup.service. Sep 9 00:30:53.850936 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 00:30:53.851586 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 9 00:30:53.852492 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 9 00:30:53.853646 systemd[1]: Mounted media.mount - External Media Directory. Sep 9 00:30:53.854492 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 9 00:30:53.855496 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 9 00:30:53.856426 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 9 00:30:53.858509 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 9 00:30:53.859781 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 00:30:53.861078 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 9 00:30:53.861302 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 9 00:30:53.862874 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 00:30:53.863115 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 00:30:53.864342 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 00:30:53.864646 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 00:30:53.866018 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 00:30:53.866150 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 00:30:53.867391 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 9 00:30:53.867664 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 9 00:30:53.868814 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 00:30:53.868941 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 00:30:53.870221 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 00:30:53.871582 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 00:30:53.872831 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 9 00:30:53.884201 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 00:30:53.890583 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 9 00:30:53.892342 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 9 00:30:53.893283 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 9 00:30:53.893320 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 00:30:53.895132 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 9 00:30:53.897074 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 9 00:30:53.899667 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 9 00:30:53.900584 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 00:30:53.903046 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 9 00:30:53.904945 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 9 00:30:53.905857 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 00:30:53.909640 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 9 00:30:53.911129 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 00:30:53.914367 systemd-journald[1117]: Time spent on flushing to /var/log/journal/df5fe11b2ca34e34bda6c12823c24ea7 is 25.088ms for 834 entries. Sep 9 00:30:53.914367 systemd-journald[1117]: System Journal (/var/log/journal/df5fe11b2ca34e34bda6c12823c24ea7) is 8.0M, max 195.6M, 187.6M free. Sep 9 00:30:53.953467 systemd-journald[1117]: Received client request to flush runtime journal. Sep 9 00:30:53.953576 kernel: loop0: detected capacity change from 0 to 203944 Sep 9 00:30:53.953602 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 9 00:30:53.914674 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 00:30:53.918763 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 9 00:30:53.923449 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 9 00:30:53.927240 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 00:30:53.929229 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 9 00:30:53.930579 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 9 00:30:53.932290 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 9 00:30:53.933667 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 9 00:30:53.938069 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 9 00:30:53.947734 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 9 00:30:53.951734 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 9 00:30:53.954629 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 00:30:53.961161 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 9 00:30:53.970502 kernel: loop1: detected capacity change from 0 to 114432 Sep 9 00:30:53.977011 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 9 00:30:53.979502 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 9 00:30:53.982341 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 9 00:30:53.997644 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 00:30:54.000745 udevadm[1168]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 9 00:30:54.003647 kernel: loop2: detected capacity change from 0 to 114328 Sep 9 00:30:54.016118 systemd-tmpfiles[1177]: ACLs are not supported, ignoring. Sep 9 00:30:54.016136 systemd-tmpfiles[1177]: ACLs are not supported, ignoring. Sep 9 00:30:54.020439 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 00:30:54.045499 kernel: loop3: detected capacity change from 0 to 203944 Sep 9 00:30:54.052508 kernel: loop4: detected capacity change from 0 to 114432 Sep 9 00:30:54.059735 kernel: loop5: detected capacity change from 0 to 114328 Sep 9 00:30:54.065068 (sd-merge)[1181]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 9 00:30:54.065438 (sd-merge)[1181]: Merged extensions into '/usr'. Sep 9 00:30:54.068832 systemd[1]: Reloading requested from client PID 1155 ('systemd-sysext') (unit systemd-sysext.service)... Sep 9 00:30:54.068850 systemd[1]: Reloading... Sep 9 00:30:54.120396 zram_generator::config[1207]: No configuration found. Sep 9 00:30:54.214018 ldconfig[1150]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 00:30:54.217587 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 9 00:30:54.252892 systemd[1]: Reloading finished in 183 ms. Sep 9 00:30:54.281550 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 9 00:30:54.282828 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 9 00:30:54.302696 systemd[1]: Starting ensure-sysext.service... Sep 9 00:30:54.305205 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 00:30:54.313058 systemd[1]: Reloading requested from client PID 1241 ('systemctl') (unit ensure-sysext.service)... Sep 9 00:30:54.313074 systemd[1]: Reloading... Sep 9 00:30:54.321777 systemd-tmpfiles[1242]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 9 00:30:54.322060 systemd-tmpfiles[1242]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 9 00:30:54.322865 systemd-tmpfiles[1242]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 00:30:54.323132 systemd-tmpfiles[1242]: ACLs are not supported, ignoring. Sep 9 00:30:54.323187 systemd-tmpfiles[1242]: ACLs are not supported, ignoring. Sep 9 00:30:54.326494 systemd-tmpfiles[1242]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 00:30:54.326506 systemd-tmpfiles[1242]: Skipping /boot Sep 9 00:30:54.335652 systemd-tmpfiles[1242]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 00:30:54.335666 systemd-tmpfiles[1242]: Skipping /boot Sep 9 00:30:54.349512 zram_generator::config[1269]: No configuration found. Sep 9 00:30:54.444452 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 9 00:30:54.479934 systemd[1]: Reloading finished in 166 ms. Sep 9 00:30:54.494638 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 9 00:30:54.507922 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 00:30:54.515958 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 9 00:30:54.519046 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 9 00:30:54.521159 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 9 00:30:54.524729 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 00:30:54.530776 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 00:30:54.534249 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 9 00:30:54.539162 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 00:30:54.545758 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 00:30:54.549485 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 00:30:54.553772 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 00:30:54.554632 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 00:30:54.557850 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 9 00:30:54.559888 systemd-udevd[1316]: Using default interface naming scheme 'v255'. Sep 9 00:30:54.561355 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 9 00:30:54.563061 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 00:30:54.563179 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 00:30:54.564606 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 00:30:54.564976 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 00:30:54.567214 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 00:30:54.567327 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 00:30:54.577112 augenrules[1330]: No rules Sep 9 00:30:54.577286 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 00:30:54.592914 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 00:30:54.596650 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 00:30:54.599468 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 00:30:54.600360 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 00:30:54.608761 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 9 00:30:54.610402 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 00:30:54.613159 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 9 00:30:54.616139 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 9 00:30:54.619953 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 9 00:30:54.623147 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 9 00:30:54.624687 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 00:30:54.624838 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 00:30:54.627071 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 00:30:54.629519 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 00:30:54.632079 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 9 00:30:54.649087 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 00:30:54.649478 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 00:30:54.659202 systemd[1]: Finished ensure-sysext.service. Sep 9 00:30:54.660773 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 9 00:30:54.661785 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 00:30:54.671583 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1360) Sep 9 00:30:54.674746 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 00:30:54.677647 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 00:30:54.685660 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 00:30:54.686860 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 00:30:54.690491 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 00:30:54.691245 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 00:30:54.695688 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 9 00:30:54.696153 systemd-resolved[1310]: Positive Trust Anchors: Sep 9 00:30:54.696172 systemd-resolved[1310]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 00:30:54.696203 systemd-resolved[1310]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 9 00:30:54.696585 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 9 00:30:54.697026 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 00:30:54.698513 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 00:30:54.701040 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 00:30:54.701180 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 00:30:54.704653 systemd-resolved[1310]: Defaulting to hostname 'linux'. Sep 9 00:30:54.705905 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 00:30:54.706131 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 00:30:54.707133 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 00:30:54.714901 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 00:30:54.715946 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 00:30:54.724318 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 9 00:30:54.731650 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 9 00:30:54.754392 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 9 00:30:54.765669 systemd-networkd[1383]: lo: Link UP Sep 9 00:30:54.765682 systemd-networkd[1383]: lo: Gained carrier Sep 9 00:30:54.766390 systemd-networkd[1383]: Enumeration completed Sep 9 00:30:54.767254 systemd-networkd[1383]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 00:30:54.767267 systemd-networkd[1383]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 00:30:54.767277 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 9 00:30:54.768484 systemd-networkd[1383]: eth0: Link UP Sep 9 00:30:54.768492 systemd-networkd[1383]: eth0: Gained carrier Sep 9 00:30:54.768506 systemd-networkd[1383]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 00:30:54.769407 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 00:30:54.772524 systemd[1]: Reached target network.target - Network. Sep 9 00:30:54.773613 systemd[1]: Reached target time-set.target - System Time Set. Sep 9 00:30:54.782807 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 9 00:30:54.784958 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 00:30:54.786529 systemd-networkd[1383]: eth0: DHCPv4 address 10.0.0.113/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 9 00:30:54.787200 systemd-timesyncd[1384]: Network configuration changed, trying to establish connection. Sep 9 00:30:54.788332 systemd-timesyncd[1384]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 9 00:30:54.788382 systemd-timesyncd[1384]: Initial clock synchronization to Tue 2025-09-09 00:30:54.685925 UTC. Sep 9 00:30:54.795873 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 9 00:30:54.798844 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 9 00:30:54.812532 lvm[1400]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 9 00:30:54.839847 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 00:30:54.850972 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 9 00:30:54.852266 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 00:30:54.854588 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 00:30:54.855502 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 9 00:30:54.856556 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 9 00:30:54.857674 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 9 00:30:54.858626 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 9 00:30:54.859614 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 9 00:30:54.860733 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 9 00:30:54.860773 systemd[1]: Reached target paths.target - Path Units. Sep 9 00:30:54.861495 systemd[1]: Reached target timers.target - Timer Units. Sep 9 00:30:54.862773 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 9 00:30:54.865010 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 00:30:54.876580 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 9 00:30:54.878936 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 9 00:30:54.880380 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 00:30:54.881449 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 00:30:54.882294 systemd[1]: Reached target basic.target - Basic System. Sep 9 00:30:54.883131 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 9 00:30:54.883165 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 9 00:30:54.884093 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 00:30:54.885985 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 9 00:30:54.887147 lvm[1408]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 9 00:30:54.889726 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 9 00:30:54.892232 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 9 00:30:54.894060 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 9 00:30:54.895656 jq[1411]: false Sep 9 00:30:54.897220 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 9 00:30:54.899090 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 9 00:30:54.901413 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 9 00:30:54.907601 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 9 00:30:54.909116 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 9 00:30:54.909531 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 9 00:30:54.912786 systemd[1]: Starting update-engine.service - Update Engine... Sep 9 00:30:54.914034 extend-filesystems[1412]: Found loop3 Sep 9 00:30:54.914034 extend-filesystems[1412]: Found loop4 Sep 9 00:30:54.914034 extend-filesystems[1412]: Found loop5 Sep 9 00:30:54.914034 extend-filesystems[1412]: Found vda Sep 9 00:30:54.914034 extend-filesystems[1412]: Found vda1 Sep 9 00:30:54.914034 extend-filesystems[1412]: Found vda2 Sep 9 00:30:54.914034 extend-filesystems[1412]: Found vda3 Sep 9 00:30:54.914034 extend-filesystems[1412]: Found usr Sep 9 00:30:54.914034 extend-filesystems[1412]: Found vda4 Sep 9 00:30:54.914034 extend-filesystems[1412]: Found vda6 Sep 9 00:30:54.914034 extend-filesystems[1412]: Found vda7 Sep 9 00:30:54.914034 extend-filesystems[1412]: Found vda9 Sep 9 00:30:54.914034 extend-filesystems[1412]: Checking size of /dev/vda9 Sep 9 00:30:54.948690 extend-filesystems[1412]: Resized partition /dev/vda9 Sep 9 00:30:54.932073 dbus-daemon[1410]: [system] SELinux support is enabled Sep 9 00:30:54.915732 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 9 00:30:54.951615 extend-filesystems[1435]: resize2fs 1.47.1 (20-May-2024) Sep 9 00:30:54.952772 update_engine[1420]: I20250909 00:30:54.944190 1420 main.cc:92] Flatcar Update Engine starting Sep 9 00:30:54.923262 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 9 00:30:54.953844 jq[1424]: true Sep 9 00:30:54.926947 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 9 00:30:54.928965 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 9 00:30:54.929252 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 9 00:30:54.929402 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 9 00:30:54.935742 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 9 00:30:54.942581 systemd[1]: motdgen.service: Deactivated successfully. Sep 9 00:30:54.942753 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 9 00:30:54.954405 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 9 00:30:54.954439 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 9 00:30:54.954982 update_engine[1420]: I20250909 00:30:54.954782 1420 update_check_scheduler.cc:74] Next update check in 11m10s Sep 9 00:30:54.957332 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 9 00:30:54.957357 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 9 00:30:54.959625 systemd[1]: Started update-engine.service - Update Engine. Sep 9 00:30:54.962565 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 9 00:30:54.962618 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1364) Sep 9 00:30:54.967210 (ntainerd)[1434]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 9 00:30:54.969687 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 9 00:30:54.972498 jq[1433]: true Sep 9 00:30:54.984311 systemd-logind[1417]: Watching system buttons on /dev/input/event0 (Power Button) Sep 9 00:30:54.985311 systemd-logind[1417]: New seat seat0. Sep 9 00:30:54.986838 systemd[1]: Started systemd-logind.service - User Login Management. Sep 9 00:30:55.002522 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 9 00:30:55.017621 locksmithd[1443]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 9 00:30:55.018430 extend-filesystems[1435]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 9 00:30:55.018430 extend-filesystems[1435]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 9 00:30:55.018430 extend-filesystems[1435]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 9 00:30:55.023903 extend-filesystems[1412]: Resized filesystem in /dev/vda9 Sep 9 00:30:55.019752 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 9 00:30:55.019928 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 9 00:30:55.035173 bash[1468]: Updated "/home/core/.ssh/authorized_keys" Sep 9 00:30:55.036591 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 9 00:30:55.038241 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 9 00:30:55.127969 containerd[1434]: time="2025-09-09T00:30:55.127859962Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Sep 9 00:30:55.155506 containerd[1434]: time="2025-09-09T00:30:55.155254407Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 9 00:30:55.157225 containerd[1434]: time="2025-09-09T00:30:55.157185824Z" 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.104-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 9 00:30:55.157307 containerd[1434]: time="2025-09-09T00:30:55.157293035Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 9 00:30:55.157365 containerd[1434]: time="2025-09-09T00:30:55.157352031Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 9 00:30:55.157610 containerd[1434]: time="2025-09-09T00:30:55.157585210Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 9 00:30:55.157720 containerd[1434]: time="2025-09-09T00:30:55.157704228Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 9 00:30:55.157854 containerd[1434]: time="2025-09-09T00:30:55.157833473Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 9 00:30:55.157913 containerd[1434]: time="2025-09-09T00:30:55.157899537Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 9 00:30:55.158151 containerd[1434]: time="2025-09-09T00:30:55.158127306Z" 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 9 00:30:55.158845 containerd[1434]: time="2025-09-09T00:30:55.158201071Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 9 00:30:55.158845 containerd[1434]: time="2025-09-09T00:30:55.158221842Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 9 00:30:55.158845 containerd[1434]: time="2025-09-09T00:30:55.158232780Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 9 00:30:55.158845 containerd[1434]: time="2025-09-09T00:30:55.158321036Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 9 00:30:55.158845 containerd[1434]: time="2025-09-09T00:30:55.158545568Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 9 00:30:55.158845 containerd[1434]: time="2025-09-09T00:30:55.158657359Z" 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 9 00:30:55.158845 containerd[1434]: time="2025-09-09T00:30:55.158681289Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 9 00:30:55.158845 containerd[1434]: time="2025-09-09T00:30:55.158761609Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 9 00:30:55.158845 containerd[1434]: time="2025-09-09T00:30:55.158805836Z" level=info msg="metadata content store policy set" policy=shared Sep 9 00:30:55.163770 containerd[1434]: time="2025-09-09T00:30:55.163742241Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 9 00:30:55.163898 containerd[1434]: time="2025-09-09T00:30:55.163882385Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 9 00:30:55.163986 containerd[1434]: time="2025-09-09T00:30:55.163970839Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 9 00:30:55.164056 containerd[1434]: time="2025-09-09T00:30:55.164043814Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 9 00:30:55.164110 containerd[1434]: time="2025-09-09T00:30:55.164098308Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 9 00:30:55.164296 containerd[1434]: time="2025-09-09T00:30:55.164275413Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 9 00:30:55.164654 containerd[1434]: time="2025-09-09T00:30:55.164635271Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 9 00:30:55.164832 containerd[1434]: time="2025-09-09T00:30:55.164812218Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 9 00:30:55.164896 containerd[1434]: time="2025-09-09T00:30:55.164882784Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 9 00:30:55.164971 containerd[1434]: time="2025-09-09T00:30:55.164957457Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 9 00:30:55.165030 containerd[1434]: time="2025-09-09T00:30:55.165017558Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 9 00:30:55.165085 containerd[1434]: time="2025-09-09T00:30:55.165073355Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 9 00:30:55.165145 containerd[1434]: time="2025-09-09T00:30:55.165132825Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 9 00:30:55.165198 containerd[1434]: time="2025-09-09T00:30:55.165186213Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 9 00:30:55.165253 containerd[1434]: time="2025-09-09T00:30:55.165240115Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 9 00:30:55.165309 containerd[1434]: time="2025-09-09T00:30:55.165296228Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 9 00:30:55.165361 containerd[1434]: time="2025-09-09T00:30:55.165348589Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 9 00:30:55.165416 containerd[1434]: time="2025-09-09T00:30:55.165403676Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 9 00:30:55.165510 containerd[1434]: time="2025-09-09T00:30:55.165494736Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.165615 containerd[1434]: time="2025-09-09T00:30:55.165598630Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.165673 containerd[1434]: time="2025-09-09T00:30:55.165661456Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.165747 containerd[1434]: time="2025-09-09T00:30:55.165734036Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.165805 containerd[1434]: time="2025-09-09T00:30:55.165793110Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.165855 containerd[1434]: time="2025-09-09T00:30:55.165843576Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.165907 containerd[1434]: time="2025-09-09T00:30:55.165894438Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.165959 containerd[1434]: time="2025-09-09T00:30:55.165947115Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.166116 containerd[1434]: time="2025-09-09T00:30:55.166097487Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.166181 containerd[1434]: time="2025-09-09T00:30:55.166168921Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.166235 containerd[1434]: time="2025-09-09T00:30:55.166222389Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.166298 containerd[1434]: time="2025-09-09T00:30:55.166284859Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.166356 containerd[1434]: time="2025-09-09T00:30:55.166342789Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.166419 containerd[1434]: time="2025-09-09T00:30:55.166406523Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 9 00:30:55.166650 containerd[1434]: time="2025-09-09T00:30:55.166499321Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.166650 containerd[1434]: time="2025-09-09T00:30:55.166518433Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.166650 containerd[1434]: time="2025-09-09T00:30:55.166529845Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 9 00:30:55.167031 containerd[1434]: time="2025-09-09T00:30:55.166750467Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 9 00:30:55.167276 containerd[1434]: time="2025-09-09T00:30:55.167253628Z" 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 9 00:30:55.167326 containerd[1434]: time="2025-09-09T00:30:55.167314835Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 9 00:30:55.167389 containerd[1434]: time="2025-09-09T00:30:55.167363130Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 9 00:30:55.167436 containerd[1434]: time="2025-09-09T00:30:55.167425008Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.167518 containerd[1434]: time="2025-09-09T00:30:55.167504143Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 9 00:30:55.168508 containerd[1434]: time="2025-09-09T00:30:55.167585094Z" level=info msg="NRI interface is disabled by configuration." Sep 9 00:30:55.168508 containerd[1434]: time="2025-09-09T00:30:55.167604483Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 9 00:30:55.168588 containerd[1434]: time="2025-09-09T00:30:55.167970461Z" 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 9 00:30:55.168588 containerd[1434]: time="2025-09-09T00:30:55.168032300Z" level=info msg="Connect containerd service" Sep 9 00:30:55.168588 containerd[1434]: time="2025-09-09T00:30:55.168062311Z" level=info msg="using legacy CRI server" Sep 9 00:30:55.168588 containerd[1434]: time="2025-09-09T00:30:55.168069064Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 00:30:55.168588 containerd[1434]: time="2025-09-09T00:30:55.168158071Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 9 00:30:55.169130 containerd[1434]: time="2025-09-09T00:30:55.169100106Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 9 00:30:55.169442 containerd[1434]: time="2025-09-09T00:30:55.169410248Z" level=info msg="Start subscribing containerd event" Sep 9 00:30:55.169557 containerd[1434]: time="2025-09-09T00:30:55.169541428Z" level=info msg="Start recovering state" Sep 9 00:30:55.170062 containerd[1434]: time="2025-09-09T00:30:55.170044668Z" level=info msg="Start event monitor" Sep 9 00:30:55.170132 containerd[1434]: time="2025-09-09T00:30:55.170119222Z" level=info msg="Start snapshots syncer" Sep 9 00:30:55.170198 containerd[1434]: time="2025-09-09T00:30:55.170185247Z" level=info msg="Start cni network conf syncer for default" Sep 9 00:30:55.170245 containerd[1434]: time="2025-09-09T00:30:55.170234134Z" level=info msg="Start streaming server" Sep 9 00:30:55.170444 containerd[1434]: time="2025-09-09T00:30:55.169973668Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 00:30:55.170592 containerd[1434]: time="2025-09-09T00:30:55.170573615Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 00:30:55.170773 containerd[1434]: time="2025-09-09T00:30:55.170758461Z" level=info msg="containerd successfully booted in 0.044093s" Sep 9 00:30:55.170847 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 00:30:55.207074 sshd_keygen[1430]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 9 00:30:55.226860 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 9 00:30:55.236799 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 9 00:30:55.242901 systemd[1]: issuegen.service: Deactivated successfully. Sep 9 00:30:55.243141 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 9 00:30:55.246382 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 9 00:30:55.259383 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 9 00:30:55.263724 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 9 00:30:55.266854 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 9 00:30:55.267925 systemd[1]: Reached target getty.target - Login Prompts. Sep 9 00:30:56.815619 systemd-networkd[1383]: eth0: Gained IPv6LL Sep 9 00:30:56.818232 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 9 00:30:56.820434 systemd[1]: Reached target network-online.target - Network is Online. Sep 9 00:30:56.832763 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 9 00:30:56.835010 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 9 00:30:56.836994 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 9 00:30:56.852384 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 9 00:30:56.852600 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 9 00:30:56.854012 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 9 00:30:56.856332 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 9 00:30:57.420958 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 9 00:30:57.422826 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 9 00:30:57.423849 systemd[1]: Startup finished in 503ms (kernel) + 4.609s (initrd) + 4.166s (userspace) = 9.279s. Sep 9 00:30:57.424647 (kubelet)[1517]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 9 00:30:57.802028 kubelet[1517]: E0909 00:30:57.801908 1517 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Sep 9 00:30:57.804541 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 9 00:30:57.804683 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 9 00:31:01.533760 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 9 00:31:01.534987 systemd[1]: Started sshd@0-10.0.0.113:22-10.0.0.1:41702.service - OpenSSH per-connection server daemon (10.0.0.1:41702). Sep 9 00:31:01.582990 sshd[1530]: Accepted publickey for core from 10.0.0.1 port 41702 ssh2: RSA SHA256:h2hdqj5up/hBRHZQ3StgDpJiWnWjl57ZEr1UTjCMf5k Sep 9 00:31:01.585437 sshd[1530]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:31:01.594927 systemd-logind[1417]: New session 1 of user core. Sep 9 00:31:01.596000 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 9 00:31:01.604718 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 9 00:31:01.615590 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 9 00:31:01.617889 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 9 00:31:01.624839 (systemd)[1534]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 9 00:31:01.697804 systemd[1534]: Queued start job for default target default.target. Sep 9 00:31:01.712453 systemd[1534]: Created slice app.slice - User Application Slice. Sep 9 00:31:01.712505 systemd[1534]: Reached target paths.target - Paths. Sep 9 00:31:01.712517 systemd[1534]: Reached target timers.target - Timers. Sep 9 00:31:01.713762 systemd[1534]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 00:31:01.724318 systemd[1534]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 00:31:01.724395 systemd[1534]: Reached target sockets.target - Sockets. Sep 9 00:31:01.724408 systemd[1534]: Reached target basic.target - Basic System. Sep 9 00:31:01.724448 systemd[1534]: Reached target default.target - Main User Target. Sep 9 00:31:01.724496 systemd[1534]: Startup finished in 94ms. Sep 9 00:31:01.724707 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 9 00:31:01.725999 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 9 00:31:01.789547 systemd[1]: Started sshd@1-10.0.0.113:22-10.0.0.1:41716.service - OpenSSH per-connection server daemon (10.0.0.1:41716). Sep 9 00:31:01.846853 sshd[1545]: Accepted publickey for core from 10.0.0.1 port 41716 ssh2: RSA SHA256:h2hdqj5up/hBRHZQ3StgDpJiWnWjl57ZEr1UTjCMf5k Sep 9 00:31:01.848065 sshd[1545]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:31:01.853199 systemd-logind[1417]: New session 2 of user core. Sep 9 00:31:01.862648 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 9 00:31:01.913531 sshd[1545]: pam_unix(sshd:session): session closed for user core Sep 9 00:31:01.922693 systemd[1]: sshd@1-10.0.0.113:22-10.0.0.1:41716.service: Deactivated successfully. Sep 9 00:31:01.923879 systemd[1]: session-2.scope: Deactivated successfully. Sep 9 00:31:01.928048 systemd-logind[1417]: Session 2 logged out. Waiting for processes to exit. Sep 9 00:31:01.933411 systemd[1]: Started sshd@2-10.0.0.113:22-10.0.0.1:41726.service - OpenSSH per-connection server daemon (10.0.0.1:41726). Sep 9 00:31:01.934153 systemd-logind[1417]: Removed session 2. Sep 9 00:31:01.974134 sshd[1552]: Accepted publickey for core from 10.0.0.1 port 41726 ssh2: RSA SHA256:h2hdqj5up/hBRHZQ3StgDpJiWnWjl57ZEr1UTjCMf5k Sep 9 00:31:01.975559 sshd[1552]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:31:01.980046 systemd-logind[1417]: New session 3 of user core. Sep 9 00:31:01.989737 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 9 00:31:02.038534 sshd[1552]: pam_unix(sshd:session): session closed for user core Sep 9 00:31:02.046520 systemd[1]: sshd@2-10.0.0.113:22-10.0.0.1:41726.service: Deactivated successfully. Sep 9 00:31:02.047938 systemd[1]: session-3.scope: Deactivated successfully. Sep 9 00:31:02.050920 systemd-logind[1417]: Session 3 logged out. Waiting for processes to exit. Sep 9 00:31:02.057833 systemd[1]: Started sshd@3-10.0.0.113:22-10.0.0.1:41728.service - OpenSSH per-connection server daemon (10.0.0.1:41728). Sep 9 00:31:02.061614 systemd-logind[1417]: Removed session 3. Sep 9 00:31:02.089237 sshd[1559]: Accepted publickey for core from 10.0.0.1 port 41728 ssh2: RSA SHA256:h2hdqj5up/hBRHZQ3StgDpJiWnWjl57ZEr1UTjCMf5k Sep 9 00:31:02.090339 sshd[1559]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:31:02.094216 systemd-logind[1417]: New session 4 of user core. Sep 9 00:31:02.109627 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 9 00:31:02.161467 sshd[1559]: pam_unix(sshd:session): session closed for user core Sep 9 00:31:02.171749 systemd[1]: sshd@3-10.0.0.113:22-10.0.0.1:41728.service: Deactivated successfully. Sep 9 00:31:02.174824 systemd[1]: session-4.scope: Deactivated successfully. Sep 9 00:31:02.177230 systemd-logind[1417]: Session 4 logged out. Waiting for processes to exit. Sep 9 00:31:02.190732 systemd[1]: Started sshd@4-10.0.0.113:22-10.0.0.1:41740.service - OpenSSH per-connection server daemon (10.0.0.1:41740). Sep 9 00:31:02.192044 systemd-logind[1417]: Removed session 4. Sep 9 00:31:02.225877 sshd[1566]: Accepted publickey for core from 10.0.0.1 port 41740 ssh2: RSA SHA256:h2hdqj5up/hBRHZQ3StgDpJiWnWjl57ZEr1UTjCMf5k Sep 9 00:31:02.227221 sshd[1566]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 00:31:02.231296 systemd-logind[1417]: New session 5 of user core. Sep 9 00:31:02.239648 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 9 00:31:02.297441 sudo[1569]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 9 00:31:02.297738 sudo[1569]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 00:31:02.318440 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 9 00:31:02.345376 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 9 00:31:02.346547 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 9 00:31:02.757127 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 9 00:31:02.772694 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 9 00:31:02.802863 systemd[1]: Reloading requested from client PID 1611 ('systemctl') (unit session-5.scope)... Sep 9 00:31:02.802882 systemd[1]: Reloading... Sep 9 00:31:02.875550 zram_generator::config[1649]: No configuration found. Sep 9 00:31:03.069039 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Sep 9 00:31:03.122654 systemd[1]: Reloading finished in 319 ms. Sep 9 00:31:03.161769 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 9 00:31:03.161833 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 9 00:31:03.162041 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 9 00:31:03.164197 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 9 00:31:03.268958 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 9 00:31:03.272705 (kubelet)[1695]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 9 00:31:03.305722 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 9 00:31:03.305722 kubelet[1695]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Sep 9 00:31:03.305722 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 9 00:31:03.305722 kubelet[1695]: I0909 00:31:03.305091 1695 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 9 00:31:03.872683 kubelet[1695]: I0909 00:31:03.872635 1695 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Sep 9 00:31:03.872683 kubelet[1695]: I0909 00:31:03.872667 1695 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 9 00:31:03.872931 kubelet[1695]: I0909 00:31:03.872900 1695 server.go:934] "Client rotation is on, will bootstrap in background" Sep 9 00:31:03.889750 kubelet[1695]: I0909 00:31:03.889495 1695 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 9 00:31:03.902655 kubelet[1695]: E0909 00:31:03.902621 1695 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Sep 9 00:31:03.902655 kubelet[1695]: I0909 00:31:03.902654 1695 server.go:1408] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Sep 9 00:31:03.906372 kubelet[1695]: I0909 00:31:03.906343 1695 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 9 00:31:03.907353 kubelet[1695]: I0909 00:31:03.907319 1695 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Sep 9 00:31:03.907527 kubelet[1695]: I0909 00:31:03.907462 1695 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 9 00:31:03.907702 kubelet[1695]: I0909 00:31:03.907520 1695 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.113","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"imagefs.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},{"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}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 9 00:31:03.907788 kubelet[1695]: I0909 00:31:03.907770 1695 topology_manager.go:138] "Creating topology manager with none policy" Sep 9 00:31:03.907788 kubelet[1695]: I0909 00:31:03.907780 1695 container_manager_linux.go:300] "Creating device plugin manager" Sep 9 00:31:03.908074 kubelet[1695]: I0909 00:31:03.908060 1695 state_mem.go:36] "Initialized new in-memory state store" Sep 9 00:31:03.910091 kubelet[1695]: I0909 00:31:03.910063 1695 kubelet.go:408] "Attempting to sync node with API server" Sep 9 00:31:03.910133 kubelet[1695]: I0909 00:31:03.910094 1695 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 9 00:31:03.910133 kubelet[1695]: I0909 00:31:03.910120 1695 kubelet.go:314] "Adding apiserver pod source" Sep 9 00:31:03.910435 kubelet[1695]: I0909 00:31:03.910194 1695 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 9 00:31:03.910435 kubelet[1695]: E0909 00:31:03.910251 1695 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:03.910435 kubelet[1695]: E0909 00:31:03.910295 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:03.914388 kubelet[1695]: I0909 00:31:03.914368 1695 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Sep 9 00:31:03.915135 kubelet[1695]: I0909 00:31:03.915101 1695 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Sep 9 00:31:03.915300 kubelet[1695]: W0909 00:31:03.915290 1695 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 9 00:31:03.916224 kubelet[1695]: I0909 00:31:03.916197 1695 server.go:1274] "Started kubelet" Sep 9 00:31:03.917430 kubelet[1695]: I0909 00:31:03.917288 1695 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 9 00:31:03.920647 kubelet[1695]: I0909 00:31:03.920496 1695 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Sep 9 00:31:03.921520 kubelet[1695]: I0909 00:31:03.921449 1695 server.go:449] "Adding debug handlers to kubelet server" Sep 9 00:31:03.924359 kubelet[1695]: I0909 00:31:03.922581 1695 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 9 00:31:03.924359 kubelet[1695]: I0909 00:31:03.922793 1695 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 9 00:31:03.924359 kubelet[1695]: I0909 00:31:03.923342 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 9 00:31:03.925663 kubelet[1695]: I0909 00:31:03.925022 1695 volume_manager.go:289] "Starting Kubelet Volume Manager" Sep 9 00:31:03.925663 kubelet[1695]: I0909 00:31:03.925110 1695 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Sep 9 00:31:03.925663 kubelet[1695]: I0909 00:31:03.925155 1695 reconciler.go:26] "Reconciler: start to sync state" Sep 9 00:31:03.925663 kubelet[1695]: E0909 00:31:03.925324 1695 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.113\" not found" Sep 9 00:31:03.925833 kubelet[1695]: I0909 00:31:03.925823 1695 factory.go:221] Registration of the systemd container factory successfully Sep 9 00:31:03.926085 kubelet[1695]: I0909 00:31:03.925918 1695 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Sep 9 00:31:03.926645 kubelet[1695]: E0909 00:31:03.926598 1695 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 9 00:31:03.927320 kubelet[1695]: I0909 00:31:03.927292 1695 factory.go:221] Registration of the containerd container factory successfully Sep 9 00:31:03.935979 kubelet[1695]: E0909 00:31:03.935935 1695 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.113\" not found" node="10.0.0.113" Sep 9 00:31:03.936897 kubelet[1695]: I0909 00:31:03.936877 1695 cpu_manager.go:214] "Starting CPU manager" policy="none" Sep 9 00:31:03.936897 kubelet[1695]: I0909 00:31:03.936894 1695 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Sep 9 00:31:03.937059 kubelet[1695]: I0909 00:31:03.936913 1695 state_mem.go:36] "Initialized new in-memory state store" Sep 9 00:31:04.008719 kubelet[1695]: I0909 00:31:04.008679 1695 policy_none.go:49] "None policy: Start" Sep 9 00:31:04.009937 kubelet[1695]: I0909 00:31:04.009922 1695 memory_manager.go:170] "Starting memorymanager" policy="None" Sep 9 00:31:04.009988 kubelet[1695]: I0909 00:31:04.009944 1695 state_mem.go:35] "Initializing new in-memory state store" Sep 9 00:31:04.016958 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 9 00:31:04.026185 kubelet[1695]: E0909 00:31:04.026152 1695 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.113\" not found" Sep 9 00:31:04.033751 kubelet[1695]: I0909 00:31:04.033724 1695 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 9 00:31:04.034214 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 9 00:31:04.035125 kubelet[1695]: I0909 00:31:04.035105 1695 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 9 00:31:04.035366 kubelet[1695]: I0909 00:31:04.035354 1695 status_manager.go:217] "Starting to sync pod status with apiserver" Sep 9 00:31:04.035458 kubelet[1695]: I0909 00:31:04.035449 1695 kubelet.go:2321] "Starting kubelet main sync loop" Sep 9 00:31:04.035566 kubelet[1695]: E0909 00:31:04.035548 1695 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Sep 9 00:31:04.038454 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 9 00:31:04.050441 kubelet[1695]: I0909 00:31:04.050151 1695 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 9 00:31:04.050646 kubelet[1695]: I0909 00:31:04.050633 1695 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 9 00:31:04.050747 kubelet[1695]: I0909 00:31:04.050704 1695 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 9 00:31:04.051702 kubelet[1695]: I0909 00:31:04.051688 1695 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 9 00:31:04.052365 kubelet[1695]: E0909 00:31:04.052328 1695 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.113\" not found" Sep 9 00:31:04.153950 kubelet[1695]: I0909 00:31:04.153848 1695 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.113" Sep 9 00:31:04.159514 kubelet[1695]: I0909 00:31:04.159436 1695 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.113" Sep 9 00:31:04.269635 kubelet[1695]: I0909 00:31:04.269606 1695 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 9 00:31:04.269947 containerd[1434]: time="2025-09-09T00:31:04.269913207Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 9 00:31:04.270202 kubelet[1695]: I0909 00:31:04.270088 1695 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 9 00:31:04.302645 sudo[1569]: pam_unix(sudo:session): session closed for user root Sep 9 00:31:04.304291 sshd[1566]: pam_unix(sshd:session): session closed for user core Sep 9 00:31:04.307413 systemd[1]: sshd@4-10.0.0.113:22-10.0.0.1:41740.service: Deactivated successfully. Sep 9 00:31:04.308955 systemd[1]: session-5.scope: Deactivated successfully. Sep 9 00:31:04.309555 systemd-logind[1417]: Session 5 logged out. Waiting for processes to exit. Sep 9 00:31:04.310623 systemd-logind[1417]: Removed session 5. Sep 9 00:31:04.874519 kubelet[1695]: I0909 00:31:04.874437 1695 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 9 00:31:04.875006 kubelet[1695]: W0909 00:31:04.874650 1695 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 9 00:31:04.875006 kubelet[1695]: W0909 00:31:04.874657 1695 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 9 00:31:04.875006 kubelet[1695]: W0909 00:31:04.874682 1695 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 9 00:31:04.911375 kubelet[1695]: E0909 00:31:04.911301 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:04.911375 kubelet[1695]: I0909 00:31:04.911333 1695 apiserver.go:52] "Watching apiserver" Sep 9 00:31:04.920056 systemd[1]: Created slice kubepods-besteffort-pod42a2e303_9f7f_43ef_ab90_31a02eea02ce.slice - libcontainer container kubepods-besteffort-pod42a2e303_9f7f_43ef_ab90_31a02eea02ce.slice. Sep 9 00:31:04.925534 kubelet[1695]: I0909 00:31:04.925493 1695 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Sep 9 00:31:04.929522 systemd[1]: Created slice kubepods-burstable-poda7d39dce_5e83_4b56_9cb5_1fd301bdccbd.slice - libcontainer container kubepods-burstable-poda7d39dce_5e83_4b56_9cb5_1fd301bdccbd.slice. Sep 9 00:31:04.931983 kubelet[1695]: I0909 00:31:04.931945 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/a7d39dce-5e83-4b56-9cb5-1fd301bdccbd-cni\") pod \"kube-flannel-ds-nxplx\" (UID: \"a7d39dce-5e83-4b56-9cb5-1fd301bdccbd\") " pod="kube-flannel/kube-flannel-ds-nxplx" Sep 9 00:31:04.931983 kubelet[1695]: I0909 00:31:04.931981 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/a7d39dce-5e83-4b56-9cb5-1fd301bdccbd-flannel-cfg\") pod \"kube-flannel-ds-nxplx\" (UID: \"a7d39dce-5e83-4b56-9cb5-1fd301bdccbd\") " pod="kube-flannel/kube-flannel-ds-nxplx" Sep 9 00:31:04.932085 kubelet[1695]: I0909 00:31:04.932001 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/42a2e303-9f7f-43ef-ab90-31a02eea02ce-kube-proxy\") pod \"kube-proxy-pt422\" (UID: \"42a2e303-9f7f-43ef-ab90-31a02eea02ce\") " pod="kube-system/kube-proxy-pt422" Sep 9 00:31:04.932085 kubelet[1695]: I0909 00:31:04.932043 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/42a2e303-9f7f-43ef-ab90-31a02eea02ce-xtables-lock\") pod \"kube-proxy-pt422\" (UID: \"42a2e303-9f7f-43ef-ab90-31a02eea02ce\") " pod="kube-system/kube-proxy-pt422" Sep 9 00:31:04.932085 kubelet[1695]: I0909 00:31:04.932059 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/42a2e303-9f7f-43ef-ab90-31a02eea02ce-lib-modules\") pod \"kube-proxy-pt422\" (UID: \"42a2e303-9f7f-43ef-ab90-31a02eea02ce\") " pod="kube-system/kube-proxy-pt422" Sep 9 00:31:04.932085 kubelet[1695]: I0909 00:31:04.932073 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/a7d39dce-5e83-4b56-9cb5-1fd301bdccbd-run\") pod \"kube-flannel-ds-nxplx\" (UID: \"a7d39dce-5e83-4b56-9cb5-1fd301bdccbd\") " pod="kube-flannel/kube-flannel-ds-nxplx" Sep 9 00:31:04.932168 kubelet[1695]: I0909 00:31:04.932088 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/a7d39dce-5e83-4b56-9cb5-1fd301bdccbd-cni-plugin\") pod \"kube-flannel-ds-nxplx\" (UID: \"a7d39dce-5e83-4b56-9cb5-1fd301bdccbd\") " pod="kube-flannel/kube-flannel-ds-nxplx" Sep 9 00:31:04.932168 kubelet[1695]: I0909 00:31:04.932103 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/a7d39dce-5e83-4b56-9cb5-1fd301bdccbd-xtables-lock\") pod \"kube-flannel-ds-nxplx\" (UID: \"a7d39dce-5e83-4b56-9cb5-1fd301bdccbd\") " pod="kube-flannel/kube-flannel-ds-nxplx" Sep 9 00:31:04.932168 kubelet[1695]: I0909 00:31:04.932119 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-w6kb6\" (UniqueName: \"kubernetes.io/projected/a7d39dce-5e83-4b56-9cb5-1fd301bdccbd-kube-api-access-w6kb6\") pod \"kube-flannel-ds-nxplx\" (UID: \"a7d39dce-5e83-4b56-9cb5-1fd301bdccbd\") " pod="kube-flannel/kube-flannel-ds-nxplx" Sep 9 00:31:04.932168 kubelet[1695]: I0909 00:31:04.932135 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-b5qmd\" (UniqueName: \"kubernetes.io/projected/42a2e303-9f7f-43ef-ab90-31a02eea02ce-kube-api-access-b5qmd\") pod \"kube-proxy-pt422\" (UID: \"42a2e303-9f7f-43ef-ab90-31a02eea02ce\") " pod="kube-system/kube-proxy-pt422" Sep 9 00:31:05.228123 kubelet[1695]: E0909 00:31:05.227926 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 9 00:31:05.228878 containerd[1434]: time="2025-09-09T00:31:05.228714531Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-pt422,Uid:42a2e303-9f7f-43ef-ab90-31a02eea02ce,Namespace:kube-system,Attempt:0,}" Sep 9 00:31:05.239378 kubelet[1695]: E0909 00:31:05.239215 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 9 00:31:05.239732 containerd[1434]: time="2025-09-09T00:31:05.239684176Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-nxplx,Uid:a7d39dce-5e83-4b56-9cb5-1fd301bdccbd,Namespace:kube-flannel,Attempt:0,}" Sep 9 00:31:05.860829 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount762944393.mount: Deactivated successfully. Sep 9 00:31:05.868482 containerd[1434]: time="2025-09-09T00:31:05.868385615Z" 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 9 00:31:05.869505 containerd[1434]: time="2025-09-09T00:31:05.869434434Z" 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 9 00:31:05.870309 containerd[1434]: time="2025-09-09T00:31:05.870162536Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Sep 9 00:31:05.871336 containerd[1434]: time="2025-09-09T00:31:05.871306235Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Sep 9 00:31:05.872167 containerd[1434]: time="2025-09-09T00:31:05.872124154Z" 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 9 00:31:05.875656 containerd[1434]: time="2025-09-09T00:31:05.875593321Z" 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 9 00:31:05.877204 containerd[1434]: time="2025-09-09T00:31:05.877163181Z" 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 648.353252ms" Sep 9 00:31:05.878730 containerd[1434]: time="2025-09-09T00:31:05.878526937Z" 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 638.726833ms" Sep 9 00:31:05.911984 kubelet[1695]: E0909 00:31:05.911928 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:05.985166 containerd[1434]: time="2025-09-09T00:31:05.984947969Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 9 00:31:05.985166 containerd[1434]: time="2025-09-09T00:31:05.985006611Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 9 00:31:05.985166 containerd[1434]: time="2025-09-09T00:31:05.985030052Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:05.985917 containerd[1434]: time="2025-09-09T00:31:05.985792159Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:05.986296 containerd[1434]: time="2025-09-09T00:31:05.986185870Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 9 00:31:05.986356 containerd[1434]: time="2025-09-09T00:31:05.986277440Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 9 00:31:05.986356 containerd[1434]: time="2025-09-09T00:31:05.986296775Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:05.986964 containerd[1434]: time="2025-09-09T00:31:05.986920669Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:06.041667 systemd[1]: Started cri-containerd-07df3a1ebcb83c8e8f5ae30cb2b0a2492ccfe6fb252f90f824695aa3e98a79f6.scope - libcontainer container 07df3a1ebcb83c8e8f5ae30cb2b0a2492ccfe6fb252f90f824695aa3e98a79f6. Sep 9 00:31:06.042858 systemd[1]: Started cri-containerd-687d84143b9443d540e9830d4b8a09b62ca4f4765f88d4ee9533851c1d04fe9e.scope - libcontainer container 687d84143b9443d540e9830d4b8a09b62ca4f4765f88d4ee9533851c1d04fe9e. Sep 9 00:31:06.067992 containerd[1434]: time="2025-09-09T00:31:06.067917482Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-pt422,Uid:42a2e303-9f7f-43ef-ab90-31a02eea02ce,Namespace:kube-system,Attempt:0,} returns sandbox id \"687d84143b9443d540e9830d4b8a09b62ca4f4765f88d4ee9533851c1d04fe9e\"" Sep 9 00:31:06.070796 kubelet[1695]: E0909 00:31:06.070768 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 9 00:31:06.072222 containerd[1434]: time="2025-09-09T00:31:06.072029181Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.12\"" Sep 9 00:31:06.081560 containerd[1434]: time="2025-09-09T00:31:06.081527016Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-nxplx,Uid:a7d39dce-5e83-4b56-9cb5-1fd301bdccbd,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"07df3a1ebcb83c8e8f5ae30cb2b0a2492ccfe6fb252f90f824695aa3e98a79f6\"" Sep 9 00:31:06.082314 kubelet[1695]: E0909 00:31:06.082292 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 9 00:31:06.912757 kubelet[1695]: E0909 00:31:06.912607 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:06.985619 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3148199787.mount: Deactivated successfully. Sep 9 00:31:07.268466 containerd[1434]: time="2025-09-09T00:31:07.268322870Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.12\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:07.269053 containerd[1434]: time="2025-09-09T00:31:07.269014843Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.12: active requests=0, bytes read=26916097" Sep 9 00:31:07.269924 containerd[1434]: time="2025-09-09T00:31:07.269890541Z" level=info msg="ImageCreate event name:\"sha256:25c7652bd0d893b147dce9135dc6a68c37da76f9a20dceec1d520782031b2f36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:07.272186 containerd[1434]: time="2025-09-09T00:31:07.272147113Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:90aa6b5f4065937521ff8438bc705317485d0be3f8b00a07145e697d92cc2cc6\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:07.272898 containerd[1434]: time="2025-09-09T00:31:07.272862266Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.12\" with image id \"sha256:25c7652bd0d893b147dce9135dc6a68c37da76f9a20dceec1d520782031b2f36\", repo tag \"registry.k8s.io/kube-proxy:v1.31.12\", repo digest \"registry.k8s.io/kube-proxy@sha256:90aa6b5f4065937521ff8438bc705317485d0be3f8b00a07145e697d92cc2cc6\", size \"26915114\" in 1.200793757s" Sep 9 00:31:07.272946 containerd[1434]: time="2025-09-09T00:31:07.272898652Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.12\" returns image reference \"sha256:25c7652bd0d893b147dce9135dc6a68c37da76f9a20dceec1d520782031b2f36\"" Sep 9 00:31:07.274341 containerd[1434]: time="2025-09-09T00:31:07.274313079Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 9 00:31:07.275973 containerd[1434]: time="2025-09-09T00:31:07.275847835Z" level=info msg="CreateContainer within sandbox \"687d84143b9443d540e9830d4b8a09b62ca4f4765f88d4ee9533851c1d04fe9e\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 9 00:31:07.287224 containerd[1434]: time="2025-09-09T00:31:07.287168675Z" level=info msg="CreateContainer within sandbox \"687d84143b9443d540e9830d4b8a09b62ca4f4765f88d4ee9533851c1d04fe9e\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"b2c18c87c8a511d3f65a0c9605faf69f704e76f76c43e1e6ac561a2e7425546a\"" Sep 9 00:31:07.288087 containerd[1434]: time="2025-09-09T00:31:07.288042977Z" level=info msg="StartContainer for \"b2c18c87c8a511d3f65a0c9605faf69f704e76f76c43e1e6ac561a2e7425546a\"" Sep 9 00:31:07.322677 systemd[1]: Started cri-containerd-b2c18c87c8a511d3f65a0c9605faf69f704e76f76c43e1e6ac561a2e7425546a.scope - libcontainer container b2c18c87c8a511d3f65a0c9605faf69f704e76f76c43e1e6ac561a2e7425546a. Sep 9 00:31:07.347581 containerd[1434]: time="2025-09-09T00:31:07.347525586Z" level=info msg="StartContainer for \"b2c18c87c8a511d3f65a0c9605faf69f704e76f76c43e1e6ac561a2e7425546a\" returns successfully" Sep 9 00:31:07.912806 kubelet[1695]: E0909 00:31:07.912765 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:08.046800 kubelet[1695]: E0909 00:31:08.046773 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 9 00:31:08.057288 kubelet[1695]: I0909 00:31:08.057215 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-pt422" podStartSLOduration=2.854676404 podStartE2EDuration="4.057198415s" podCreationTimestamp="2025-09-09 00:31:04 +0000 UTC" firstStartedPulling="2025-09-09 00:31:06.071620548 +0000 UTC m=+2.795799746" lastFinishedPulling="2025-09-09 00:31:07.274142559 +0000 UTC m=+3.998321757" observedRunningTime="2025-09-09 00:31:08.056294657 +0000 UTC m=+4.780473855" watchObservedRunningTime="2025-09-09 00:31:08.057198415 +0000 UTC m=+4.781377613" Sep 9 00:31:08.407927 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2534017027.mount: Deactivated successfully. Sep 9 00:31:08.435949 containerd[1434]: time="2025-09-09T00:31:08.435890391Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:08.437326 containerd[1434]: time="2025-09-09T00:31:08.437292344Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Sep 9 00:31:08.438303 containerd[1434]: time="2025-09-09T00:31:08.438265625Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:08.440872 containerd[1434]: time="2025-09-09T00:31:08.440590972Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:08.441455 containerd[1434]: time="2025-09-09T00:31:08.441428559Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.166979109s" Sep 9 00:31:08.441597 containerd[1434]: time="2025-09-09T00:31:08.441489342Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Sep 9 00:31:08.443800 containerd[1434]: time="2025-09-09T00:31:08.443695478Z" level=info msg="CreateContainer within sandbox \"07df3a1ebcb83c8e8f5ae30cb2b0a2492ccfe6fb252f90f824695aa3e98a79f6\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 9 00:31:08.454507 containerd[1434]: time="2025-09-09T00:31:08.454392870Z" level=info msg="CreateContainer within sandbox \"07df3a1ebcb83c8e8f5ae30cb2b0a2492ccfe6fb252f90f824695aa3e98a79f6\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"8b4bf0b63f16bd09a03376fd64ede68aa551386c2a2988c7ef8e300aa8430b8a\"" Sep 9 00:31:08.455032 containerd[1434]: time="2025-09-09T00:31:08.454993274Z" level=info msg="StartContainer for \"8b4bf0b63f16bd09a03376fd64ede68aa551386c2a2988c7ef8e300aa8430b8a\"" Sep 9 00:31:08.482687 systemd[1]: Started cri-containerd-8b4bf0b63f16bd09a03376fd64ede68aa551386c2a2988c7ef8e300aa8430b8a.scope - libcontainer container 8b4bf0b63f16bd09a03376fd64ede68aa551386c2a2988c7ef8e300aa8430b8a. Sep 9 00:31:08.503060 systemd[1]: cri-containerd-8b4bf0b63f16bd09a03376fd64ede68aa551386c2a2988c7ef8e300aa8430b8a.scope: Deactivated successfully. Sep 9 00:31:08.521189 containerd[1434]: time="2025-09-09T00:31:08.521135206Z" level=info msg="StartContainer for \"8b4bf0b63f16bd09a03376fd64ede68aa551386c2a2988c7ef8e300aa8430b8a\" returns successfully" Sep 9 00:31:08.717804 containerd[1434]: time="2025-09-09T00:31:08.717667438Z" level=info msg="shim disconnected" id=8b4bf0b63f16bd09a03376fd64ede68aa551386c2a2988c7ef8e300aa8430b8a namespace=k8s.io Sep 9 00:31:08.717804 containerd[1434]: time="2025-09-09T00:31:08.717722354Z" level=warning msg="cleaning up after shim disconnected" id=8b4bf0b63f16bd09a03376fd64ede68aa551386c2a2988c7ef8e300aa8430b8a namespace=k8s.io Sep 9 00:31:08.717804 containerd[1434]: time="2025-09-09T00:31:08.717730895Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 9 00:31:08.912976 kubelet[1695]: E0909 00:31:08.912924 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:09.049389 kubelet[1695]: E0909 00:31:09.049083 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 9 00:31:09.049389 kubelet[1695]: E0909 00:31:09.049142 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 9 00:31:09.050195 containerd[1434]: time="2025-09-09T00:31:09.050163205Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 9 00:31:09.351894 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-8b4bf0b63f16bd09a03376fd64ede68aa551386c2a2988c7ef8e300aa8430b8a-rootfs.mount: Deactivated successfully. Sep 9 00:31:09.913272 kubelet[1695]: E0909 00:31:09.913226 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:10.152280 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1057570083.mount: Deactivated successfully. Sep 9 00:31:10.591151 containerd[1434]: time="2025-09-09T00:31:10.591100677Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:10.592249 containerd[1434]: time="2025-09-09T00:31:10.592209640Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Sep 9 00:31:10.593187 containerd[1434]: time="2025-09-09T00:31:10.593139193Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:10.596574 containerd[1434]: time="2025-09-09T00:31:10.596523183Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:10.598245 containerd[1434]: time="2025-09-09T00:31:10.598093308Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 1.547892096s" Sep 9 00:31:10.598245 containerd[1434]: time="2025-09-09T00:31:10.598124574Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Sep 9 00:31:10.599792 containerd[1434]: time="2025-09-09T00:31:10.599765058Z" level=info msg="CreateContainer within sandbox \"07df3a1ebcb83c8e8f5ae30cb2b0a2492ccfe6fb252f90f824695aa3e98a79f6\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 9 00:31:10.607786 containerd[1434]: time="2025-09-09T00:31:10.607687242Z" level=info msg="CreateContainer within sandbox \"07df3a1ebcb83c8e8f5ae30cb2b0a2492ccfe6fb252f90f824695aa3e98a79f6\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"9d4d2f23c72461b4d624864b5b4a33f4dd195d76a92a89757778f8ae73df7f4f\"" Sep 9 00:31:10.608341 containerd[1434]: time="2025-09-09T00:31:10.608315596Z" level=info msg="StartContainer for \"9d4d2f23c72461b4d624864b5b4a33f4dd195d76a92a89757778f8ae73df7f4f\"" Sep 9 00:31:10.635621 systemd[1]: Started cri-containerd-9d4d2f23c72461b4d624864b5b4a33f4dd195d76a92a89757778f8ae73df7f4f.scope - libcontainer container 9d4d2f23c72461b4d624864b5b4a33f4dd195d76a92a89757778f8ae73df7f4f. Sep 9 00:31:10.652505 systemd[1]: cri-containerd-9d4d2f23c72461b4d624864b5b4a33f4dd195d76a92a89757778f8ae73df7f4f.scope: Deactivated successfully. Sep 9 00:31:10.654651 containerd[1434]: time="2025-09-09T00:31:10.654559330Z" level=info msg="StartContainer for \"9d4d2f23c72461b4d624864b5b4a33f4dd195d76a92a89757778f8ae73df7f4f\" returns successfully" Sep 9 00:31:10.667053 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9d4d2f23c72461b4d624864b5b4a33f4dd195d76a92a89757778f8ae73df7f4f-rootfs.mount: Deactivated successfully. Sep 9 00:31:10.694221 kubelet[1695]: I0909 00:31:10.694194 1695 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Sep 9 00:31:10.767019 containerd[1434]: time="2025-09-09T00:31:10.766947715Z" level=info msg="shim disconnected" id=9d4d2f23c72461b4d624864b5b4a33f4dd195d76a92a89757778f8ae73df7f4f namespace=k8s.io Sep 9 00:31:10.767019 containerd[1434]: time="2025-09-09T00:31:10.767013201Z" level=warning msg="cleaning up after shim disconnected" id=9d4d2f23c72461b4d624864b5b4a33f4dd195d76a92a89757778f8ae73df7f4f namespace=k8s.io Sep 9 00:31:10.767019 containerd[1434]: time="2025-09-09T00:31:10.767022186Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 9 00:31:10.913948 kubelet[1695]: E0909 00:31:10.913909 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:11.052573 kubelet[1695]: E0909 00:31:11.052549 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 9 00:31:11.054351 containerd[1434]: time="2025-09-09T00:31:11.054233391Z" level=info msg="CreateContainer within sandbox \"07df3a1ebcb83c8e8f5ae30cb2b0a2492ccfe6fb252f90f824695aa3e98a79f6\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 9 00:31:11.066902 containerd[1434]: time="2025-09-09T00:31:11.066790280Z" level=info msg="CreateContainer within sandbox \"07df3a1ebcb83c8e8f5ae30cb2b0a2492ccfe6fb252f90f824695aa3e98a79f6\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"b010a2f11903f700483551c7b1bdbfb3a3121eb5d865ed3a94bd376ac2e9fdbb\"" Sep 9 00:31:11.067243 containerd[1434]: time="2025-09-09T00:31:11.067223545Z" level=info msg="StartContainer for \"b010a2f11903f700483551c7b1bdbfb3a3121eb5d865ed3a94bd376ac2e9fdbb\"" Sep 9 00:31:11.094641 systemd[1]: Started cri-containerd-b010a2f11903f700483551c7b1bdbfb3a3121eb5d865ed3a94bd376ac2e9fdbb.scope - libcontainer container b010a2f11903f700483551c7b1bdbfb3a3121eb5d865ed3a94bd376ac2e9fdbb. Sep 9 00:31:11.114222 containerd[1434]: time="2025-09-09T00:31:11.114175417Z" level=info msg="StartContainer for \"b010a2f11903f700483551c7b1bdbfb3a3121eb5d865ed3a94bd376ac2e9fdbb\" returns successfully" Sep 9 00:31:11.914670 kubelet[1695]: E0909 00:31:11.914618 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:12.055974 kubelet[1695]: E0909 00:31:12.055920 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 9 00:31:12.165624 systemd-networkd[1383]: flannel.1: Link UP Sep 9 00:31:12.165632 systemd-networkd[1383]: flannel.1: Gained carrier Sep 9 00:31:12.915344 kubelet[1695]: E0909 00:31:12.915297 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:13.057534 kubelet[1695]: E0909 00:31:13.057165 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 9 00:31:13.915484 kubelet[1695]: E0909 00:31:13.915442 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:14.031613 systemd-networkd[1383]: flannel.1: Gained IPv6LL Sep 9 00:31:14.915626 kubelet[1695]: E0909 00:31:14.915577 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:15.323297 kubelet[1695]: I0909 00:31:15.323153 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-nxplx" podStartSLOduration=6.8072989029999995 podStartE2EDuration="11.323133036s" podCreationTimestamp="2025-09-09 00:31:04 +0000 UTC" firstStartedPulling="2025-09-09 00:31:06.082780474 +0000 UTC m=+2.806959632" lastFinishedPulling="2025-09-09 00:31:10.598614567 +0000 UTC m=+7.322793765" observedRunningTime="2025-09-09 00:31:12.066703917 +0000 UTC m=+8.790883235" watchObservedRunningTime="2025-09-09 00:31:15.323133036 +0000 UTC m=+12.047312194" Sep 9 00:31:15.328421 systemd[1]: Created slice kubepods-besteffort-podbe45ca17_180b_4ab4_849b_05a4d106845f.slice - libcontainer container kubepods-besteffort-podbe45ca17_180b_4ab4_849b_05a4d106845f.slice. Sep 9 00:31:15.392765 kubelet[1695]: I0909 00:31:15.392712 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zxhbm\" (UniqueName: \"kubernetes.io/projected/be45ca17-180b-4ab4-849b-05a4d106845f-kube-api-access-zxhbm\") pod \"nginx-deployment-8587fbcb89-hdhdh\" (UID: \"be45ca17-180b-4ab4-849b-05a4d106845f\") " pod="default/nginx-deployment-8587fbcb89-hdhdh" Sep 9 00:31:15.631036 containerd[1434]: time="2025-09-09T00:31:15.630928380Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-hdhdh,Uid:be45ca17-180b-4ab4-849b-05a4d106845f,Namespace:default,Attempt:0,}" Sep 9 00:31:15.650912 systemd-networkd[1383]: cni0: Link UP Sep 9 00:31:15.650919 systemd-networkd[1383]: cni0: Gained carrier Sep 9 00:31:15.652776 systemd-networkd[1383]: cni0: Lost carrier Sep 9 00:31:15.655073 systemd-networkd[1383]: veth86ab591a: Link UP Sep 9 00:31:15.658505 kernel: cni0: port 1(veth86ab591a) entered blocking state Sep 9 00:31:15.658569 kernel: cni0: port 1(veth86ab591a) entered disabled state Sep 9 00:31:15.658587 kernel: veth86ab591a: entered allmulticast mode Sep 9 00:31:15.658626 kernel: veth86ab591a: entered promiscuous mode Sep 9 00:31:15.658643 kernel: cni0: port 1(veth86ab591a) entered blocking state Sep 9 00:31:15.658656 kernel: cni0: port 1(veth86ab591a) entered forwarding state Sep 9 00:31:15.659926 kernel: cni0: port 1(veth86ab591a) entered disabled state Sep 9 00:31:15.666163 systemd-networkd[1383]: veth86ab591a: Gained carrier Sep 9 00:31:15.666366 systemd-networkd[1383]: cni0: Gained carrier Sep 9 00:31:15.666480 kernel: cni0: port 1(veth86ab591a) entered blocking state Sep 9 00:31:15.666542 kernel: cni0: port 1(veth86ab591a) entered forwarding state Sep 9 00:31:15.668919 containerd[1434]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000018938), "name":"cbr0", "type":"bridge"} Sep 9 00:31:15.668919 containerd[1434]: delegateAdd: netconf sent to delegate plugin: Sep 9 00:31:15.686145 containerd[1434]: {"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-09T00:31:15.685675116Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 9 00:31:15.686145 containerd[1434]: time="2025-09-09T00:31:15.686074842Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 9 00:31:15.686145 containerd[1434]: time="2025-09-09T00:31:15.686119443Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:15.686307 containerd[1434]: time="2025-09-09T00:31:15.686219394Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:15.709797 systemd[1]: Started cri-containerd-0af589447acca9906bc2a970be4aedec3eb75c2a2ae4e924721de53463a2b750.scope - libcontainer container 0af589447acca9906bc2a970be4aedec3eb75c2a2ae4e924721de53463a2b750. Sep 9 00:31:15.718369 systemd-resolved[1310]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 00:31:15.734133 containerd[1434]: time="2025-09-09T00:31:15.734081110Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-hdhdh,Uid:be45ca17-180b-4ab4-849b-05a4d106845f,Namespace:default,Attempt:0,} returns sandbox id \"0af589447acca9906bc2a970be4aedec3eb75c2a2ae4e924721de53463a2b750\"" Sep 9 00:31:15.735466 containerd[1434]: time="2025-09-09T00:31:15.735438708Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 9 00:31:15.916100 kubelet[1695]: E0909 00:31:15.916057 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:16.916382 kubelet[1695]: E0909 00:31:16.916341 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:17.296883 systemd-networkd[1383]: cni0: Gained IPv6LL Sep 9 00:31:17.360829 systemd-networkd[1383]: veth86ab591a: Gained IPv6LL Sep 9 00:31:17.585326 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount644298501.mount: Deactivated successfully. Sep 9 00:31:17.916509 kubelet[1695]: E0909 00:31:17.916415 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:18.303435 containerd[1434]: time="2025-09-09T00:31:18.303243459Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:18.304384 containerd[1434]: time="2025-09-09T00:31:18.304181822Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69986522" Sep 9 00:31:18.305597 containerd[1434]: time="2025-09-09T00:31:18.305175033Z" level=info msg="ImageCreate event name:\"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:18.309044 containerd[1434]: time="2025-09-09T00:31:18.308596762Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:18.309692 containerd[1434]: time="2025-09-09T00:31:18.309668287Z" 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.574196925s" Sep 9 00:31:18.309737 containerd[1434]: time="2025-09-09T00:31:18.309699188Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 9 00:31:18.311971 containerd[1434]: time="2025-09-09T00:31:18.311910236Z" level=info msg="CreateContainer within sandbox \"0af589447acca9906bc2a970be4aedec3eb75c2a2ae4e924721de53463a2b750\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 9 00:31:18.325422 containerd[1434]: time="2025-09-09T00:31:18.325383602Z" level=info msg="CreateContainer within sandbox \"0af589447acca9906bc2a970be4aedec3eb75c2a2ae4e924721de53463a2b750\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"f99158ae653a54023711135de05f23215318ba53810cdd83b90f98c78b69e258\"" Sep 9 00:31:18.326021 containerd[1434]: time="2025-09-09T00:31:18.325991161Z" level=info msg="StartContainer for \"f99158ae653a54023711135de05f23215318ba53810cdd83b90f98c78b69e258\"" Sep 9 00:31:18.359653 systemd[1]: Started cri-containerd-f99158ae653a54023711135de05f23215318ba53810cdd83b90f98c78b69e258.scope - libcontainer container f99158ae653a54023711135de05f23215318ba53810cdd83b90f98c78b69e258. Sep 9 00:31:18.383346 containerd[1434]: time="2025-09-09T00:31:18.383236235Z" level=info msg="StartContainer for \"f99158ae653a54023711135de05f23215318ba53810cdd83b90f98c78b69e258\" returns successfully" Sep 9 00:31:18.916828 kubelet[1695]: E0909 00:31:18.916781 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:19.078390 kubelet[1695]: I0909 00:31:19.078326 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-hdhdh" podStartSLOduration=1.502854685 podStartE2EDuration="4.078310506s" podCreationTimestamp="2025-09-09 00:31:15 +0000 UTC" firstStartedPulling="2025-09-09 00:31:15.735200399 +0000 UTC m=+12.459379557" lastFinishedPulling="2025-09-09 00:31:18.31065618 +0000 UTC m=+15.034835378" observedRunningTime="2025-09-09 00:31:19.078203642 +0000 UTC m=+15.802382840" watchObservedRunningTime="2025-09-09 00:31:19.078310506 +0000 UTC m=+15.802489704" Sep 9 00:31:19.917011 kubelet[1695]: E0909 00:31:19.916974 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:20.917285 kubelet[1695]: E0909 00:31:20.917225 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:21.906717 systemd[1]: Created slice kubepods-besteffort-pod096de39e_72db_426a_bf94_97901014ec60.slice - libcontainer container kubepods-besteffort-pod096de39e_72db_426a_bf94_97901014ec60.slice. Sep 9 00:31:21.917454 kubelet[1695]: E0909 00:31:21.917400 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:21.930451 kubelet[1695]: I0909 00:31:21.930386 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/096de39e-72db-426a-bf94-97901014ec60-data\") pod \"nfs-server-provisioner-0\" (UID: \"096de39e-72db-426a-bf94-97901014ec60\") " pod="default/nfs-server-provisioner-0" Sep 9 00:31:21.930451 kubelet[1695]: I0909 00:31:21.930431 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-td77j\" (UniqueName: \"kubernetes.io/projected/096de39e-72db-426a-bf94-97901014ec60-kube-api-access-td77j\") pod \"nfs-server-provisioner-0\" (UID: \"096de39e-72db-426a-bf94-97901014ec60\") " pod="default/nfs-server-provisioner-0" Sep 9 00:31:22.209705 containerd[1434]: time="2025-09-09T00:31:22.209591938Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:096de39e-72db-426a-bf94-97901014ec60,Namespace:default,Attempt:0,}" Sep 9 00:31:22.237498 kernel: cni0: port 2(veth8dd41fc0) entered blocking state Sep 9 00:31:22.237617 kernel: cni0: port 2(veth8dd41fc0) entered disabled state Sep 9 00:31:22.237651 kernel: veth8dd41fc0: entered allmulticast mode Sep 9 00:31:22.237691 kernel: veth8dd41fc0: entered promiscuous mode Sep 9 00:31:22.238342 systemd-networkd[1383]: veth8dd41fc0: Link UP Sep 9 00:31:22.245572 kernel: cni0: port 2(veth8dd41fc0) entered blocking state Sep 9 00:31:22.245639 kernel: cni0: port 2(veth8dd41fc0) entered forwarding state Sep 9 00:31:22.245537 systemd-networkd[1383]: veth8dd41fc0: Gained carrier Sep 9 00:31:22.248495 containerd[1434]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x4000014938), "name":"cbr0", "type":"bridge"} Sep 9 00:31:22.248495 containerd[1434]: delegateAdd: netconf sent to delegate plugin: Sep 9 00:31:22.265307 containerd[1434]: {"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-09T00:31:22.265203321Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 9 00:31:22.265307 containerd[1434]: time="2025-09-09T00:31:22.265289851Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 9 00:31:22.265307 containerd[1434]: time="2025-09-09T00:31:22.265305285Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:22.265465 containerd[1434]: time="2025-09-09T00:31:22.265376820Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:22.288661 systemd[1]: Started cri-containerd-3d8d5ed854bcfe6343228cffe0b3cb1455179dbfcc1ab6b8829862e86fae96ab.scope - libcontainer container 3d8d5ed854bcfe6343228cffe0b3cb1455179dbfcc1ab6b8829862e86fae96ab. Sep 9 00:31:22.297720 systemd-resolved[1310]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 00:31:22.314700 containerd[1434]: time="2025-09-09T00:31:22.314645688Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:096de39e-72db-426a-bf94-97901014ec60,Namespace:default,Attempt:0,} returns sandbox id \"3d8d5ed854bcfe6343228cffe0b3cb1455179dbfcc1ab6b8829862e86fae96ab\"" Sep 9 00:31:22.316407 containerd[1434]: time="2025-09-09T00:31:22.316382844Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 9 00:31:22.917919 kubelet[1695]: E0909 00:31:22.917873 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:23.504933 systemd-networkd[1383]: veth8dd41fc0: Gained IPv6LL Sep 9 00:31:23.910372 kubelet[1695]: E0909 00:31:23.910332 1695 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:23.918776 kubelet[1695]: E0909 00:31:23.918747 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:23.982624 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount793136785.mount: Deactivated successfully. Sep 9 00:31:24.919880 kubelet[1695]: E0909 00:31:24.919844 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:25.371267 containerd[1434]: time="2025-09-09T00:31:25.370665435Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:25.373345 containerd[1434]: time="2025-09-09T00:31:25.372960700Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Sep 9 00:31:25.374774 containerd[1434]: time="2025-09-09T00:31:25.374725609Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:25.378437 containerd[1434]: time="2025-09-09T00:31:25.378368761Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:25.383046 containerd[1434]: time="2025-09-09T00:31:25.382920741Z" 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.066353399s" Sep 9 00:31:25.383046 containerd[1434]: time="2025-09-09T00:31:25.382974688Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 9 00:31:25.387043 containerd[1434]: time="2025-09-09T00:31:25.385837541Z" level=info msg="CreateContainer within sandbox \"3d8d5ed854bcfe6343228cffe0b3cb1455179dbfcc1ab6b8829862e86fae96ab\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 9 00:31:25.399223 containerd[1434]: time="2025-09-09T00:31:25.399187352Z" level=info msg="CreateContainer within sandbox \"3d8d5ed854bcfe6343228cffe0b3cb1455179dbfcc1ab6b8829862e86fae96ab\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"9a465e6d3b0c1cbfcbaa5302700f7968dccaa8eba455a9fbc91ad8f95edd09b6\"" Sep 9 00:31:25.399685 containerd[1434]: time="2025-09-09T00:31:25.399659882Z" level=info msg="StartContainer for \"9a465e6d3b0c1cbfcbaa5302700f7968dccaa8eba455a9fbc91ad8f95edd09b6\"" Sep 9 00:31:25.424742 systemd[1]: Started cri-containerd-9a465e6d3b0c1cbfcbaa5302700f7968dccaa8eba455a9fbc91ad8f95edd09b6.scope - libcontainer container 9a465e6d3b0c1cbfcbaa5302700f7968dccaa8eba455a9fbc91ad8f95edd09b6. Sep 9 00:31:25.451132 containerd[1434]: time="2025-09-09T00:31:25.451038434Z" level=info msg="StartContainer for \"9a465e6d3b0c1cbfcbaa5302700f7968dccaa8eba455a9fbc91ad8f95edd09b6\" returns successfully" Sep 9 00:31:25.922252 kubelet[1695]: E0909 00:31:25.922125 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:26.129389 kubelet[1695]: I0909 00:31:26.129243 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.060483038 podStartE2EDuration="5.129224679s" podCreationTimestamp="2025-09-09 00:31:21 +0000 UTC" firstStartedPulling="2025-09-09 00:31:22.315840633 +0000 UTC m=+19.040019831" lastFinishedPulling="2025-09-09 00:31:25.384582274 +0000 UTC m=+22.108761472" observedRunningTime="2025-09-09 00:31:26.129054194 +0000 UTC m=+22.853233392" watchObservedRunningTime="2025-09-09 00:31:26.129224679 +0000 UTC m=+22.853403877" Sep 9 00:31:26.922582 kubelet[1695]: E0909 00:31:26.922455 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:27.923604 kubelet[1695]: E0909 00:31:27.923557 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:28.924365 kubelet[1695]: E0909 00:31:28.924318 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:29.924859 kubelet[1695]: E0909 00:31:29.924809 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:30.925102 kubelet[1695]: E0909 00:31:30.925053 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:31.925883 kubelet[1695]: E0909 00:31:31.925832 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:32.926626 kubelet[1695]: E0909 00:31:32.926571 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:33.927191 kubelet[1695]: E0909 00:31:33.927163 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:34.927463 kubelet[1695]: E0909 00:31:34.927411 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:35.324509 systemd[1]: Created slice kubepods-besteffort-pod36f3d034_d149_4ee2_9ef3_797f8e431b10.slice - libcontainer container kubepods-besteffort-pod36f3d034_d149_4ee2_9ef3_797f8e431b10.slice. Sep 9 00:31:35.410849 kubelet[1695]: I0909 00:31:35.410791 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-8265d91a-5d2c-4a7e-a9ff-69f1daaabad6\" (UniqueName: \"kubernetes.io/nfs/36f3d034-d149-4ee2-9ef3-797f8e431b10-pvc-8265d91a-5d2c-4a7e-a9ff-69f1daaabad6\") pod \"test-pod-1\" (UID: \"36f3d034-d149-4ee2-9ef3-797f8e431b10\") " pod="default/test-pod-1" Sep 9 00:31:35.410849 kubelet[1695]: I0909 00:31:35.410838 1695 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wvgzz\" (UniqueName: \"kubernetes.io/projected/36f3d034-d149-4ee2-9ef3-797f8e431b10-kube-api-access-wvgzz\") pod \"test-pod-1\" (UID: \"36f3d034-d149-4ee2-9ef3-797f8e431b10\") " pod="default/test-pod-1" Sep 9 00:31:35.544744 kernel: FS-Cache: Loaded Sep 9 00:31:35.573110 kernel: RPC: Registered named UNIX socket transport module. Sep 9 00:31:35.573212 kernel: RPC: Registered udp transport module. Sep 9 00:31:35.573228 kernel: RPC: Registered tcp transport module. Sep 9 00:31:35.573256 kernel: RPC: Registered tcp-with-tls transport module. Sep 9 00:31:35.573271 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 9 00:31:35.755536 kernel: NFS: Registering the id_resolver key type Sep 9 00:31:35.755671 kernel: Key type id_resolver registered Sep 9 00:31:35.755719 kernel: Key type id_legacy registered Sep 9 00:31:35.811788 nfsidmap[2744]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 9 00:31:35.815271 nfsidmap[2747]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 9 00:31:35.928049 kubelet[1695]: E0909 00:31:35.927672 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:35.928384 containerd[1434]: time="2025-09-09T00:31:35.928234247Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:36f3d034-d149-4ee2-9ef3-797f8e431b10,Namespace:default,Attempt:0,}" Sep 9 00:31:35.948085 systemd-networkd[1383]: vethc6a49a45: Link UP Sep 9 00:31:35.950108 kernel: cni0: port 3(vethc6a49a45) entered blocking state Sep 9 00:31:35.950158 kernel: cni0: port 3(vethc6a49a45) entered disabled state Sep 9 00:31:35.950175 kernel: vethc6a49a45: entered allmulticast mode Sep 9 00:31:35.950197 kernel: vethc6a49a45: entered promiscuous mode Sep 9 00:31:35.956586 kernel: cni0: port 3(vethc6a49a45) entered blocking state Sep 9 00:31:35.956632 kernel: cni0: port 3(vethc6a49a45) entered forwarding state Sep 9 00:31:35.956384 systemd-networkd[1383]: vethc6a49a45: Gained carrier Sep 9 00:31:35.958031 containerd[1434]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Sep 9 00:31:35.958031 containerd[1434]: delegateAdd: netconf sent to delegate plugin: Sep 9 00:31:35.978554 containerd[1434]: {"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-09T00:31:35.978305648Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 9 00:31:35.978554 containerd[1434]: time="2025-09-09T00:31:35.978356447Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 9 00:31:35.978554 containerd[1434]: time="2025-09-09T00:31:35.978371006Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:35.978554 containerd[1434]: time="2025-09-09T00:31:35.978452165Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 9 00:31:35.999639 systemd[1]: Started cri-containerd-eb70a5211871b67f6d4617bd45354a7d779c2cccfeca064f1c07c6d7e80db93d.scope - libcontainer container eb70a5211871b67f6d4617bd45354a7d779c2cccfeca064f1c07c6d7e80db93d. Sep 9 00:31:36.009021 systemd-resolved[1310]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 9 00:31:36.025421 containerd[1434]: time="2025-09-09T00:31:36.025386686Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:36f3d034-d149-4ee2-9ef3-797f8e431b10,Namespace:default,Attempt:0,} returns sandbox id \"eb70a5211871b67f6d4617bd45354a7d779c2cccfeca064f1c07c6d7e80db93d\"" Sep 9 00:31:36.027301 containerd[1434]: time="2025-09-09T00:31:36.027232854Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 9 00:31:36.521887 containerd[1434]: time="2025-09-09T00:31:36.521823003Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 9 00:31:36.523547 containerd[1434]: time="2025-09-09T00:31:36.523495894Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 9 00:31:36.528356 containerd[1434]: time="2025-09-09T00:31:36.528261611Z" 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 500.983718ms" Sep 9 00:31:36.528356 containerd[1434]: time="2025-09-09T00:31:36.528298571Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 9 00:31:36.533414 containerd[1434]: time="2025-09-09T00:31:36.533370242Z" level=info msg="CreateContainer within sandbox \"eb70a5211871b67f6d4617bd45354a7d779c2cccfeca064f1c07c6d7e80db93d\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 9 00:31:36.553740 containerd[1434]: time="2025-09-09T00:31:36.553615930Z" level=info msg="CreateContainer within sandbox \"eb70a5211871b67f6d4617bd45354a7d779c2cccfeca064f1c07c6d7e80db93d\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"417d4d163ecdca64d2f0900f8ba1ce2ebc6b8542a24e35c3bf4476d2c6842b6e\"" Sep 9 00:31:36.554414 containerd[1434]: time="2025-09-09T00:31:36.554379836Z" level=info msg="StartContainer for \"417d4d163ecdca64d2f0900f8ba1ce2ebc6b8542a24e35c3bf4476d2c6842b6e\"" Sep 9 00:31:36.588659 systemd[1]: Started cri-containerd-417d4d163ecdca64d2f0900f8ba1ce2ebc6b8542a24e35c3bf4476d2c6842b6e.scope - libcontainer container 417d4d163ecdca64d2f0900f8ba1ce2ebc6b8542a24e35c3bf4476d2c6842b6e. Sep 9 00:31:36.611863 containerd[1434]: time="2025-09-09T00:31:36.611825956Z" level=info msg="StartContainer for \"417d4d163ecdca64d2f0900f8ba1ce2ebc6b8542a24e35c3bf4476d2c6842b6e\" returns successfully" Sep 9 00:31:36.928512 kubelet[1695]: E0909 00:31:36.928446 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:37.118861 kubelet[1695]: I0909 00:31:37.118785 1695 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=14.613672104 podStartE2EDuration="15.118768671s" podCreationTimestamp="2025-09-09 00:31:22 +0000 UTC" firstStartedPulling="2025-09-09 00:31:36.026809581 +0000 UTC m=+32.750988779" lastFinishedPulling="2025-09-09 00:31:36.531906188 +0000 UTC m=+33.256085346" observedRunningTime="2025-09-09 00:31:37.118758631 +0000 UTC m=+33.842937829" watchObservedRunningTime="2025-09-09 00:31:37.118768671 +0000 UTC m=+33.842947869" Sep 9 00:31:37.519678 systemd-networkd[1383]: vethc6a49a45: Gained IPv6LL Sep 9 00:31:37.929001 kubelet[1695]: E0909 00:31:37.928952 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 9 00:31:38.929575 kubelet[1695]: E0909 00:31:38.929522 1695 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"