Sep 10 23:17:12.840558 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 10 23:17:12.840579 kernel: Linux version 6.6.105-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT Wed Sep 10 22:05:18 -00 2025 Sep 10 23:17:12.840588 kernel: KASLR enabled Sep 10 23:17:12.840594 kernel: efi: EFI v2.7 by EDK II Sep 10 23:17:12.840600 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdbbae018 ACPI 2.0=0xd9b43018 RNG=0xd9b43a18 MEMRESERVE=0xd9b40218 Sep 10 23:17:12.840605 kernel: random: crng init done Sep 10 23:17:12.840612 kernel: secureboot: Secure boot disabled Sep 10 23:17:12.840618 kernel: ACPI: Early table checksum verification disabled Sep 10 23:17:12.840624 kernel: ACPI: RSDP 0x00000000D9B43018 000024 (v02 BOCHS ) Sep 10 23:17:12.840631 kernel: ACPI: XSDT 0x00000000D9B43F18 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 10 23:17:12.840637 kernel: ACPI: FACP 0x00000000D9B43B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 10 23:17:12.840643 kernel: ACPI: DSDT 0x00000000D9B41018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 10 23:17:12.840649 kernel: ACPI: APIC 0x00000000D9B43C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 10 23:17:12.840655 kernel: ACPI: PPTT 0x00000000D9B43098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 10 23:17:12.840662 kernel: ACPI: GTDT 0x00000000D9B43818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 10 23:17:12.840669 kernel: ACPI: MCFG 0x00000000D9B43A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 10 23:17:12.840675 kernel: ACPI: SPCR 0x00000000D9B43918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 10 23:17:12.840681 kernel: ACPI: DBG2 0x00000000D9B43998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 10 23:17:12.840687 kernel: ACPI: IORT 0x00000000D9B43198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 10 23:17:12.840693 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 10 23:17:12.840699 kernel: NUMA: Failed to initialise from firmware Sep 10 23:17:12.840706 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 10 23:17:12.840712 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Sep 10 23:17:12.840718 kernel: Zone ranges: Sep 10 23:17:12.840724 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 10 23:17:12.840731 kernel: DMA32 empty Sep 10 23:17:12.840737 kernel: Normal empty Sep 10 23:17:12.840744 kernel: Movable zone start for each node Sep 10 23:17:12.840750 kernel: Early memory node ranges Sep 10 23:17:12.840756 kernel: node 0: [mem 0x0000000040000000-0x00000000d967ffff] Sep 10 23:17:12.840763 kernel: node 0: [mem 0x00000000d9680000-0x00000000d968ffff] Sep 10 23:17:12.840769 kernel: node 0: [mem 0x00000000d9690000-0x00000000d976ffff] Sep 10 23:17:12.840775 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Sep 10 23:17:12.840781 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Sep 10 23:17:12.840799 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 10 23:17:12.840805 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 10 23:17:12.840811 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 10 23:17:12.840827 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 10 23:17:12.840835 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 10 23:17:12.840841 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 10 23:17:12.840851 kernel: psci: probing for conduit method from ACPI. Sep 10 23:17:12.840857 kernel: psci: PSCIv1.1 detected in firmware. Sep 10 23:17:12.840864 kernel: psci: Using standard PSCI v0.2 function IDs Sep 10 23:17:12.840872 kernel: psci: Trusted OS migration not required Sep 10 23:17:12.840878 kernel: psci: SMC Calling Convention v1.1 Sep 10 23:17:12.840885 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 10 23:17:12.840891 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Sep 10 23:17:12.840898 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Sep 10 23:17:12.840905 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 10 23:17:12.840911 kernel: Detected PIPT I-cache on CPU0 Sep 10 23:17:12.840917 kernel: CPU features: detected: GIC system register CPU interface Sep 10 23:17:12.840924 kernel: CPU features: detected: Hardware dirty bit management Sep 10 23:17:12.840931 kernel: CPU features: detected: Spectre-v4 Sep 10 23:17:12.840938 kernel: CPU features: detected: Spectre-BHB Sep 10 23:17:12.840945 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 10 23:17:12.840952 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 10 23:17:12.840958 kernel: CPU features: detected: ARM erratum 1418040 Sep 10 23:17:12.840964 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 10 23:17:12.840971 kernel: alternatives: applying boot alternatives Sep 10 23:17:12.840978 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=812c036cb680f79e5ca620d89a6ff10a489f95d8e789d774dfb3714b0f5aa257 Sep 10 23:17:12.840985 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 10 23:17:12.840992 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 10 23:17:12.840999 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 10 23:17:12.841005 kernel: Fallback order for Node 0: 0 Sep 10 23:17:12.841013 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Sep 10 23:17:12.841019 kernel: Policy zone: DMA Sep 10 23:17:12.841025 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 10 23:17:12.841032 kernel: software IO TLB: area num 4. Sep 10 23:17:12.841038 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Sep 10 23:17:12.841045 kernel: Memory: 2387412K/2572288K available (10368K kernel code, 2186K rwdata, 8104K rodata, 38400K init, 897K bss, 184876K reserved, 0K cma-reserved) Sep 10 23:17:12.841052 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 10 23:17:12.841058 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 10 23:17:12.841065 kernel: rcu: RCU event tracing is enabled. Sep 10 23:17:12.841072 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 10 23:17:12.841078 kernel: Trampoline variant of Tasks RCU enabled. Sep 10 23:17:12.841085 kernel: Tracing variant of Tasks RCU enabled. Sep 10 23:17:12.841093 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 10 23:17:12.841099 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 10 23:17:12.841106 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 10 23:17:12.841112 kernel: GICv3: 256 SPIs implemented Sep 10 23:17:12.841119 kernel: GICv3: 0 Extended SPIs implemented Sep 10 23:17:12.841125 kernel: Root IRQ handler: gic_handle_irq Sep 10 23:17:12.841131 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 10 23:17:12.841146 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 10 23:17:12.841158 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 10 23:17:12.841165 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400c0000 (indirect, esz 8, psz 64K, shr 1) Sep 10 23:17:12.841171 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400d0000 (flat, esz 8, psz 64K, shr 1) Sep 10 23:17:12.841180 kernel: GICv3: using LPI property table @0x00000000400f0000 Sep 10 23:17:12.841186 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Sep 10 23:17:12.841193 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 10 23:17:12.841200 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 10 23:17:12.841206 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 10 23:17:12.841213 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 10 23:17:12.841219 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 10 23:17:12.841226 kernel: arm-pv: using stolen time PV Sep 10 23:17:12.841233 kernel: Console: colour dummy device 80x25 Sep 10 23:17:12.841239 kernel: ACPI: Core revision 20230628 Sep 10 23:17:12.841246 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 10 23:17:12.841254 kernel: pid_max: default: 32768 minimum: 301 Sep 10 23:17:12.841261 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 10 23:17:12.841267 kernel: landlock: Up and running. Sep 10 23:17:12.841274 kernel: SELinux: Initializing. Sep 10 23:17:12.841280 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 10 23:17:12.841287 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 10 23:17:12.841294 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 10 23:17:12.841301 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 10 23:17:12.841307 kernel: rcu: Hierarchical SRCU implementation. Sep 10 23:17:12.841315 kernel: rcu: Max phase no-delay instances is 400. Sep 10 23:17:12.841322 kernel: Platform MSI: ITS@0x8080000 domain created Sep 10 23:17:12.841328 kernel: PCI/MSI: ITS@0x8080000 domain created Sep 10 23:17:12.841335 kernel: Remapping and enabling EFI services. Sep 10 23:17:12.841341 kernel: smp: Bringing up secondary CPUs ... Sep 10 23:17:12.841348 kernel: Detected PIPT I-cache on CPU1 Sep 10 23:17:12.841354 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 10 23:17:12.841361 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Sep 10 23:17:12.841368 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 10 23:17:12.841376 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 10 23:17:12.841382 kernel: Detected PIPT I-cache on CPU2 Sep 10 23:17:12.841393 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 10 23:17:12.841401 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Sep 10 23:17:12.841408 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 10 23:17:12.841415 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 10 23:17:12.841422 kernel: Detected PIPT I-cache on CPU3 Sep 10 23:17:12.841429 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 10 23:17:12.841436 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Sep 10 23:17:12.841445 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 10 23:17:12.841451 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 10 23:17:12.841458 kernel: smp: Brought up 1 node, 4 CPUs Sep 10 23:17:12.841465 kernel: SMP: Total of 4 processors activated. Sep 10 23:17:12.841472 kernel: CPU features: detected: 32-bit EL0 Support Sep 10 23:17:12.841479 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 10 23:17:12.841486 kernel: CPU features: detected: Common not Private translations Sep 10 23:17:12.841493 kernel: CPU features: detected: CRC32 instructions Sep 10 23:17:12.841501 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 10 23:17:12.841508 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 10 23:17:12.841515 kernel: CPU features: detected: LSE atomic instructions Sep 10 23:17:12.841522 kernel: CPU features: detected: Privileged Access Never Sep 10 23:17:12.841529 kernel: CPU features: detected: RAS Extension Support Sep 10 23:17:12.841542 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 10 23:17:12.841549 kernel: CPU: All CPU(s) started at EL1 Sep 10 23:17:12.841556 kernel: alternatives: applying system-wide alternatives Sep 10 23:17:12.841563 kernel: devtmpfs: initialized Sep 10 23:17:12.841570 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 10 23:17:12.841578 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 10 23:17:12.841585 kernel: pinctrl core: initialized pinctrl subsystem Sep 10 23:17:12.841592 kernel: SMBIOS 3.0.0 present. Sep 10 23:17:12.841599 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Sep 10 23:17:12.841606 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 10 23:17:12.841613 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 10 23:17:12.841620 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 10 23:17:12.841627 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 10 23:17:12.841635 kernel: audit: initializing netlink subsys (disabled) Sep 10 23:17:12.841642 kernel: audit: type=2000 audit(0.018:1): state=initialized audit_enabled=0 res=1 Sep 10 23:17:12.841649 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 10 23:17:12.841656 kernel: cpuidle: using governor menu Sep 10 23:17:12.841663 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 10 23:17:12.841670 kernel: ASID allocator initialised with 32768 entries Sep 10 23:17:12.841677 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 10 23:17:12.841684 kernel: Serial: AMBA PL011 UART driver Sep 10 23:17:12.841691 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 10 23:17:12.841698 kernel: Modules: 0 pages in range for non-PLT usage Sep 10 23:17:12.841707 kernel: Modules: 509248 pages in range for PLT usage Sep 10 23:17:12.841717 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 10 23:17:12.841724 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 10 23:17:12.841731 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 10 23:17:12.841738 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 10 23:17:12.841745 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 10 23:17:12.841752 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 10 23:17:12.841759 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 10 23:17:12.841766 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 10 23:17:12.841774 kernel: ACPI: Added _OSI(Module Device) Sep 10 23:17:12.841781 kernel: ACPI: Added _OSI(Processor Device) Sep 10 23:17:12.841788 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 10 23:17:12.841795 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 10 23:17:12.841802 kernel: ACPI: Interpreter enabled Sep 10 23:17:12.841809 kernel: ACPI: Using GIC for interrupt routing Sep 10 23:17:12.841816 kernel: ACPI: MCFG table detected, 1 entries Sep 10 23:17:12.841828 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 10 23:17:12.841835 kernel: printk: console [ttyAMA0] enabled Sep 10 23:17:12.841844 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 10 23:17:12.841983 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 10 23:17:12.842058 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 10 23:17:12.842130 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 10 23:17:12.842234 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 10 23:17:12.842303 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 10 23:17:12.842313 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 10 23:17:12.842323 kernel: PCI host bridge to bus 0000:00 Sep 10 23:17:12.842397 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 10 23:17:12.842457 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 10 23:17:12.842514 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 10 23:17:12.842571 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 10 23:17:12.842647 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Sep 10 23:17:12.842729 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Sep 10 23:17:12.842797 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Sep 10 23:17:12.842976 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Sep 10 23:17:12.843050 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Sep 10 23:17:12.843117 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Sep 10 23:17:12.843224 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Sep 10 23:17:12.843291 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Sep 10 23:17:12.843356 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 10 23:17:12.843414 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 10 23:17:12.843475 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 10 23:17:12.843484 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 10 23:17:12.843491 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 10 23:17:12.843499 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 10 23:17:12.843506 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 10 23:17:12.843513 kernel: iommu: Default domain type: Translated Sep 10 23:17:12.843522 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 10 23:17:12.843529 kernel: efivars: Registered efivars operations Sep 10 23:17:12.843536 kernel: vgaarb: loaded Sep 10 23:17:12.843543 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 10 23:17:12.843550 kernel: VFS: Disk quotas dquot_6.6.0 Sep 10 23:17:12.843557 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 10 23:17:12.843564 kernel: pnp: PnP ACPI init Sep 10 23:17:12.843636 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 10 23:17:12.843646 kernel: pnp: PnP ACPI: found 1 devices Sep 10 23:17:12.843655 kernel: NET: Registered PF_INET protocol family Sep 10 23:17:12.843663 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 10 23:17:12.843670 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 10 23:17:12.843677 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 10 23:17:12.843684 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 10 23:17:12.843691 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 10 23:17:12.843698 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 10 23:17:12.843705 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 10 23:17:12.843712 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 10 23:17:12.843721 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 10 23:17:12.843728 kernel: PCI: CLS 0 bytes, default 64 Sep 10 23:17:12.843735 kernel: kvm [1]: HYP mode not available Sep 10 23:17:12.843741 kernel: Initialise system trusted keyrings Sep 10 23:17:12.843748 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 10 23:17:12.843755 kernel: Key type asymmetric registered Sep 10 23:17:12.843762 kernel: Asymmetric key parser 'x509' registered Sep 10 23:17:12.843769 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 10 23:17:12.843776 kernel: io scheduler mq-deadline registered Sep 10 23:17:12.843784 kernel: io scheduler kyber registered Sep 10 23:17:12.843791 kernel: io scheduler bfq registered Sep 10 23:17:12.843798 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 10 23:17:12.843805 kernel: ACPI: button: Power Button [PWRB] Sep 10 23:17:12.843813 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 10 23:17:12.843891 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 10 23:17:12.843902 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 10 23:17:12.843909 kernel: thunder_xcv, ver 1.0 Sep 10 23:17:12.843916 kernel: thunder_bgx, ver 1.0 Sep 10 23:17:12.843925 kernel: nicpf, ver 1.0 Sep 10 23:17:12.843932 kernel: nicvf, ver 1.0 Sep 10 23:17:12.844009 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 10 23:17:12.844072 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-10T23:17:12 UTC (1757546232) Sep 10 23:17:12.844081 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 10 23:17:12.844088 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Sep 10 23:17:12.844096 kernel: watchdog: Delayed init of the lockup detector failed: -19 Sep 10 23:17:12.844103 kernel: watchdog: Hard watchdog permanently disabled Sep 10 23:17:12.844111 kernel: NET: Registered PF_INET6 protocol family Sep 10 23:17:12.844118 kernel: Segment Routing with IPv6 Sep 10 23:17:12.844125 kernel: In-situ OAM (IOAM) with IPv6 Sep 10 23:17:12.844132 kernel: NET: Registered PF_PACKET protocol family Sep 10 23:17:12.844149 kernel: Key type dns_resolver registered Sep 10 23:17:12.844156 kernel: registered taskstats version 1 Sep 10 23:17:12.844163 kernel: Loading compiled-in X.509 certificates Sep 10 23:17:12.844170 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.105-flatcar: d7b4405ae069a339ad721bbd0dc0977a88602ca7' Sep 10 23:17:12.844177 kernel: Key type .fscrypt registered Sep 10 23:17:12.844185 kernel: Key type fscrypt-provisioning registered Sep 10 23:17:12.844193 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 10 23:17:12.844200 kernel: ima: Allocated hash algorithm: sha1 Sep 10 23:17:12.844207 kernel: ima: No architecture policies found Sep 10 23:17:12.844214 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 10 23:17:12.844221 kernel: clk: Disabling unused clocks Sep 10 23:17:12.844228 kernel: Freeing unused kernel memory: 38400K Sep 10 23:17:12.844235 kernel: Run /init as init process Sep 10 23:17:12.844242 kernel: with arguments: Sep 10 23:17:12.844250 kernel: /init Sep 10 23:17:12.844257 kernel: with environment: Sep 10 23:17:12.844263 kernel: HOME=/ Sep 10 23:17:12.844270 kernel: TERM=linux Sep 10 23:17:12.844277 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 10 23:17:12.844285 systemd[1]: Successfully made /usr/ read-only. Sep 10 23:17:12.844295 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 10 23:17:12.844304 systemd[1]: Detected virtualization kvm. Sep 10 23:17:12.844312 systemd[1]: Detected architecture arm64. Sep 10 23:17:12.844319 systemd[1]: Running in initrd. Sep 10 23:17:12.844326 systemd[1]: No hostname configured, using default hostname. Sep 10 23:17:12.844334 systemd[1]: Hostname set to . Sep 10 23:17:12.844341 systemd[1]: Initializing machine ID from VM UUID. Sep 10 23:17:12.844349 systemd[1]: Queued start job for default target initrd.target. Sep 10 23:17:12.844357 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 10 23:17:12.844364 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 10 23:17:12.844374 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 10 23:17:12.844382 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 10 23:17:12.844389 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 10 23:17:12.844398 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 10 23:17:12.844407 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 10 23:17:12.844414 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 10 23:17:12.844423 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 10 23:17:12.844431 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 10 23:17:12.844439 systemd[1]: Reached target paths.target - Path Units. Sep 10 23:17:12.844446 systemd[1]: Reached target slices.target - Slice Units. Sep 10 23:17:12.844454 systemd[1]: Reached target swap.target - Swaps. Sep 10 23:17:12.844461 systemd[1]: Reached target timers.target - Timer Units. Sep 10 23:17:12.844469 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 10 23:17:12.844477 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 10 23:17:12.844484 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 10 23:17:12.844494 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 10 23:17:12.844501 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 10 23:17:12.844509 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 10 23:17:12.844517 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 10 23:17:12.844524 systemd[1]: Reached target sockets.target - Socket Units. Sep 10 23:17:12.844532 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 10 23:17:12.844540 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 10 23:17:12.844547 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 10 23:17:12.844556 systemd[1]: Starting systemd-fsck-usr.service... Sep 10 23:17:12.844564 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 10 23:17:12.844571 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 10 23:17:12.844579 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:17:12.844586 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 10 23:17:12.844594 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 10 23:17:12.844603 systemd[1]: Finished systemd-fsck-usr.service. Sep 10 23:17:12.844627 systemd-journald[240]: Collecting audit messages is disabled. Sep 10 23:17:12.844645 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 10 23:17:12.844654 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 10 23:17:12.844662 kernel: Bridge firewalling registered Sep 10 23:17:12.844669 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:17:12.844677 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 10 23:17:12.844685 systemd-journald[240]: Journal started Sep 10 23:17:12.844703 systemd-journald[240]: Runtime Journal (/run/log/journal/cba86f2bf6d742a6b25041fac8202b27) is 5.9M, max 47.3M, 41.4M free. Sep 10 23:17:12.829223 systemd-modules-load[241]: Inserted module 'overlay' Sep 10 23:17:12.842644 systemd-modules-load[241]: Inserted module 'br_netfilter' Sep 10 23:17:12.849163 systemd[1]: Started systemd-journald.service - Journal Service. Sep 10 23:17:12.849420 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 10 23:17:12.853723 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 10 23:17:12.855268 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 10 23:17:12.857128 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 10 23:17:12.859131 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 10 23:17:12.865097 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 10 23:17:12.867401 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 10 23:17:12.869890 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 10 23:17:12.879284 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 10 23:17:12.880215 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 10 23:17:12.882428 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 10 23:17:12.894463 dracut-cmdline[283]: dracut-dracut-053 Sep 10 23:17:12.896765 dracut-cmdline[283]: 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=812c036cb680f79e5ca620d89a6ff10a489f95d8e789d774dfb3714b0f5aa257 Sep 10 23:17:12.904857 systemd-resolved[275]: Positive Trust Anchors: Sep 10 23:17:12.904874 systemd-resolved[275]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 10 23:17:12.904906 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 10 23:17:12.909440 systemd-resolved[275]: Defaulting to hostname 'linux'. Sep 10 23:17:12.910896 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 10 23:17:12.912219 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 10 23:17:12.965147 kernel: SCSI subsystem initialized Sep 10 23:17:12.968159 kernel: Loading iSCSI transport class v2.0-870. Sep 10 23:17:12.975183 kernel: iscsi: registered transport (tcp) Sep 10 23:17:12.988184 kernel: iscsi: registered transport (qla4xxx) Sep 10 23:17:12.988255 kernel: QLogic iSCSI HBA Driver Sep 10 23:17:13.029681 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 10 23:17:13.042280 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 10 23:17:13.058035 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 10 23:17:13.058082 kernel: device-mapper: uevent: version 1.0.3 Sep 10 23:17:13.058093 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 10 23:17:13.103162 kernel: raid6: neonx8 gen() 15682 MB/s Sep 10 23:17:13.120150 kernel: raid6: neonx4 gen() 15706 MB/s Sep 10 23:17:13.137148 kernel: raid6: neonx2 gen() 13163 MB/s Sep 10 23:17:13.154148 kernel: raid6: neonx1 gen() 10432 MB/s Sep 10 23:17:13.171147 kernel: raid6: int64x8 gen() 6735 MB/s Sep 10 23:17:13.188146 kernel: raid6: int64x4 gen() 7309 MB/s Sep 10 23:17:13.205152 kernel: raid6: int64x2 gen() 6064 MB/s Sep 10 23:17:13.222146 kernel: raid6: int64x1 gen() 5000 MB/s Sep 10 23:17:13.222159 kernel: raid6: using algorithm neonx4 gen() 15706 MB/s Sep 10 23:17:13.239145 kernel: raid6: .... xor() 12356 MB/s, rmw enabled Sep 10 23:17:13.239164 kernel: raid6: using neon recovery algorithm Sep 10 23:17:13.244171 kernel: xor: measuring software checksum speed Sep 10 23:17:13.244191 kernel: 8regs : 21596 MB/sec Sep 10 23:17:13.245192 kernel: 32regs : 21687 MB/sec Sep 10 23:17:13.245205 kernel: arm64_neon : 27268 MB/sec Sep 10 23:17:13.245214 kernel: xor: using function: arm64_neon (27268 MB/sec) Sep 10 23:17:13.293163 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 10 23:17:13.303921 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 10 23:17:13.320313 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 10 23:17:13.333739 systemd-udevd[464]: Using default interface naming scheme 'v255'. Sep 10 23:17:13.337411 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 10 23:17:13.347301 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 10 23:17:13.358480 dracut-pre-trigger[469]: rd.md=0: removing MD RAID activation Sep 10 23:17:13.385961 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 10 23:17:13.395298 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 10 23:17:13.437437 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 10 23:17:13.443322 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 10 23:17:13.457285 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 10 23:17:13.458567 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 10 23:17:13.459845 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 10 23:17:13.461451 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 10 23:17:13.470305 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 10 23:17:13.480257 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 10 23:17:13.489155 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 10 23:17:13.494966 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 10 23:17:13.497505 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 10 23:17:13.497535 kernel: GPT:9289727 != 19775487 Sep 10 23:17:13.497545 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 10 23:17:13.498281 kernel: GPT:9289727 != 19775487 Sep 10 23:17:13.498312 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 10 23:17:13.499164 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 10 23:17:13.505044 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 10 23:17:13.505122 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 10 23:17:13.512116 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 10 23:17:13.512998 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 10 23:17:13.513057 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:17:13.516056 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:17:13.522305 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:17:13.527183 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by (udev-worker) (509) Sep 10 23:17:13.537152 kernel: BTRFS: device fsid fd58f7db-5430-4b8c-ae33-665ce7287c74 devid 1 transid 35 /dev/vda3 scanned by (udev-worker) (513) Sep 10 23:17:13.539221 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:17:13.552535 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 10 23:17:13.560114 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 10 23:17:13.567323 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 10 23:17:13.573272 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 10 23:17:13.574161 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 10 23:17:13.586289 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 10 23:17:13.588293 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 10 23:17:13.595816 disk-uuid[555]: Primary Header is updated. Sep 10 23:17:13.595816 disk-uuid[555]: Secondary Entries is updated. Sep 10 23:17:13.595816 disk-uuid[555]: Secondary Header is updated. Sep 10 23:17:13.602185 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 10 23:17:13.614871 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 10 23:17:14.609180 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 10 23:17:14.609849 disk-uuid[556]: The operation has completed successfully. Sep 10 23:17:14.654474 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 10 23:17:14.654570 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 10 23:17:14.681276 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 10 23:17:14.683904 sh[575]: Success Sep 10 23:17:14.694191 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Sep 10 23:17:14.738943 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 10 23:17:14.750019 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 10 23:17:14.750917 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 10 23:17:14.763362 kernel: BTRFS info (device dm-0): first mount of filesystem fd58f7db-5430-4b8c-ae33-665ce7287c74 Sep 10 23:17:14.763397 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 10 23:17:14.763408 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 10 23:17:14.764185 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 10 23:17:14.765219 kernel: BTRFS info (device dm-0): using free space tree Sep 10 23:17:14.771849 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 10 23:17:14.772695 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 10 23:17:14.786320 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 10 23:17:14.787866 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 10 23:17:14.805439 kernel: BTRFS info (device vda6): first mount of filesystem 42af1272-c999-4ec5-9130-292f2318261d Sep 10 23:17:14.805493 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 10 23:17:14.807055 kernel: BTRFS info (device vda6): using free space tree Sep 10 23:17:14.813181 kernel: BTRFS info (device vda6): auto enabling async discard Sep 10 23:17:14.819334 kernel: BTRFS info (device vda6): last unmount of filesystem 42af1272-c999-4ec5-9130-292f2318261d Sep 10 23:17:14.823744 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 10 23:17:14.830298 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 10 23:17:14.890366 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 10 23:17:14.897614 ignition[675]: Ignition 2.20.0 Sep 10 23:17:14.897624 ignition[675]: Stage: fetch-offline Sep 10 23:17:14.904702 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 10 23:17:14.897655 ignition[675]: no configs at "/usr/lib/ignition/base.d" Sep 10 23:17:14.897664 ignition[675]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 10 23:17:14.897831 ignition[675]: parsed url from cmdline: "" Sep 10 23:17:14.897835 ignition[675]: no config URL provided Sep 10 23:17:14.897840 ignition[675]: reading system config file "/usr/lib/ignition/user.ign" Sep 10 23:17:14.897847 ignition[675]: no config at "/usr/lib/ignition/user.ign" Sep 10 23:17:14.897869 ignition[675]: op(1): [started] loading QEMU firmware config module Sep 10 23:17:14.897874 ignition[675]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 10 23:17:14.905772 ignition[675]: op(1): [finished] loading QEMU firmware config module Sep 10 23:17:14.915869 ignition[675]: parsing config with SHA512: 03b3d02440d7268d7edda3255db6c7ed16d8d4f1c9747ef525bd1581180046a221c724067a325f0ae874339f672f58dc02de3b2b995f34cc1b59631f5c1b8313 Sep 10 23:17:14.919129 unknown[675]: fetched base config from "system" Sep 10 23:17:14.919184 unknown[675]: fetched user config from "qemu" Sep 10 23:17:14.919446 ignition[675]: fetch-offline: fetch-offline passed Sep 10 23:17:14.919514 ignition[675]: Ignition finished successfully Sep 10 23:17:14.921387 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 10 23:17:14.930720 systemd-networkd[763]: lo: Link UP Sep 10 23:17:14.930732 systemd-networkd[763]: lo: Gained carrier Sep 10 23:17:14.931709 systemd-networkd[763]: Enumeration completed Sep 10 23:17:14.931803 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 10 23:17:14.932118 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 10 23:17:14.932121 systemd-networkd[763]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 10 23:17:14.932690 systemd-networkd[763]: eth0: Link UP Sep 10 23:17:14.932693 systemd-networkd[763]: eth0: Gained carrier Sep 10 23:17:14.932699 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 10 23:17:14.933113 systemd[1]: Reached target network.target - Network. Sep 10 23:17:14.934321 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 10 23:17:14.946308 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 10 23:17:14.951193 systemd-networkd[763]: eth0: DHCPv4 address 10.0.0.15/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 10 23:17:14.959712 ignition[768]: Ignition 2.20.0 Sep 10 23:17:14.959722 ignition[768]: Stage: kargs Sep 10 23:17:14.959908 ignition[768]: no configs at "/usr/lib/ignition/base.d" Sep 10 23:17:14.959918 ignition[768]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 10 23:17:14.960579 ignition[768]: kargs: kargs passed Sep 10 23:17:14.960619 ignition[768]: Ignition finished successfully Sep 10 23:17:14.964895 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 10 23:17:14.972302 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 10 23:17:14.981862 ignition[778]: Ignition 2.20.0 Sep 10 23:17:14.981871 ignition[778]: Stage: disks Sep 10 23:17:14.982038 ignition[778]: no configs at "/usr/lib/ignition/base.d" Sep 10 23:17:14.984129 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 10 23:17:14.982051 ignition[778]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 10 23:17:14.985278 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 10 23:17:14.982753 ignition[778]: disks: disks passed Sep 10 23:17:14.986634 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 10 23:17:14.982795 ignition[778]: Ignition finished successfully Sep 10 23:17:14.988167 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 10 23:17:14.989722 systemd[1]: Reached target sysinit.target - System Initialization. Sep 10 23:17:14.991131 systemd[1]: Reached target basic.target - Basic System. Sep 10 23:17:15.000269 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 10 23:17:15.015149 systemd-fsck[789]: ROOT: clean, 14/553520 files, 52654/553472 blocks Sep 10 23:17:15.019546 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 10 23:17:15.028248 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 10 23:17:15.071160 kernel: EXT4-fs (vda9): mounted filesystem a23ff18d-cc1e-4b34-900c-13c0a3e995c4 r/w with ordered data mode. Quota mode: none. Sep 10 23:17:15.071226 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 10 23:17:15.072263 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 10 23:17:15.083211 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 10 23:17:15.085382 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 10 23:17:15.086201 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 10 23:17:15.086241 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 10 23:17:15.086262 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 10 23:17:15.091378 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 10 23:17:15.093121 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 10 23:17:15.097287 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by mount (797) Sep 10 23:17:15.099818 kernel: BTRFS info (device vda6): first mount of filesystem 42af1272-c999-4ec5-9130-292f2318261d Sep 10 23:17:15.099854 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 10 23:17:15.099866 kernel: BTRFS info (device vda6): using free space tree Sep 10 23:17:15.102180 kernel: BTRFS info (device vda6): auto enabling async discard Sep 10 23:17:15.102781 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 10 23:17:15.125878 initrd-setup-root[821]: cut: /sysroot/etc/passwd: No such file or directory Sep 10 23:17:15.130052 initrd-setup-root[828]: cut: /sysroot/etc/group: No such file or directory Sep 10 23:17:15.136689 initrd-setup-root[835]: cut: /sysroot/etc/shadow: No such file or directory Sep 10 23:17:15.140666 initrd-setup-root[842]: cut: /sysroot/etc/gshadow: No such file or directory Sep 10 23:17:15.217733 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 10 23:17:15.228277 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 10 23:17:15.229614 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 10 23:17:15.234159 kernel: BTRFS info (device vda6): last unmount of filesystem 42af1272-c999-4ec5-9130-292f2318261d Sep 10 23:17:15.252051 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 10 23:17:15.255325 ignition[910]: INFO : Ignition 2.20.0 Sep 10 23:17:15.255325 ignition[910]: INFO : Stage: mount Sep 10 23:17:15.256577 ignition[910]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 10 23:17:15.256577 ignition[910]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 10 23:17:15.256577 ignition[910]: INFO : mount: mount passed Sep 10 23:17:15.256577 ignition[910]: INFO : Ignition finished successfully Sep 10 23:17:15.258381 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 10 23:17:15.270301 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 10 23:17:15.907384 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 10 23:17:15.917297 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 10 23:17:15.925740 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (924) Sep 10 23:17:15.925784 kernel: BTRFS info (device vda6): first mount of filesystem 42af1272-c999-4ec5-9130-292f2318261d Sep 10 23:17:15.925795 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 10 23:17:15.927144 kernel: BTRFS info (device vda6): using free space tree Sep 10 23:17:15.929148 kernel: BTRFS info (device vda6): auto enabling async discard Sep 10 23:17:15.930069 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 10 23:17:15.947747 ignition[941]: INFO : Ignition 2.20.0 Sep 10 23:17:15.947747 ignition[941]: INFO : Stage: files Sep 10 23:17:15.949240 ignition[941]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 10 23:17:15.949240 ignition[941]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 10 23:17:15.949240 ignition[941]: DEBUG : files: compiled without relabeling support, skipping Sep 10 23:17:15.952249 ignition[941]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 10 23:17:15.952249 ignition[941]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 10 23:17:15.954566 ignition[941]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 10 23:17:15.955671 ignition[941]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 10 23:17:15.955671 ignition[941]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 10 23:17:15.955107 unknown[941]: wrote ssh authorized keys file for user: core Sep 10 23:17:15.958853 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 10 23:17:15.958853 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 10 23:17:15.958853 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 10 23:17:15.958853 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 10 23:17:15.958853 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 10 23:17:15.958853 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 10 23:17:15.958853 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 10 23:17:15.958853 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Sep 10 23:17:16.323301 systemd-networkd[763]: eth0: Gained IPv6LL Sep 10 23:17:16.416051 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 10 23:17:16.776900 ignition[941]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 10 23:17:16.776900 ignition[941]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 10 23:17:16.779775 ignition[941]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 10 23:17:16.779775 ignition[941]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 10 23:17:16.779775 ignition[941]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 10 23:17:16.779775 ignition[941]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 10 23:17:16.792758 ignition[941]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 10 23:17:16.796229 ignition[941]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 10 23:17:16.798172 ignition[941]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 10 23:17:16.798172 ignition[941]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 10 23:17:16.798172 ignition[941]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 10 23:17:16.798172 ignition[941]: INFO : files: files passed Sep 10 23:17:16.798172 ignition[941]: INFO : Ignition finished successfully Sep 10 23:17:16.799556 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 10 23:17:16.806307 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 10 23:17:16.808188 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 10 23:17:16.810752 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 10 23:17:16.810879 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 10 23:17:16.814339 initrd-setup-root-after-ignition[971]: grep: /sysroot/oem/oem-release: No such file or directory Sep 10 23:17:16.817311 initrd-setup-root-after-ignition[973]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 10 23:17:16.817311 initrd-setup-root-after-ignition[973]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 10 23:17:16.819779 initrd-setup-root-after-ignition[977]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 10 23:17:16.820249 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 10 23:17:16.822338 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 10 23:17:16.839283 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 10 23:17:16.858109 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 10 23:17:16.858254 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 10 23:17:16.860235 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 10 23:17:16.861372 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 10 23:17:16.862807 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 10 23:17:16.863662 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 10 23:17:16.878095 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 10 23:17:16.890306 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 10 23:17:16.898999 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 10 23:17:16.900030 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 10 23:17:16.901690 systemd[1]: Stopped target timers.target - Timer Units. Sep 10 23:17:16.903014 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 10 23:17:16.903167 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 10 23:17:16.905126 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 10 23:17:16.906828 systemd[1]: Stopped target basic.target - Basic System. Sep 10 23:17:16.908066 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 10 23:17:16.909479 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 10 23:17:16.911074 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 10 23:17:16.912805 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 10 23:17:16.914267 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 10 23:17:16.915846 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 10 23:17:16.917380 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 10 23:17:16.918715 systemd[1]: Stopped target swap.target - Swaps. Sep 10 23:17:16.919855 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 10 23:17:16.919980 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 10 23:17:16.921822 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 10 23:17:16.923325 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 10 23:17:16.924792 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 10 23:17:16.928198 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 10 23:17:16.929126 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 10 23:17:16.929259 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 10 23:17:16.931571 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 10 23:17:16.931679 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 10 23:17:16.933121 systemd[1]: Stopped target paths.target - Path Units. Sep 10 23:17:16.934359 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 10 23:17:16.935234 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 10 23:17:16.936641 systemd[1]: Stopped target slices.target - Slice Units. Sep 10 23:17:16.937882 systemd[1]: Stopped target sockets.target - Socket Units. Sep 10 23:17:16.939546 systemd[1]: iscsid.socket: Deactivated successfully. Sep 10 23:17:16.939629 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 10 23:17:16.940817 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 10 23:17:16.940901 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 10 23:17:16.942061 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 10 23:17:16.942178 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 10 23:17:16.943466 systemd[1]: ignition-files.service: Deactivated successfully. Sep 10 23:17:16.943562 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 10 23:17:16.956306 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 10 23:17:16.956981 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 10 23:17:16.957093 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 10 23:17:16.962356 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 10 23:17:16.963006 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 10 23:17:16.963121 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 10 23:17:16.964823 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 10 23:17:16.967644 ignition[997]: INFO : Ignition 2.20.0 Sep 10 23:17:16.967644 ignition[997]: INFO : Stage: umount Sep 10 23:17:16.967644 ignition[997]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 10 23:17:16.967644 ignition[997]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 10 23:17:16.964926 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 10 23:17:16.972123 ignition[997]: INFO : umount: umount passed Sep 10 23:17:16.972123 ignition[997]: INFO : Ignition finished successfully Sep 10 23:17:16.969460 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 10 23:17:16.969542 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 10 23:17:16.971994 systemd[1]: Stopped target network.target - Network. Sep 10 23:17:16.973012 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 10 23:17:16.973072 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 10 23:17:16.975025 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 10 23:17:16.975075 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 10 23:17:16.976423 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 10 23:17:16.976465 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 10 23:17:16.978622 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 10 23:17:16.978669 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 10 23:17:16.989270 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 10 23:17:16.991837 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 10 23:17:16.995583 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 10 23:17:16.996177 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 10 23:17:16.996261 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 10 23:17:16.998867 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 10 23:17:16.998948 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 10 23:17:17.003021 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 10 23:17:17.003312 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 10 23:17:17.003400 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 10 23:17:17.006296 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 10 23:17:17.007524 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 10 23:17:17.007565 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 10 23:17:17.018285 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 10 23:17:17.018954 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 10 23:17:17.019008 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 10 23:17:17.020790 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 10 23:17:17.020848 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 10 23:17:17.024786 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 10 23:17:17.024843 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 10 23:17:17.028870 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 10 23:17:17.028921 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 10 23:17:17.031223 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 10 23:17:17.035759 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 10 23:17:17.035830 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 10 23:17:17.036152 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 10 23:17:17.036234 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 10 23:17:17.043806 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 10 23:17:17.043907 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 10 23:17:17.046953 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 10 23:17:17.047071 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 10 23:17:17.048777 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 10 23:17:17.048913 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 10 23:17:17.050764 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 10 23:17:17.050840 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 10 23:17:17.052494 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 10 23:17:17.052524 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 10 23:17:17.054588 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 10 23:17:17.054634 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 10 23:17:17.056787 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 10 23:17:17.056844 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 10 23:17:17.058821 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 10 23:17:17.058865 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 10 23:17:17.078346 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 10 23:17:17.079133 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 10 23:17:17.079208 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 10 23:17:17.081752 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 10 23:17:17.081794 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:17:17.084761 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 10 23:17:17.084822 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 10 23:17:17.085115 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 10 23:17:17.085228 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 10 23:17:17.087317 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 10 23:17:17.089530 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 10 23:17:17.098320 systemd[1]: Switching root. Sep 10 23:17:17.121147 systemd-journald[240]: Received SIGTERM from PID 1 (systemd). Sep 10 23:17:17.121194 systemd-journald[240]: Journal stopped Sep 10 23:17:17.828592 kernel: SELinux: policy capability network_peer_controls=1 Sep 10 23:17:17.828649 kernel: SELinux: policy capability open_perms=1 Sep 10 23:17:17.828660 kernel: SELinux: policy capability extended_socket_class=1 Sep 10 23:17:17.828672 kernel: SELinux: policy capability always_check_network=0 Sep 10 23:17:17.828682 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 10 23:17:17.828691 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 10 23:17:17.828701 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 10 23:17:17.828710 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 10 23:17:17.828720 kernel: audit: type=1403 audit(1757546237.245:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 10 23:17:17.828737 systemd[1]: Successfully loaded SELinux policy in 29.672ms. Sep 10 23:17:17.828758 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 9.768ms. Sep 10 23:17:17.828769 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 10 23:17:17.828782 systemd[1]: Detected virtualization kvm. Sep 10 23:17:17.828793 systemd[1]: Detected architecture arm64. Sep 10 23:17:17.828804 systemd[1]: Detected first boot. Sep 10 23:17:17.828825 systemd[1]: Initializing machine ID from VM UUID. Sep 10 23:17:17.828838 zram_generator::config[1045]: No configuration found. Sep 10 23:17:17.828851 kernel: NET: Registered PF_VSOCK protocol family Sep 10 23:17:17.828861 systemd[1]: Populated /etc with preset unit settings. Sep 10 23:17:17.828871 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 10 23:17:17.828882 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 10 23:17:17.828892 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 10 23:17:17.828902 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 10 23:17:17.828912 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 10 23:17:17.828923 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 10 23:17:17.828933 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 10 23:17:17.828945 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 10 23:17:17.828955 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 10 23:17:17.828965 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 10 23:17:17.828976 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 10 23:17:17.828988 systemd[1]: Created slice user.slice - User and Session Slice. Sep 10 23:17:17.828998 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 10 23:17:17.829009 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 10 23:17:17.829019 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 10 23:17:17.829031 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 10 23:17:17.829041 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 10 23:17:17.829051 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 10 23:17:17.829062 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 10 23:17:17.829072 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 10 23:17:17.829083 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 10 23:17:17.829093 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 10 23:17:17.829103 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 10 23:17:17.829115 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 10 23:17:17.829125 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 10 23:17:17.829195 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 10 23:17:17.829209 systemd[1]: Reached target slices.target - Slice Units. Sep 10 23:17:17.829219 systemd[1]: Reached target swap.target - Swaps. Sep 10 23:17:17.829230 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 10 23:17:17.829240 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 10 23:17:17.829250 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 10 23:17:17.829261 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 10 23:17:17.829275 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 10 23:17:17.829286 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 10 23:17:17.829298 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 10 23:17:17.829308 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 10 23:17:17.829319 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 10 23:17:17.829329 systemd[1]: Mounting media.mount - External Media Directory... Sep 10 23:17:17.829339 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 10 23:17:17.829350 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 10 23:17:17.829360 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 10 23:17:17.829373 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 10 23:17:17.829384 systemd[1]: Reached target machines.target - Containers. Sep 10 23:17:17.829394 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 10 23:17:17.829405 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 10 23:17:17.829415 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 10 23:17:17.829425 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 10 23:17:17.829436 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 10 23:17:17.829446 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 10 23:17:17.829457 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 10 23:17:17.829468 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 10 23:17:17.829478 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 10 23:17:17.829489 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 10 23:17:17.829499 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 10 23:17:17.829509 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 10 23:17:17.829524 kernel: fuse: init (API version 7.39) Sep 10 23:17:17.829534 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 10 23:17:17.829545 systemd[1]: Stopped systemd-fsck-usr.service. Sep 10 23:17:17.829557 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 10 23:17:17.829568 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 10 23:17:17.829578 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 10 23:17:17.829589 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 10 23:17:17.829598 kernel: ACPI: bus type drm_connector registered Sep 10 23:17:17.829607 kernel: loop: module loaded Sep 10 23:17:17.829617 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 10 23:17:17.829627 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 10 23:17:17.829639 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 10 23:17:17.829650 systemd[1]: verity-setup.service: Deactivated successfully. Sep 10 23:17:17.829660 systemd[1]: Stopped verity-setup.service. Sep 10 23:17:17.829670 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 10 23:17:17.829680 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 10 23:17:17.829713 systemd-journald[1114]: Collecting audit messages is disabled. Sep 10 23:17:17.829737 systemd[1]: Mounted media.mount - External Media Directory. Sep 10 23:17:17.829748 systemd-journald[1114]: Journal started Sep 10 23:17:17.829768 systemd-journald[1114]: Runtime Journal (/run/log/journal/cba86f2bf6d742a6b25041fac8202b27) is 5.9M, max 47.3M, 41.4M free. Sep 10 23:17:17.630710 systemd[1]: Queued start job for default target multi-user.target. Sep 10 23:17:17.643073 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 10 23:17:17.643470 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 10 23:17:17.831365 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 10 23:17:17.833150 systemd[1]: Started systemd-journald.service - Journal Service. Sep 10 23:17:17.833454 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 10 23:17:17.834378 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 10 23:17:17.835375 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 10 23:17:17.836972 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 10 23:17:17.838529 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 10 23:17:17.838789 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 10 23:17:17.840177 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 10 23:17:17.840438 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 10 23:17:17.841635 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 10 23:17:17.841892 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 10 23:17:17.843174 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 10 23:17:17.843333 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 10 23:17:17.844558 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 10 23:17:17.844728 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 10 23:17:17.845945 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 10 23:17:17.846101 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 10 23:17:17.847331 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 10 23:17:17.848448 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 10 23:17:17.849713 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 10 23:17:17.851106 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 10 23:17:17.863254 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 10 23:17:17.873254 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 10 23:17:17.875162 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 10 23:17:17.876031 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 10 23:17:17.876068 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 10 23:17:17.877836 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 10 23:17:17.879832 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 10 23:17:17.881944 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 10 23:17:17.882909 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 10 23:17:17.884243 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 10 23:17:17.885906 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 10 23:17:17.887197 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 10 23:17:17.890316 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 10 23:17:17.892190 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 10 23:17:17.893338 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 10 23:17:17.896477 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 10 23:17:17.896790 systemd-journald[1114]: Time spent on flushing to /var/log/journal/cba86f2bf6d742a6b25041fac8202b27 is 20.361ms for 850 entries. Sep 10 23:17:17.896790 systemd-journald[1114]: System Journal (/var/log/journal/cba86f2bf6d742a6b25041fac8202b27) is 8M, max 195.6M, 187.6M free. Sep 10 23:17:17.926480 systemd-journald[1114]: Received client request to flush runtime journal. Sep 10 23:17:17.926524 kernel: loop0: detected capacity change from 0 to 113512 Sep 10 23:17:17.901354 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 10 23:17:17.904286 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 10 23:17:17.906552 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 10 23:17:17.907572 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 10 23:17:17.908941 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 10 23:17:17.912232 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 10 23:17:17.917091 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 10 23:17:17.925368 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 10 23:17:17.929417 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 10 23:17:17.930950 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 10 23:17:17.932735 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 10 23:17:17.944163 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 10 23:17:17.949682 udevadm[1175]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Sep 10 23:17:17.953378 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 10 23:17:17.959036 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 10 23:17:17.967419 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 10 23:17:17.978371 kernel: loop1: detected capacity change from 0 to 211168 Sep 10 23:17:17.995758 systemd-tmpfiles[1183]: ACLs are not supported, ignoring. Sep 10 23:17:17.995778 systemd-tmpfiles[1183]: ACLs are not supported, ignoring. Sep 10 23:17:18.000018 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 10 23:17:18.026236 kernel: loop2: detected capacity change from 0 to 123192 Sep 10 23:17:18.074254 kernel: loop3: detected capacity change from 0 to 113512 Sep 10 23:17:18.081272 kernel: loop4: detected capacity change from 0 to 211168 Sep 10 23:17:18.088164 kernel: loop5: detected capacity change from 0 to 123192 Sep 10 23:17:18.092098 (sd-merge)[1188]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 10 23:17:18.092548 (sd-merge)[1188]: Merged extensions into '/usr'. Sep 10 23:17:18.101680 systemd[1]: Reload requested from client PID 1163 ('systemd-sysext') (unit systemd-sysext.service)... Sep 10 23:17:18.101704 systemd[1]: Reloading... Sep 10 23:17:18.181175 zram_generator::config[1225]: No configuration found. Sep 10 23:17:18.183357 ldconfig[1158]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 10 23:17:18.261537 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 10 23:17:18.310406 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 10 23:17:18.311093 systemd[1]: Reloading finished in 208 ms. Sep 10 23:17:18.331718 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 10 23:17:18.336421 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 10 23:17:18.348352 systemd[1]: Starting ensure-sysext.service... Sep 10 23:17:18.350099 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 10 23:17:18.359214 systemd[1]: Reload requested from client PID 1250 ('systemctl') (unit ensure-sysext.service)... Sep 10 23:17:18.359343 systemd[1]: Reloading... Sep 10 23:17:18.367633 systemd-tmpfiles[1251]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 10 23:17:18.367871 systemd-tmpfiles[1251]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 10 23:17:18.368541 systemd-tmpfiles[1251]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 10 23:17:18.368762 systemd-tmpfiles[1251]: ACLs are not supported, ignoring. Sep 10 23:17:18.368826 systemd-tmpfiles[1251]: ACLs are not supported, ignoring. Sep 10 23:17:18.371360 systemd-tmpfiles[1251]: Detected autofs mount point /boot during canonicalization of boot. Sep 10 23:17:18.371373 systemd-tmpfiles[1251]: Skipping /boot Sep 10 23:17:18.380296 systemd-tmpfiles[1251]: Detected autofs mount point /boot during canonicalization of boot. Sep 10 23:17:18.380312 systemd-tmpfiles[1251]: Skipping /boot Sep 10 23:17:18.414237 zram_generator::config[1281]: No configuration found. Sep 10 23:17:18.493165 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 10 23:17:18.542705 systemd[1]: Reloading finished in 183 ms. Sep 10 23:17:18.556289 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 10 23:17:18.573202 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 10 23:17:18.581717 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 10 23:17:18.584130 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 10 23:17:18.585051 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 10 23:17:18.586155 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 10 23:17:18.590426 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 10 23:17:18.594536 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 10 23:17:18.595496 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 10 23:17:18.595614 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 10 23:17:18.597950 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 10 23:17:18.601412 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 10 23:17:18.606672 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 10 23:17:18.609468 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 10 23:17:18.613494 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 10 23:17:18.613707 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 10 23:17:18.615164 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 10 23:17:18.615331 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 10 23:17:18.616768 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 10 23:17:18.616932 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 10 23:17:18.625031 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 10 23:17:18.629101 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 10 23:17:18.631207 augenrules[1348]: No rules Sep 10 23:17:18.634388 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 10 23:17:18.636604 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 10 23:17:18.638225 systemd-udevd[1336]: Using default interface naming scheme 'v255'. Sep 10 23:17:18.639585 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 10 23:17:18.640526 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 10 23:17:18.640670 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 10 23:17:18.641947 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 10 23:17:18.648743 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 10 23:17:18.652457 systemd[1]: audit-rules.service: Deactivated successfully. Sep 10 23:17:18.652715 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 10 23:17:18.654094 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 10 23:17:18.655959 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 10 23:17:18.657660 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 10 23:17:18.657834 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 10 23:17:18.659440 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 10 23:17:18.659607 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 10 23:17:18.661132 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 10 23:17:18.661333 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 10 23:17:18.662770 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 10 23:17:18.669526 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 10 23:17:18.677425 systemd[1]: Finished ensure-sysext.service. Sep 10 23:17:18.686310 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 10 23:17:18.687063 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 10 23:17:18.688439 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 10 23:17:18.691523 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 10 23:17:18.695093 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 10 23:17:18.699205 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 10 23:17:18.700050 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 10 23:17:18.700096 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 10 23:17:18.701632 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 10 23:17:18.703844 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 10 23:17:18.704925 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 10 23:17:18.705248 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 10 23:17:18.706541 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 10 23:17:18.707073 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 10 23:17:18.708562 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 10 23:17:18.708722 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 10 23:17:18.710364 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 10 23:17:18.710520 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 10 23:17:18.719689 augenrules[1385]: /sbin/augenrules: No change Sep 10 23:17:18.720623 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 10 23:17:18.720687 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 10 23:17:18.721777 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 10 23:17:18.722216 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 10 23:17:18.735206 augenrules[1418]: No rules Sep 10 23:17:18.769161 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 35 scanned by (udev-worker) (1379) Sep 10 23:17:18.772603 systemd-networkd[1403]: lo: Link UP Sep 10 23:17:18.772617 systemd-networkd[1403]: lo: Gained carrier Sep 10 23:17:18.773364 systemd-networkd[1403]: Enumeration completed Sep 10 23:17:18.782789 systemd-resolved[1329]: Positive Trust Anchors: Sep 10 23:17:18.783101 systemd-resolved[1329]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 10 23:17:18.783190 systemd-resolved[1329]: 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 10 23:17:18.790710 systemd-resolved[1329]: Defaulting to hostname 'linux'. Sep 10 23:17:18.792319 systemd-networkd[1403]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 10 23:17:18.792331 systemd-networkd[1403]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 10 23:17:18.792773 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 10 23:17:18.792876 systemd-networkd[1403]: eth0: Link UP Sep 10 23:17:18.792886 systemd-networkd[1403]: eth0: Gained carrier Sep 10 23:17:18.792900 systemd-networkd[1403]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 10 23:17:18.794222 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 10 23:17:18.795508 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 10 23:17:18.796874 systemd[1]: audit-rules.service: Deactivated successfully. Sep 10 23:17:18.797194 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 10 23:17:18.800724 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 10 23:17:18.807225 systemd-networkd[1403]: eth0: DHCPv4 address 10.0.0.15/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 10 23:17:18.808000 systemd-timesyncd[1404]: Network configuration changed, trying to establish connection. Sep 10 23:17:18.809106 systemd-timesyncd[1404]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 10 23:17:18.810663 systemd-timesyncd[1404]: Initial clock synchronization to Wed 2025-09-10 23:17:18.592529 UTC. Sep 10 23:17:18.815239 systemd[1]: Reached target network.target - Network. Sep 10 23:17:18.816054 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 10 23:17:18.817109 systemd[1]: Reached target time-set.target - System Time Set. Sep 10 23:17:18.824294 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 10 23:17:18.826320 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 10 23:17:18.830086 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 10 23:17:18.835690 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 10 23:17:18.847028 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 10 23:17:18.848825 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 10 23:17:18.887297 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 10 23:17:18.891291 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 10 23:17:18.894981 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 10 23:17:18.906461 lvm[1443]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 10 23:17:18.921249 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 10 23:17:18.943661 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 10 23:17:18.944866 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 10 23:17:18.945798 systemd[1]: Reached target sysinit.target - System Initialization. Sep 10 23:17:18.946682 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 10 23:17:18.947624 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 10 23:17:18.948705 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 10 23:17:18.949614 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 10 23:17:18.950550 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 10 23:17:18.951443 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 10 23:17:18.951473 systemd[1]: Reached target paths.target - Path Units. Sep 10 23:17:18.952098 systemd[1]: Reached target timers.target - Timer Units. Sep 10 23:17:18.953614 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 10 23:17:18.955691 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 10 23:17:18.958742 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 10 23:17:18.959948 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 10 23:17:18.960973 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 10 23:17:18.965022 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 10 23:17:18.966297 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 10 23:17:18.968184 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 10 23:17:18.969509 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 10 23:17:18.970389 systemd[1]: Reached target sockets.target - Socket Units. Sep 10 23:17:18.971082 systemd[1]: Reached target basic.target - Basic System. Sep 10 23:17:18.971874 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 10 23:17:18.971902 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 10 23:17:18.972793 systemd[1]: Starting containerd.service - containerd container runtime... Sep 10 23:17:18.974545 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 10 23:17:18.975699 lvm[1451]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 10 23:17:18.976285 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 10 23:17:18.982577 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 10 23:17:18.983456 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 10 23:17:18.984630 jq[1454]: false Sep 10 23:17:18.985021 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 10 23:17:18.987003 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 10 23:17:18.990271 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 10 23:17:18.994395 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 10 23:17:18.996083 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 10 23:17:18.996581 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 10 23:17:18.997441 systemd[1]: Starting update-engine.service - Update Engine... Sep 10 23:17:18.999836 extend-filesystems[1455]: Found loop3 Sep 10 23:17:18.999836 extend-filesystems[1455]: Found loop4 Sep 10 23:17:18.999836 extend-filesystems[1455]: Found loop5 Sep 10 23:17:18.999836 extend-filesystems[1455]: Found vda Sep 10 23:17:18.999836 extend-filesystems[1455]: Found vda1 Sep 10 23:17:18.999836 extend-filesystems[1455]: Found vda2 Sep 10 23:17:18.999836 extend-filesystems[1455]: Found vda3 Sep 10 23:17:18.999836 extend-filesystems[1455]: Found usr Sep 10 23:17:18.999836 extend-filesystems[1455]: Found vda4 Sep 10 23:17:18.999836 extend-filesystems[1455]: Found vda6 Sep 10 23:17:18.999836 extend-filesystems[1455]: Found vda7 Sep 10 23:17:18.999836 extend-filesystems[1455]: Found vda9 Sep 10 23:17:18.999836 extend-filesystems[1455]: Checking size of /dev/vda9 Sep 10 23:17:18.999873 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 10 23:17:19.007618 dbus-daemon[1453]: [system] SELinux support is enabled Sep 10 23:17:19.005240 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 10 23:17:19.008311 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 10 23:17:19.008825 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 10 23:17:19.017497 jq[1464]: true Sep 10 23:17:19.008960 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 10 23:17:19.012663 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 10 23:17:19.014170 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 10 23:17:19.024032 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 10 23:17:19.024079 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 10 23:17:19.025173 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 10 23:17:19.025196 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 10 23:17:19.028520 (ntainerd)[1478]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 10 23:17:19.029038 jq[1474]: true Sep 10 23:17:19.031190 systemd[1]: motdgen.service: Deactivated successfully. Sep 10 23:17:19.031308 update_engine[1461]: I20250910 23:17:19.030957 1461 main.cc:92] Flatcar Update Engine starting Sep 10 23:17:19.031368 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 10 23:17:19.035339 systemd[1]: Started update-engine.service - Update Engine. Sep 10 23:17:19.037322 update_engine[1461]: I20250910 23:17:19.037176 1461 update_check_scheduler.cc:74] Next update check in 9m43s Sep 10 23:17:19.040236 extend-filesystems[1455]: Resized partition /dev/vda9 Sep 10 23:17:19.046754 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 10 23:17:19.047596 extend-filesystems[1488]: resize2fs 1.47.1 (20-May-2024) Sep 10 23:17:19.053729 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 10 23:17:19.071158 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 35 scanned by (udev-worker) (1376) Sep 10 23:17:19.073804 systemd-logind[1460]: Watching system buttons on /dev/input/event0 (Power Button) Sep 10 23:17:19.076131 systemd-logind[1460]: New seat seat0. Sep 10 23:17:19.078499 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 10 23:17:19.081025 systemd[1]: Started systemd-logind.service - User Login Management. Sep 10 23:17:19.093572 extend-filesystems[1488]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 10 23:17:19.093572 extend-filesystems[1488]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 10 23:17:19.093572 extend-filesystems[1488]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 10 23:17:19.100898 extend-filesystems[1455]: Resized filesystem in /dev/vda9 Sep 10 23:17:19.094417 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 10 23:17:19.094621 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 10 23:17:19.105776 bash[1503]: Updated "/home/core/.ssh/authorized_keys" Sep 10 23:17:19.110738 locksmithd[1487]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 10 23:17:19.111224 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 10 23:17:19.114446 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 10 23:17:19.175167 sshd_keygen[1470]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 10 23:17:19.194239 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 10 23:17:19.197594 containerd[1478]: time="2025-09-10T23:17:19.197506378Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Sep 10 23:17:19.206420 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 10 23:17:19.211839 systemd[1]: issuegen.service: Deactivated successfully. Sep 10 23:17:19.212087 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 10 23:17:19.215023 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 10 23:17:19.220995 containerd[1478]: time="2025-09-10T23:17:19.220944632Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 10 23:17:19.222270 containerd[1478]: time="2025-09-10T23:17:19.222235495Z" 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.105-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222330221Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222351587Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222512006Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222529091Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222581863Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222593461Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222797117Z" 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 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222813618Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222827862Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222836035Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.222915778Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 10 23:17:19.223494 containerd[1478]: time="2025-09-10T23:17:19.223092892Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 10 23:17:19.223755 containerd[1478]: time="2025-09-10T23:17:19.223226497Z" 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 10 23:17:19.223755 containerd[1478]: time="2025-09-10T23:17:19.223240352Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 10 23:17:19.223755 containerd[1478]: time="2025-09-10T23:17:19.223317214Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 10 23:17:19.223755 containerd[1478]: time="2025-09-10T23:17:19.223356560Z" level=info msg="metadata content store policy set" policy=shared Sep 10 23:17:19.226382 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 10 23:17:19.235538 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 10 23:17:19.237640 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 10 23:17:19.238672 systemd[1]: Reached target getty.target - Login Prompts. Sep 10 23:17:19.256569 containerd[1478]: time="2025-09-10T23:17:19.256493352Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 10 23:17:19.256569 containerd[1478]: time="2025-09-10T23:17:19.256554764Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 10 23:17:19.256569 containerd[1478]: time="2025-09-10T23:17:19.256570214Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 10 23:17:19.256770 containerd[1478]: time="2025-09-10T23:17:19.256585548Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 10 23:17:19.256770 containerd[1478]: time="2025-09-10T23:17:19.256608704Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 10 23:17:19.256807 containerd[1478]: time="2025-09-10T23:17:19.256773637Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 10 23:17:19.257801 containerd[1478]: time="2025-09-10T23:17:19.257730158Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258024454Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258052436Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258068626Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258083064Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258095985Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258109100Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258122371Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258151365Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258165064Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258178023Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258188842Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 10 23:17:19.258206 containerd[1478]: time="2025-09-10T23:17:19.258207484Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258220911Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258232236Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258244106Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258255081Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258268040Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258278781Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258291352Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258303689Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258317894Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258328246Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258339999Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258351558Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258365412Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258384754Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258414 containerd[1478]: time="2025-09-10T23:17:19.258396702Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258663 containerd[1478]: time="2025-09-10T23:17:19.258411063Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 10 23:17:19.258663 containerd[1478]: time="2025-09-10T23:17:19.258589656Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 10 23:17:19.258663 containerd[1478]: time="2025-09-10T23:17:19.258609582Z" 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 10 23:17:19.258663 containerd[1478]: time="2025-09-10T23:17:19.258633633Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 10 23:17:19.258663 containerd[1478]: time="2025-09-10T23:17:19.258646982Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 10 23:17:19.258663 containerd[1478]: time="2025-09-10T23:17:19.258656128Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.258763 containerd[1478]: time="2025-09-10T23:17:19.258671967Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 10 23:17:19.258763 containerd[1478]: time="2025-09-10T23:17:19.258681697Z" level=info msg="NRI interface is disabled by configuration." Sep 10 23:17:19.258763 containerd[1478]: time="2025-09-10T23:17:19.258691232Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 10 23:17:19.259069 containerd[1478]: time="2025-09-10T23:17:19.259024173Z" 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 10 23:17:19.259192 containerd[1478]: time="2025-09-10T23:17:19.259073443Z" level=info msg="Connect containerd service" Sep 10 23:17:19.259192 containerd[1478]: time="2025-09-10T23:17:19.259104266Z" level=info msg="using legacy CRI server" Sep 10 23:17:19.259192 containerd[1478]: time="2025-09-10T23:17:19.259120300Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 10 23:17:19.259367 containerd[1478]: time="2025-09-10T23:17:19.259351627Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 10 23:17:19.260001 containerd[1478]: time="2025-09-10T23:17:19.259975790Z" 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 10 23:17:19.260291 containerd[1478]: time="2025-09-10T23:17:19.260224085Z" level=info msg="Start subscribing containerd event" Sep 10 23:17:19.260291 containerd[1478]: time="2025-09-10T23:17:19.260276546Z" level=info msg="Start recovering state" Sep 10 23:17:19.260422 containerd[1478]: time="2025-09-10T23:17:19.260400616Z" level=info msg="Start event monitor" Sep 10 23:17:19.260546 containerd[1478]: time="2025-09-10T23:17:19.260473353Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 10 23:17:19.260546 containerd[1478]: time="2025-09-10T23:17:19.260483667Z" level=info msg="Start snapshots syncer" Sep 10 23:17:19.260546 containerd[1478]: time="2025-09-10T23:17:19.260510909Z" level=info msg="Start cni network conf syncer for default" Sep 10 23:17:19.260546 containerd[1478]: time="2025-09-10T23:17:19.260515774Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 10 23:17:19.260631 containerd[1478]: time="2025-09-10T23:17:19.260518965Z" level=info msg="Start streaming server" Sep 10 23:17:19.260723 containerd[1478]: time="2025-09-10T23:17:19.260707483Z" level=info msg="containerd successfully booted in 0.065411s" Sep 10 23:17:19.260799 systemd[1]: Started containerd.service - containerd container runtime. Sep 10 23:17:20.035322 systemd-networkd[1403]: eth0: Gained IPv6LL Sep 10 23:17:20.037580 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 10 23:17:20.038963 systemd[1]: Reached target network-online.target - Network is Online. Sep 10 23:17:20.055381 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 10 23:17:20.057532 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:17:20.059257 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 10 23:17:20.071561 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 10 23:17:20.073185 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 10 23:17:20.074425 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 10 23:17:20.078684 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 10 23:17:20.598047 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:17:20.599384 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 10 23:17:20.602912 (kubelet)[1560]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 10 23:17:20.604857 systemd[1]: Startup finished in 508ms (kernel) + 4.574s (initrd) + 3.389s (userspace) = 8.472s. Sep 10 23:17:20.959993 kubelet[1560]: E0910 23:17:20.959942 1560 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 10 23:17:20.963106 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 10 23:17:20.964101 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 10 23:17:20.965251 systemd[1]: kubelet.service: Consumed 751ms CPU time, 258.4M memory peak. Sep 10 23:17:25.265402 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 10 23:17:25.266452 systemd[1]: Started sshd@0-10.0.0.15:22-10.0.0.1:47988.service - OpenSSH per-connection server daemon (10.0.0.1:47988). Sep 10 23:17:25.322121 sshd[1573]: Accepted publickey for core from 10.0.0.1 port 47988 ssh2: RSA SHA256:vI2v+Kj925DhJN+VWmdLDSx5Cqw/fvuZ8IHXlsQiGm4 Sep 10 23:17:25.323973 sshd-session[1573]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:17:25.333700 systemd-logind[1460]: New session 1 of user core. Sep 10 23:17:25.334900 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 10 23:17:25.344405 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 10 23:17:25.353203 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 10 23:17:25.355175 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 10 23:17:25.361382 (systemd)[1577]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 10 23:17:25.363314 systemd-logind[1460]: New session c1 of user core. Sep 10 23:17:25.455667 systemd[1577]: Queued start job for default target default.target. Sep 10 23:17:25.466994 systemd[1577]: Created slice app.slice - User Application Slice. Sep 10 23:17:25.467020 systemd[1577]: Reached target paths.target - Paths. Sep 10 23:17:25.467054 systemd[1577]: Reached target timers.target - Timers. Sep 10 23:17:25.468213 systemd[1577]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 10 23:17:25.477577 systemd[1577]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 10 23:17:25.477632 systemd[1577]: Reached target sockets.target - Sockets. Sep 10 23:17:25.477667 systemd[1577]: Reached target basic.target - Basic System. Sep 10 23:17:25.477693 systemd[1577]: Reached target default.target - Main User Target. Sep 10 23:17:25.477716 systemd[1577]: Startup finished in 109ms. Sep 10 23:17:25.477989 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 10 23:17:25.479619 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 10 23:17:25.543840 systemd[1]: Started sshd@1-10.0.0.15:22-10.0.0.1:47998.service - OpenSSH per-connection server daemon (10.0.0.1:47998). Sep 10 23:17:25.583102 sshd[1588]: Accepted publickey for core from 10.0.0.1 port 47998 ssh2: RSA SHA256:vI2v+Kj925DhJN+VWmdLDSx5Cqw/fvuZ8IHXlsQiGm4 Sep 10 23:17:25.584397 sshd-session[1588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:17:25.588542 systemd-logind[1460]: New session 2 of user core. Sep 10 23:17:25.596303 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 10 23:17:25.647494 sshd[1590]: Connection closed by 10.0.0.1 port 47998 Sep 10 23:17:25.647964 sshd-session[1588]: pam_unix(sshd:session): session closed for user core Sep 10 23:17:25.659091 systemd[1]: sshd@1-10.0.0.15:22-10.0.0.1:47998.service: Deactivated successfully. Sep 10 23:17:25.660531 systemd[1]: session-2.scope: Deactivated successfully. Sep 10 23:17:25.661708 systemd-logind[1460]: Session 2 logged out. Waiting for processes to exit. Sep 10 23:17:25.662851 systemd[1]: Started sshd@2-10.0.0.15:22-10.0.0.1:48010.service - OpenSSH per-connection server daemon (10.0.0.1:48010). Sep 10 23:17:25.663530 systemd-logind[1460]: Removed session 2. Sep 10 23:17:25.704862 sshd[1595]: Accepted publickey for core from 10.0.0.1 port 48010 ssh2: RSA SHA256:vI2v+Kj925DhJN+VWmdLDSx5Cqw/fvuZ8IHXlsQiGm4 Sep 10 23:17:25.705343 sshd-session[1595]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:17:25.709223 systemd-logind[1460]: New session 3 of user core. Sep 10 23:17:25.724290 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 10 23:17:25.809843 sshd[1598]: Connection closed by 10.0.0.1 port 48010 Sep 10 23:17:25.810857 sshd-session[1595]: pam_unix(sshd:session): session closed for user core Sep 10 23:17:25.820365 systemd[1]: sshd@2-10.0.0.15:22-10.0.0.1:48010.service: Deactivated successfully. Sep 10 23:17:25.821719 systemd[1]: session-3.scope: Deactivated successfully. Sep 10 23:17:25.822402 systemd-logind[1460]: Session 3 logged out. Waiting for processes to exit. Sep 10 23:17:25.823956 systemd[1]: Started sshd@3-10.0.0.15:22-10.0.0.1:48024.service - OpenSSH per-connection server daemon (10.0.0.1:48024). Sep 10 23:17:25.824661 systemd-logind[1460]: Removed session 3. Sep 10 23:17:25.863675 sshd[1603]: Accepted publickey for core from 10.0.0.1 port 48024 ssh2: RSA SHA256:vI2v+Kj925DhJN+VWmdLDSx5Cqw/fvuZ8IHXlsQiGm4 Sep 10 23:17:25.864859 sshd-session[1603]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:17:25.869594 systemd-logind[1460]: New session 4 of user core. Sep 10 23:17:25.878272 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 10 23:17:25.936062 sshd[1606]: Connection closed by 10.0.0.1 port 48024 Sep 10 23:17:25.937560 sshd-session[1603]: pam_unix(sshd:session): session closed for user core Sep 10 23:17:25.956433 systemd[1]: sshd@3-10.0.0.15:22-10.0.0.1:48024.service: Deactivated successfully. Sep 10 23:17:25.957822 systemd[1]: session-4.scope: Deactivated successfully. Sep 10 23:17:25.959077 systemd-logind[1460]: Session 4 logged out. Waiting for processes to exit. Sep 10 23:17:25.970427 systemd[1]: Started sshd@4-10.0.0.15:22-10.0.0.1:48032.service - OpenSSH per-connection server daemon (10.0.0.1:48032). Sep 10 23:17:25.971436 systemd-logind[1460]: Removed session 4. Sep 10 23:17:26.006611 sshd[1611]: Accepted publickey for core from 10.0.0.1 port 48032 ssh2: RSA SHA256:vI2v+Kj925DhJN+VWmdLDSx5Cqw/fvuZ8IHXlsQiGm4 Sep 10 23:17:26.008297 sshd-session[1611]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 10 23:17:26.012197 systemd-logind[1460]: New session 5 of user core. Sep 10 23:17:26.023363 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 10 23:17:26.081795 sudo[1615]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 10 23:17:26.082083 sudo[1615]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 10 23:17:26.110495 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 10 23:17:26.126304 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 10 23:17:26.128188 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 10 23:17:26.525095 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:17:26.525253 systemd[1]: kubelet.service: Consumed 751ms CPU time, 258.4M memory peak. Sep 10 23:17:26.536384 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:17:26.559593 systemd[1]: Reload requested from client PID 1659 ('systemctl') (unit session-5.scope)... Sep 10 23:17:26.559745 systemd[1]: Reloading... Sep 10 23:17:26.627321 zram_generator::config[1701]: No configuration found. Sep 10 23:17:26.829453 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 10 23:17:26.901019 systemd[1]: Reloading finished in 340 ms. Sep 10 23:17:26.939346 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:17:26.941392 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:17:26.943417 systemd[1]: kubelet.service: Deactivated successfully. Sep 10 23:17:26.943627 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:17:26.943665 systemd[1]: kubelet.service: Consumed 92ms CPU time, 95.1M memory peak. Sep 10 23:17:26.945085 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 10 23:17:27.051313 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 10 23:17:27.055565 (kubelet)[1749]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 10 23:17:27.090621 kubelet[1749]: 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 10 23:17:27.090621 kubelet[1749]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Sep 10 23:17:27.090621 kubelet[1749]: 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 10 23:17:27.090621 kubelet[1749]: I0910 23:17:27.090589 1749 server.go:212] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 10 23:17:27.739843 kubelet[1749]: I0910 23:17:27.739797 1749 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Sep 10 23:17:27.739843 kubelet[1749]: I0910 23:17:27.739830 1749 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 10 23:17:27.740066 kubelet[1749]: I0910 23:17:27.740041 1749 server.go:956] "Client rotation is on, will bootstrap in background" Sep 10 23:17:27.759494 kubelet[1749]: I0910 23:17:27.759319 1749 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 10 23:17:27.769230 kubelet[1749]: E0910 23:17:27.769168 1749 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Sep 10 23:17:27.769230 kubelet[1749]: I0910 23:17:27.769219 1749 server.go:1423] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Sep 10 23:17:27.772111 kubelet[1749]: I0910 23:17:27.772087 1749 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 10 23:17:27.773144 kubelet[1749]: I0910 23:17:27.773087 1749 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 10 23:17:27.773328 kubelet[1749]: I0910 23:17:27.773147 1749 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.15","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Sep 10 23:17:27.773438 kubelet[1749]: I0910 23:17:27.773379 1749 topology_manager.go:138] "Creating topology manager with none policy" Sep 10 23:17:27.773438 kubelet[1749]: I0910 23:17:27.773438 1749 container_manager_linux.go:303] "Creating device plugin manager" Sep 10 23:17:27.773643 kubelet[1749]: I0910 23:17:27.773629 1749 state_mem.go:36] "Initialized new in-memory state store" Sep 10 23:17:27.777288 kubelet[1749]: I0910 23:17:27.777024 1749 kubelet.go:480] "Attempting to sync node with API server" Sep 10 23:17:27.777288 kubelet[1749]: I0910 23:17:27.777054 1749 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 10 23:17:27.777288 kubelet[1749]: I0910 23:17:27.777082 1749 kubelet.go:386] "Adding apiserver pod source" Sep 10 23:17:27.778194 kubelet[1749]: I0910 23:17:27.778174 1749 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 10 23:17:27.778243 kubelet[1749]: E0910 23:17:27.778219 1749 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:27.778276 kubelet[1749]: E0910 23:17:27.778265 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:27.779444 kubelet[1749]: I0910 23:17:27.779408 1749 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Sep 10 23:17:27.780112 kubelet[1749]: I0910 23:17:27.780094 1749 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Sep 10 23:17:27.780233 kubelet[1749]: W0910 23:17:27.780221 1749 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 10 23:17:27.782390 kubelet[1749]: I0910 23:17:27.782374 1749 watchdog_linux.go:99] "Systemd watchdog is not enabled" Sep 10 23:17:27.782430 kubelet[1749]: I0910 23:17:27.782414 1749 server.go:1289] "Started kubelet" Sep 10 23:17:27.784156 kubelet[1749]: I0910 23:17:27.782505 1749 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Sep 10 23:17:27.784156 kubelet[1749]: I0910 23:17:27.782815 1749 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 10 23:17:27.784156 kubelet[1749]: I0910 23:17:27.783114 1749 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 10 23:17:27.784156 kubelet[1749]: I0910 23:17:27.783419 1749 server.go:317] "Adding debug handlers to kubelet server" Sep 10 23:17:27.784947 kubelet[1749]: I0910 23:17:27.784913 1749 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 10 23:17:27.785573 kubelet[1749]: I0910 23:17:27.785544 1749 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 10 23:17:27.785662 kubelet[1749]: E0910 23:17:27.785598 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:27.785662 kubelet[1749]: I0910 23:17:27.785622 1749 volume_manager.go:297] "Starting Kubelet Volume Manager" Sep 10 23:17:27.787595 kubelet[1749]: I0910 23:17:27.785974 1749 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Sep 10 23:17:27.787595 kubelet[1749]: I0910 23:17:27.786066 1749 reconciler.go:26] "Reconciler: start to sync state" Sep 10 23:17:27.787595 kubelet[1749]: I0910 23:17:27.786931 1749 factory.go:223] Registration of the systemd container factory successfully Sep 10 23:17:27.787595 kubelet[1749]: I0910 23:17:27.787055 1749 factory.go:221] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Sep 10 23:17:27.789093 kubelet[1749]: E0910 23:17:27.789058 1749 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 10 23:17:27.790014 kubelet[1749]: I0910 23:17:27.789983 1749 factory.go:223] Registration of the containerd container factory successfully Sep 10 23:17:27.795463 kubelet[1749]: E0910 23:17:27.795420 1749 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.15\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Sep 10 23:17:27.797585 kubelet[1749]: E0910 23:17:27.797541 1749 reflector.go:200] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Sep 10 23:17:27.798910 kubelet[1749]: E0910 23:17:27.798873 1749 reflector.go:200] "Failed to watch" err="failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:anonymous\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" Sep 10 23:17:27.798910 kubelet[1749]: E0910 23:17:27.795760 1749 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.15.18640f001f63dfe3 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.15,UID:10.0.0.15,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.15,},FirstTimestamp:2025-09-10 23:17:27.782391779 +0000 UTC m=+0.723504595,LastTimestamp:2025-09-10 23:17:27.782391779 +0000 UTC m=+0.723504595,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.15,}" Sep 10 23:17:27.799317 kubelet[1749]: E0910 23:17:27.799163 1749 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.0.0.15\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Sep 10 23:17:27.799975 kubelet[1749]: E0910 23:17:27.799891 1749 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.15.18640f001fc4d556 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.15,UID:10.0.0.15,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.0.0.15,},FirstTimestamp:2025-09-10 23:17:27.78874607 +0000 UTC m=+0.729858887,LastTimestamp:2025-09-10 23:17:27.78874607 +0000 UTC m=+0.729858887,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.15,}" Sep 10 23:17:27.801738 kubelet[1749]: I0910 23:17:27.801686 1749 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 10 23:17:27.801738 kubelet[1749]: I0910 23:17:27.801705 1749 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 10 23:17:27.801738 kubelet[1749]: I0910 23:17:27.801722 1749 state_mem.go:36] "Initialized new in-memory state store" Sep 10 23:17:27.886002 kubelet[1749]: E0910 23:17:27.885933 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:27.893914 kubelet[1749]: I0910 23:17:27.893879 1749 policy_none.go:49] "None policy: Start" Sep 10 23:17:27.893914 kubelet[1749]: I0910 23:17:27.893907 1749 memory_manager.go:186] "Starting memorymanager" policy="None" Sep 10 23:17:27.893914 kubelet[1749]: I0910 23:17:27.893920 1749 state_mem.go:35] "Initializing new in-memory state store" Sep 10 23:17:27.898949 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 10 23:17:27.907916 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 10 23:17:27.910682 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 10 23:17:27.919905 kubelet[1749]: E0910 23:17:27.919865 1749 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Sep 10 23:17:27.920180 kubelet[1749]: I0910 23:17:27.920157 1749 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 10 23:17:27.920562 kubelet[1749]: I0910 23:17:27.920262 1749 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 10 23:17:27.920562 kubelet[1749]: I0910 23:17:27.920506 1749 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 10 23:17:27.921313 kubelet[1749]: I0910 23:17:27.921279 1749 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Sep 10 23:17:27.921991 kubelet[1749]: E0910 23:17:27.921967 1749 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 10 23:17:27.922062 kubelet[1749]: E0910 23:17:27.922012 1749 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.15\" not found" Sep 10 23:17:27.922552 kubelet[1749]: I0910 23:17:27.922530 1749 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Sep 10 23:17:27.922552 kubelet[1749]: I0910 23:17:27.922555 1749 status_manager.go:230] "Starting to sync pod status with apiserver" Sep 10 23:17:27.922678 kubelet[1749]: I0910 23:17:27.922583 1749 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 10 23:17:27.922678 kubelet[1749]: I0910 23:17:27.922590 1749 kubelet.go:2436] "Starting kubelet main sync loop" Sep 10 23:17:27.922678 kubelet[1749]: E0910 23:17:27.922641 1749 kubelet.go:2460] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 10 23:17:28.000395 kubelet[1749]: E0910 23:17:28.000264 1749 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.15\" not found" node="10.0.0.15" Sep 10 23:17:28.022178 kubelet[1749]: I0910 23:17:28.022083 1749 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.15" Sep 10 23:17:28.026208 kubelet[1749]: I0910 23:17:28.026175 1749 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.15" Sep 10 23:17:28.026208 kubelet[1749]: E0910 23:17:28.026207 1749 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.15\": node \"10.0.0.15\" not found" Sep 10 23:17:28.037795 kubelet[1749]: E0910 23:17:28.037753 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:28.079872 sudo[1615]: pam_unix(sudo:session): session closed for user root Sep 10 23:17:28.082076 sshd[1614]: Connection closed by 10.0.0.1 port 48032 Sep 10 23:17:28.081575 sshd-session[1611]: pam_unix(sshd:session): session closed for user core Sep 10 23:17:28.084670 systemd[1]: sshd@4-10.0.0.15:22-10.0.0.1:48032.service: Deactivated successfully. Sep 10 23:17:28.086496 systemd[1]: session-5.scope: Deactivated successfully. Sep 10 23:17:28.087200 systemd[1]: session-5.scope: Consumed 399ms CPU time, 75.9M memory peak. Sep 10 23:17:28.088434 systemd-logind[1460]: Session 5 logged out. Waiting for processes to exit. Sep 10 23:17:28.089379 systemd-logind[1460]: Removed session 5. Sep 10 23:17:28.138607 kubelet[1749]: E0910 23:17:28.138570 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:28.239056 kubelet[1749]: E0910 23:17:28.239005 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:28.339862 kubelet[1749]: E0910 23:17:28.339740 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:28.440243 kubelet[1749]: E0910 23:17:28.440205 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:28.540761 kubelet[1749]: E0910 23:17:28.540727 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:28.641325 kubelet[1749]: E0910 23:17:28.641303 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:28.741830 kubelet[1749]: E0910 23:17:28.741793 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:28.741956 kubelet[1749]: I0910 23:17:28.741834 1749 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 10 23:17:28.742029 kubelet[1749]: I0910 23:17:28.742001 1749 reflector.go:556] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Sep 10 23:17:28.779277 kubelet[1749]: E0910 23:17:28.779217 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:28.842754 kubelet[1749]: E0910 23:17:28.842706 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:28.943757 kubelet[1749]: E0910 23:17:28.943623 1749 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.15\" not found" Sep 10 23:17:29.044614 kubelet[1749]: I0910 23:17:29.044577 1749 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 10 23:17:29.044905 containerd[1478]: time="2025-09-10T23:17:29.044847000Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 10 23:17:29.045397 kubelet[1749]: I0910 23:17:29.045067 1749 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 10 23:17:29.779902 kubelet[1749]: I0910 23:17:29.779849 1749 apiserver.go:52] "Watching apiserver" Sep 10 23:17:29.780309 kubelet[1749]: E0910 23:17:29.779864 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:29.792906 systemd[1]: Created slice kubepods-besteffort-pod40aa62b4_a810_4584_a3b5_84c56630a975.slice - libcontainer container kubepods-besteffort-pod40aa62b4_a810_4584_a3b5_84c56630a975.slice. Sep 10 23:17:29.797294 kubelet[1749]: I0910 23:17:29.797253 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zrxhn\" (UniqueName: \"kubernetes.io/projected/c01647e6-92d6-4267-8143-c5395498486e-kube-api-access-zrxhn\") pod \"kube-flannel-ds-7gkfc\" (UID: \"c01647e6-92d6-4267-8143-c5395498486e\") " pod="kube-flannel/kube-flannel-ds-7gkfc" Sep 10 23:17:29.797294 kubelet[1749]: I0910 23:17:29.797294 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/40aa62b4-a810-4584-a3b5-84c56630a975-xtables-lock\") pod \"kube-proxy-r7gtg\" (UID: \"40aa62b4-a810-4584-a3b5-84c56630a975\") " pod="kube-system/kube-proxy-r7gtg" Sep 10 23:17:29.797414 kubelet[1749]: I0910 23:17:29.797311 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/c01647e6-92d6-4267-8143-c5395498486e-cni-plugin\") pod \"kube-flannel-ds-7gkfc\" (UID: \"c01647e6-92d6-4267-8143-c5395498486e\") " pod="kube-flannel/kube-flannel-ds-7gkfc" Sep 10 23:17:29.797414 kubelet[1749]: I0910 23:17:29.797328 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/c01647e6-92d6-4267-8143-c5395498486e-cni\") pod \"kube-flannel-ds-7gkfc\" (UID: \"c01647e6-92d6-4267-8143-c5395498486e\") " pod="kube-flannel/kube-flannel-ds-7gkfc" Sep 10 23:17:29.797414 kubelet[1749]: I0910 23:17:29.797344 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/c01647e6-92d6-4267-8143-c5395498486e-flannel-cfg\") pod \"kube-flannel-ds-7gkfc\" (UID: \"c01647e6-92d6-4267-8143-c5395498486e\") " pod="kube-flannel/kube-flannel-ds-7gkfc" Sep 10 23:17:29.797414 kubelet[1749]: I0910 23:17:29.797364 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/c01647e6-92d6-4267-8143-c5395498486e-xtables-lock\") pod \"kube-flannel-ds-7gkfc\" (UID: \"c01647e6-92d6-4267-8143-c5395498486e\") " pod="kube-flannel/kube-flannel-ds-7gkfc" Sep 10 23:17:29.797414 kubelet[1749]: I0910 23:17:29.797378 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/40aa62b4-a810-4584-a3b5-84c56630a975-kube-proxy\") pod \"kube-proxy-r7gtg\" (UID: \"40aa62b4-a810-4584-a3b5-84c56630a975\") " pod="kube-system/kube-proxy-r7gtg" Sep 10 23:17:29.797649 kubelet[1749]: I0910 23:17:29.797398 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/40aa62b4-a810-4584-a3b5-84c56630a975-lib-modules\") pod \"kube-proxy-r7gtg\" (UID: \"40aa62b4-a810-4584-a3b5-84c56630a975\") " pod="kube-system/kube-proxy-r7gtg" Sep 10 23:17:29.797649 kubelet[1749]: I0910 23:17:29.797416 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-s965q\" (UniqueName: \"kubernetes.io/projected/40aa62b4-a810-4584-a3b5-84c56630a975-kube-api-access-s965q\") pod \"kube-proxy-r7gtg\" (UID: \"40aa62b4-a810-4584-a3b5-84c56630a975\") " pod="kube-system/kube-proxy-r7gtg" Sep 10 23:17:29.797649 kubelet[1749]: I0910 23:17:29.797429 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/c01647e6-92d6-4267-8143-c5395498486e-run\") pod \"kube-flannel-ds-7gkfc\" (UID: \"c01647e6-92d6-4267-8143-c5395498486e\") " pod="kube-flannel/kube-flannel-ds-7gkfc" Sep 10 23:17:29.803610 systemd[1]: Created slice kubepods-burstable-podc01647e6_92d6_4267_8143_c5395498486e.slice - libcontainer container kubepods-burstable-podc01647e6_92d6_4267_8143_c5395498486e.slice. Sep 10 23:17:29.886627 kubelet[1749]: I0910 23:17:29.886560 1749 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Sep 10 23:17:30.102813 containerd[1478]: time="2025-09-10T23:17:30.102701141Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-r7gtg,Uid:40aa62b4-a810-4584-a3b5-84c56630a975,Namespace:kube-system,Attempt:0,}" Sep 10 23:17:30.118707 containerd[1478]: time="2025-09-10T23:17:30.118532007Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7gkfc,Uid:c01647e6-92d6-4267-8143-c5395498486e,Namespace:kube-flannel,Attempt:0,}" Sep 10 23:17:30.619284 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1802240399.mount: Deactivated successfully. Sep 10 23:17:30.627980 containerd[1478]: time="2025-09-10T23:17:30.627932725Z" 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 10 23:17:30.629079 containerd[1478]: time="2025-09-10T23:17:30.629041377Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=269175" Sep 10 23:17:30.629717 containerd[1478]: time="2025-09-10T23:17:30.629692814Z" 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 10 23:17:30.630554 containerd[1478]: time="2025-09-10T23:17:30.630527344Z" 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 10 23:17:30.630935 containerd[1478]: time="2025-09-10T23:17:30.630752930Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Sep 10 23:17:30.633095 containerd[1478]: time="2025-09-10T23:17:30.632700206Z" 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 10 23:17:30.635292 containerd[1478]: time="2025-09-10T23:17:30.635215085Z" 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 516.570544ms" Sep 10 23:17:30.636036 containerd[1478]: time="2025-09-10T23:17:30.636007042Z" 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 533.217932ms" Sep 10 23:17:30.729812 containerd[1478]: time="2025-09-10T23:17:30.727802309Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 10 23:17:30.729812 containerd[1478]: time="2025-09-10T23:17:30.727853190Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 10 23:17:30.729812 containerd[1478]: time="2025-09-10T23:17:30.727868017Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:30.729812 containerd[1478]: time="2025-09-10T23:17:30.729661735Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:30.729812 containerd[1478]: time="2025-09-10T23:17:30.727701461Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 10 23:17:30.729812 containerd[1478]: time="2025-09-10T23:17:30.727767089Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 10 23:17:30.729812 containerd[1478]: time="2025-09-10T23:17:30.727778299Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:30.729812 containerd[1478]: time="2025-09-10T23:17:30.727850169Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:30.781221 kubelet[1749]: E0910 23:17:30.780302 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:30.790295 systemd[1]: Started cri-containerd-804f66fc15c05583b77cb1c9eaa2de708a96a2e7738798d120a95c76f616b4f2.scope - libcontainer container 804f66fc15c05583b77cb1c9eaa2de708a96a2e7738798d120a95c76f616b4f2. Sep 10 23:17:30.793382 systemd[1]: Started cri-containerd-8881048100054d8becb9ed7aaf88389c7bc63a1657e26a324fc581d4dd05b840.scope - libcontainer container 8881048100054d8becb9ed7aaf88389c7bc63a1657e26a324fc581d4dd05b840. Sep 10 23:17:30.813453 containerd[1478]: time="2025-09-10T23:17:30.813409676Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-r7gtg,Uid:40aa62b4-a810-4584-a3b5-84c56630a975,Namespace:kube-system,Attempt:0,} returns sandbox id \"804f66fc15c05583b77cb1c9eaa2de708a96a2e7738798d120a95c76f616b4f2\"" Sep 10 23:17:30.817019 containerd[1478]: time="2025-09-10T23:17:30.815841714Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\"" Sep 10 23:17:30.821687 containerd[1478]: time="2025-09-10T23:17:30.821642639Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7gkfc,Uid:c01647e6-92d6-4267-8143-c5395498486e,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"8881048100054d8becb9ed7aaf88389c7bc63a1657e26a324fc581d4dd05b840\"" Sep 10 23:17:31.780913 kubelet[1749]: E0910 23:17:31.780867 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:31.819959 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3051744967.mount: Deactivated successfully. Sep 10 23:17:32.052107 containerd[1478]: time="2025-09-10T23:17:32.051992211Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:32.052913 containerd[1478]: time="2025-09-10T23:17:32.052872514Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.5: active requests=0, bytes read=28240108" Sep 10 23:17:32.054068 containerd[1478]: time="2025-09-10T23:17:32.054035900Z" level=info msg="ImageCreate event name:\"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:32.055939 containerd[1478]: time="2025-09-10T23:17:32.055893838Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:32.057061 containerd[1478]: time="2025-09-10T23:17:32.057029915Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.5\" with image id \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\", repo tag \"registry.k8s.io/kube-proxy:v1.33.5\", repo digest \"registry.k8s.io/kube-proxy@sha256:71445ec84ad98bd52a7784865a9d31b1b50b56092d3f7699edc39eefd71befe1\", size \"28239125\" in 1.241145893s" Sep 10 23:17:32.057116 containerd[1478]: time="2025-09-10T23:17:32.057063872Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.5\" returns image reference \"sha256:021a8d45ab0c346664e47d95595ff5180ce90a22a681ea27904c65ae90788e70\"" Sep 10 23:17:32.058581 containerd[1478]: time="2025-09-10T23:17:32.058520971Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Sep 10 23:17:32.062159 containerd[1478]: time="2025-09-10T23:17:32.062099306Z" level=info msg="CreateContainer within sandbox \"804f66fc15c05583b77cb1c9eaa2de708a96a2e7738798d120a95c76f616b4f2\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 10 23:17:32.074762 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount268391738.mount: Deactivated successfully. Sep 10 23:17:32.077354 containerd[1478]: time="2025-09-10T23:17:32.077312894Z" level=info msg="CreateContainer within sandbox \"804f66fc15c05583b77cb1c9eaa2de708a96a2e7738798d120a95c76f616b4f2\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"99f78ea74d3b0abfc25e23a9e3fc87fa9f895786e072a7494824a4b714b16e1f\"" Sep 10 23:17:32.079711 containerd[1478]: time="2025-09-10T23:17:32.078292242Z" level=info msg="StartContainer for \"99f78ea74d3b0abfc25e23a9e3fc87fa9f895786e072a7494824a4b714b16e1f\"" Sep 10 23:17:32.105359 systemd[1]: Started cri-containerd-99f78ea74d3b0abfc25e23a9e3fc87fa9f895786e072a7494824a4b714b16e1f.scope - libcontainer container 99f78ea74d3b0abfc25e23a9e3fc87fa9f895786e072a7494824a4b714b16e1f. Sep 10 23:17:32.130168 containerd[1478]: time="2025-09-10T23:17:32.130114230Z" level=info msg="StartContainer for \"99f78ea74d3b0abfc25e23a9e3fc87fa9f895786e072a7494824a4b714b16e1f\" returns successfully" Sep 10 23:17:32.781264 kubelet[1749]: E0910 23:17:32.781234 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:32.945189 kubelet[1749]: I0910 23:17:32.942866 1749 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-r7gtg" podStartSLOduration=4.7002598209999995 podStartE2EDuration="5.94285026s" podCreationTimestamp="2025-09-10 23:17:27 +0000 UTC" firstStartedPulling="2025-09-10 23:17:30.815216115 +0000 UTC m=+3.756328931" lastFinishedPulling="2025-09-10 23:17:32.057806553 +0000 UTC m=+4.998919370" observedRunningTime="2025-09-10 23:17:32.942404953 +0000 UTC m=+5.883518128" watchObservedRunningTime="2025-09-10 23:17:32.94285026 +0000 UTC m=+5.883963116" Sep 10 23:17:32.962162 containerd[1478]: time="2025-09-10T23:17:32.959309121Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:32.962162 containerd[1478]: time="2025-09-10T23:17:32.959704109Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Sep 10 23:17:32.962162 containerd[1478]: time="2025-09-10T23:17:32.960445557Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:32.963600 containerd[1478]: time="2025-09-10T23:17:32.963565090Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:32.964962 containerd[1478]: time="2025-09-10T23:17:32.964895993Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"5125394\" in 906.344927ms" Sep 10 23:17:32.964962 containerd[1478]: time="2025-09-10T23:17:32.964956066Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Sep 10 23:17:32.968019 containerd[1478]: time="2025-09-10T23:17:32.967989014Z" level=info msg="CreateContainer within sandbox \"8881048100054d8becb9ed7aaf88389c7bc63a1657e26a324fc581d4dd05b840\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 10 23:17:32.984172 containerd[1478]: time="2025-09-10T23:17:32.984104242Z" level=info msg="CreateContainer within sandbox \"8881048100054d8becb9ed7aaf88389c7bc63a1657e26a324fc581d4dd05b840\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"1e986c126e4cb30ad56c5c7d333ab78aac9bb771494ecb0821273d13ec22ab19\"" Sep 10 23:17:32.984793 containerd[1478]: time="2025-09-10T23:17:32.984761135Z" level=info msg="StartContainer for \"1e986c126e4cb30ad56c5c7d333ab78aac9bb771494ecb0821273d13ec22ab19\"" Sep 10 23:17:33.008296 systemd[1]: Started cri-containerd-1e986c126e4cb30ad56c5c7d333ab78aac9bb771494ecb0821273d13ec22ab19.scope - libcontainer container 1e986c126e4cb30ad56c5c7d333ab78aac9bb771494ecb0821273d13ec22ab19. Sep 10 23:17:33.030409 systemd[1]: cri-containerd-1e986c126e4cb30ad56c5c7d333ab78aac9bb771494ecb0821273d13ec22ab19.scope: Deactivated successfully. Sep 10 23:17:33.084874 containerd[1478]: time="2025-09-10T23:17:33.084732584Z" level=info msg="StartContainer for \"1e986c126e4cb30ad56c5c7d333ab78aac9bb771494ecb0821273d13ec22ab19\" returns successfully" Sep 10 23:17:33.099039 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-1e986c126e4cb30ad56c5c7d333ab78aac9bb771494ecb0821273d13ec22ab19-rootfs.mount: Deactivated successfully. Sep 10 23:17:33.515630 containerd[1478]: time="2025-09-10T23:17:33.515566944Z" level=info msg="shim disconnected" id=1e986c126e4cb30ad56c5c7d333ab78aac9bb771494ecb0821273d13ec22ab19 namespace=k8s.io Sep 10 23:17:33.515630 containerd[1478]: time="2025-09-10T23:17:33.515619723Z" level=warning msg="cleaning up after shim disconnected" id=1e986c126e4cb30ad56c5c7d333ab78aac9bb771494ecb0821273d13ec22ab19 namespace=k8s.io Sep 10 23:17:33.515630 containerd[1478]: time="2025-09-10T23:17:33.515629323Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 10 23:17:33.782220 kubelet[1749]: E0910 23:17:33.782088 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:33.937423 containerd[1478]: time="2025-09-10T23:17:33.937381536Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Sep 10 23:17:34.782923 kubelet[1749]: E0910 23:17:34.782881 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:35.495010 containerd[1478]: time="2025-09-10T23:17:35.494961579Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:35.495463 containerd[1478]: time="2025-09-10T23:17:35.495426728Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Sep 10 23:17:35.496415 containerd[1478]: time="2025-09-10T23:17:35.496387091Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:35.502420 containerd[1478]: time="2025-09-10T23:17:35.502361664Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:35.503942 containerd[1478]: time="2025-09-10T23:17:35.503909424Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32412118\" in 1.566488923s" Sep 10 23:17:35.504019 containerd[1478]: time="2025-09-10T23:17:35.503943236Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Sep 10 23:17:35.508814 containerd[1478]: time="2025-09-10T23:17:35.508774632Z" level=info msg="CreateContainer within sandbox \"8881048100054d8becb9ed7aaf88389c7bc63a1657e26a324fc581d4dd05b840\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 10 23:17:35.524128 containerd[1478]: time="2025-09-10T23:17:35.524079027Z" level=info msg="CreateContainer within sandbox \"8881048100054d8becb9ed7aaf88389c7bc63a1657e26a324fc581d4dd05b840\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"14a613e95ed914d3af9885e991517202fbfa62bade72466846e1c21c2bc8acd2\"" Sep 10 23:17:35.524821 containerd[1478]: time="2025-09-10T23:17:35.524596169Z" level=info msg="StartContainer for \"14a613e95ed914d3af9885e991517202fbfa62bade72466846e1c21c2bc8acd2\"" Sep 10 23:17:35.549289 systemd[1]: Started cri-containerd-14a613e95ed914d3af9885e991517202fbfa62bade72466846e1c21c2bc8acd2.scope - libcontainer container 14a613e95ed914d3af9885e991517202fbfa62bade72466846e1c21c2bc8acd2. Sep 10 23:17:35.569177 systemd[1]: cri-containerd-14a613e95ed914d3af9885e991517202fbfa62bade72466846e1c21c2bc8acd2.scope: Deactivated successfully. Sep 10 23:17:35.573023 containerd[1478]: time="2025-09-10T23:17:35.572974372Z" level=info msg="StartContainer for \"14a613e95ed914d3af9885e991517202fbfa62bade72466846e1c21c2bc8acd2\" returns successfully" Sep 10 23:17:35.661002 kubelet[1749]: I0910 23:17:35.660782 1749 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Sep 10 23:17:35.700704 containerd[1478]: time="2025-09-10T23:17:35.700647301Z" level=info msg="shim disconnected" id=14a613e95ed914d3af9885e991517202fbfa62bade72466846e1c21c2bc8acd2 namespace=k8s.io Sep 10 23:17:35.700704 containerd[1478]: time="2025-09-10T23:17:35.700697899Z" level=warning msg="cleaning up after shim disconnected" id=14a613e95ed914d3af9885e991517202fbfa62bade72466846e1c21c2bc8acd2 namespace=k8s.io Sep 10 23:17:35.700704 containerd[1478]: time="2025-09-10T23:17:35.700707907Z" level=info msg="cleaning up dead shim" namespace=k8s.io Sep 10 23:17:35.783682 kubelet[1749]: E0910 23:17:35.783540 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:35.982530 containerd[1478]: time="2025-09-10T23:17:35.982458863Z" level=info msg="CreateContainer within sandbox \"8881048100054d8becb9ed7aaf88389c7bc63a1657e26a324fc581d4dd05b840\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 10 23:17:36.169837 containerd[1478]: time="2025-09-10T23:17:36.169782414Z" level=info msg="CreateContainer within sandbox \"8881048100054d8becb9ed7aaf88389c7bc63a1657e26a324fc581d4dd05b840\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"2aa9b14c75585580ddeb932be8758dc41fa89601e68dbac819216bd362869a26\"" Sep 10 23:17:36.170740 containerd[1478]: time="2025-09-10T23:17:36.170557162Z" level=info msg="StartContainer for \"2aa9b14c75585580ddeb932be8758dc41fa89601e68dbac819216bd362869a26\"" Sep 10 23:17:36.202305 systemd[1]: Started cri-containerd-2aa9b14c75585580ddeb932be8758dc41fa89601e68dbac819216bd362869a26.scope - libcontainer container 2aa9b14c75585580ddeb932be8758dc41fa89601e68dbac819216bd362869a26. Sep 10 23:17:36.236872 containerd[1478]: time="2025-09-10T23:17:36.236807903Z" level=info msg="StartContainer for \"2aa9b14c75585580ddeb932be8758dc41fa89601e68dbac819216bd362869a26\" returns successfully" Sep 10 23:17:36.518068 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-14a613e95ed914d3af9885e991517202fbfa62bade72466846e1c21c2bc8acd2-rootfs.mount: Deactivated successfully. Sep 10 23:17:36.784762 kubelet[1749]: E0910 23:17:36.784638 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:37.306720 systemd-networkd[1403]: flannel.1: Link UP Sep 10 23:17:37.306732 systemd-networkd[1403]: flannel.1: Gained carrier Sep 10 23:17:37.785598 kubelet[1749]: E0910 23:17:37.785534 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:38.785847 kubelet[1749]: E0910 23:17:38.785780 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:38.851340 systemd-networkd[1403]: flannel.1: Gained IPv6LL Sep 10 23:17:38.860199 kubelet[1749]: I0910 23:17:38.860121 1749 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-7gkfc" podStartSLOduration=7.177470393 podStartE2EDuration="11.86010296s" podCreationTimestamp="2025-09-10 23:17:27 +0000 UTC" firstStartedPulling="2025-09-10 23:17:30.822554961 +0000 UTC m=+3.763667778" lastFinishedPulling="2025-09-10 23:17:35.505187568 +0000 UTC m=+8.446300345" observedRunningTime="2025-09-10 23:17:36.955289655 +0000 UTC m=+9.896402551" watchObservedRunningTime="2025-09-10 23:17:38.86010296 +0000 UTC m=+11.801215777" Sep 10 23:17:38.868911 systemd[1]: Created slice kubepods-besteffort-poda2e516f9_c6f8_4f02_8f43_a34c92e375fc.slice - libcontainer container kubepods-besteffort-poda2e516f9_c6f8_4f02_8f43_a34c92e375fc.slice. Sep 10 23:17:38.949045 kubelet[1749]: I0910 23:17:38.949003 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-pwvjg\" (UniqueName: \"kubernetes.io/projected/a2e516f9-c6f8-4f02-8f43-a34c92e375fc-kube-api-access-pwvjg\") pod \"nginx-deployment-7fcdb87857-7vz78\" (UID: \"a2e516f9-c6f8-4f02-8f43-a34c92e375fc\") " pod="default/nginx-deployment-7fcdb87857-7vz78" Sep 10 23:17:39.176244 containerd[1478]: time="2025-09-10T23:17:39.176193802Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-7vz78,Uid:a2e516f9-c6f8-4f02-8f43-a34c92e375fc,Namespace:default,Attempt:0,}" Sep 10 23:17:39.199646 systemd-networkd[1403]: cni0: Link UP Sep 10 23:17:39.199654 systemd-networkd[1403]: cni0: Gained carrier Sep 10 23:17:39.200333 systemd-networkd[1403]: cni0: Lost carrier Sep 10 23:17:39.208912 systemd-networkd[1403]: veth00b23243: Link UP Sep 10 23:17:39.210378 kernel: cni0: port 1(veth00b23243) entered blocking state Sep 10 23:17:39.210485 kernel: cni0: port 1(veth00b23243) entered disabled state Sep 10 23:17:39.210509 kernel: veth00b23243: entered allmulticast mode Sep 10 23:17:39.210526 kernel: veth00b23243: entered promiscuous mode Sep 10 23:17:39.211532 kernel: cni0: port 1(veth00b23243) entered blocking state Sep 10 23:17:39.211561 kernel: cni0: port 1(veth00b23243) entered forwarding state Sep 10 23:17:39.212580 kernel: cni0: port 1(veth00b23243) entered disabled state Sep 10 23:17:39.220124 systemd-networkd[1403]: veth00b23243: Gained carrier Sep 10 23:17:39.220614 kernel: cni0: port 1(veth00b23243) entered blocking state Sep 10 23:17:39.220656 kernel: cni0: port 1(veth00b23243) entered forwarding state Sep 10 23:17:39.222182 containerd[1478]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Sep 10 23:17:39.222182 containerd[1478]: delegateAdd: netconf sent to delegate plugin: Sep 10 23:17:39.222189 systemd-networkd[1403]: cni0: Gained carrier Sep 10 23:17:39.244788 containerd[1478]: {"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-10T23:17:39.244691458Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 10 23:17:39.244788 containerd[1478]: time="2025-09-10T23:17:39.244750387Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 10 23:17:39.244788 containerd[1478]: time="2025-09-10T23:17:39.244765399Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:39.245647 containerd[1478]: time="2025-09-10T23:17:39.245575280Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:39.266299 systemd[1]: Started cri-containerd-a27f017b4f5c75453b956cce18583762cf7b2822d220a76777481cdddcb172e0.scope - libcontainer container a27f017b4f5c75453b956cce18583762cf7b2822d220a76777481cdddcb172e0. Sep 10 23:17:39.276547 systemd-resolved[1329]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 10 23:17:39.294282 containerd[1478]: time="2025-09-10T23:17:39.294248806Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-7vz78,Uid:a2e516f9-c6f8-4f02-8f43-a34c92e375fc,Namespace:default,Attempt:0,} returns sandbox id \"a27f017b4f5c75453b956cce18583762cf7b2822d220a76777481cdddcb172e0\"" Sep 10 23:17:39.295918 containerd[1478]: time="2025-09-10T23:17:39.295862699Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 10 23:17:39.786092 kubelet[1749]: E0910 23:17:39.786049 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:40.388278 systemd-networkd[1403]: veth00b23243: Gained IPv6LL Sep 10 23:17:40.579286 systemd-networkd[1403]: cni0: Gained IPv6LL Sep 10 23:17:40.787186 kubelet[1749]: E0910 23:17:40.787066 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:41.092862 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2288428962.mount: Deactivated successfully. Sep 10 23:17:41.788114 kubelet[1749]: E0910 23:17:41.788035 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:41.790072 containerd[1478]: time="2025-09-10T23:17:41.790029157Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:41.790903 containerd[1478]: time="2025-09-10T23:17:41.790713854Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69986522" Sep 10 23:17:41.795147 containerd[1478]: time="2025-09-10T23:17:41.794713592Z" level=info msg="ImageCreate event name:\"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:41.797483 containerd[1478]: time="2025-09-10T23:17:41.797458731Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:41.798576 containerd[1478]: time="2025-09-10T23:17:41.798554997Z" 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.50266287s" Sep 10 23:17:41.798625 containerd[1478]: time="2025-09-10T23:17:41.798581319Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 10 23:17:41.802176 containerd[1478]: time="2025-09-10T23:17:41.802147239Z" level=info msg="CreateContainer within sandbox \"a27f017b4f5c75453b956cce18583762cf7b2822d220a76777481cdddcb172e0\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 10 23:17:41.813439 containerd[1478]: time="2025-09-10T23:17:41.813395850Z" level=info msg="CreateContainer within sandbox \"a27f017b4f5c75453b956cce18583762cf7b2822d220a76777481cdddcb172e0\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"bd2eb47368d361b8c615737f644b7add3bf80055f7cf37afca97e631dd4882ae\"" Sep 10 23:17:41.813845 containerd[1478]: time="2025-09-10T23:17:41.813817604Z" level=info msg="StartContainer for \"bd2eb47368d361b8c615737f644b7add3bf80055f7cf37afca97e631dd4882ae\"" Sep 10 23:17:41.837284 systemd[1]: Started cri-containerd-bd2eb47368d361b8c615737f644b7add3bf80055f7cf37afca97e631dd4882ae.scope - libcontainer container bd2eb47368d361b8c615737f644b7add3bf80055f7cf37afca97e631dd4882ae. Sep 10 23:17:41.857882 containerd[1478]: time="2025-09-10T23:17:41.857840839Z" level=info msg="StartContainer for \"bd2eb47368d361b8c615737f644b7add3bf80055f7cf37afca97e631dd4882ae\" returns successfully" Sep 10 23:17:41.962109 kubelet[1749]: I0910 23:17:41.962036 1749 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-7vz78" podStartSLOduration=1.457678905 podStartE2EDuration="3.962019269s" podCreationTimestamp="2025-09-10 23:17:38 +0000 UTC" firstStartedPulling="2025-09-10 23:17:39.295298397 +0000 UTC m=+12.236411174" lastFinishedPulling="2025-09-10 23:17:41.799638761 +0000 UTC m=+14.740751538" observedRunningTime="2025-09-10 23:17:41.961968222 +0000 UTC m=+14.903081039" watchObservedRunningTime="2025-09-10 23:17:41.962019269 +0000 UTC m=+14.903132086" Sep 10 23:17:42.788999 kubelet[1749]: E0910 23:17:42.788926 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:43.789321 kubelet[1749]: E0910 23:17:43.789277 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:44.789839 kubelet[1749]: E0910 23:17:44.789770 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:45.133405 systemd[1]: Created slice kubepods-besteffort-pode752f39a_0b5f_4fee_bd15_1857cffd72d4.slice - libcontainer container kubepods-besteffort-pode752f39a_0b5f_4fee_bd15_1857cffd72d4.slice. Sep 10 23:17:45.177045 kubelet[1749]: I0910 23:17:45.176984 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/e752f39a-0b5f-4fee-bd15-1857cffd72d4-data\") pod \"nfs-server-provisioner-0\" (UID: \"e752f39a-0b5f-4fee-bd15-1857cffd72d4\") " pod="default/nfs-server-provisioner-0" Sep 10 23:17:45.177196 kubelet[1749]: I0910 23:17:45.177055 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mq56z\" (UniqueName: \"kubernetes.io/projected/e752f39a-0b5f-4fee-bd15-1857cffd72d4-kube-api-access-mq56z\") pod \"nfs-server-provisioner-0\" (UID: \"e752f39a-0b5f-4fee-bd15-1857cffd72d4\") " pod="default/nfs-server-provisioner-0" Sep 10 23:17:45.436397 containerd[1478]: time="2025-09-10T23:17:45.436274200Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:e752f39a-0b5f-4fee-bd15-1857cffd72d4,Namespace:default,Attempt:0,}" Sep 10 23:17:45.458725 systemd-networkd[1403]: vethfd8f5089: Link UP Sep 10 23:17:45.460443 kernel: cni0: port 2(vethfd8f5089) entered blocking state Sep 10 23:17:45.460487 kernel: cni0: port 2(vethfd8f5089) entered disabled state Sep 10 23:17:45.460509 kernel: vethfd8f5089: entered allmulticast mode Sep 10 23:17:45.461448 kernel: vethfd8f5089: entered promiscuous mode Sep 10 23:17:45.468985 systemd-networkd[1403]: vethfd8f5089: Gained carrier Sep 10 23:17:45.469162 kernel: cni0: port 2(vethfd8f5089) entered blocking state Sep 10 23:17:45.469192 kernel: cni0: port 2(vethfd8f5089) entered forwarding state Sep 10 23:17:45.470710 containerd[1478]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Sep 10 23:17:45.470710 containerd[1478]: delegateAdd: netconf sent to delegate plugin: Sep 10 23:17:45.487436 containerd[1478]: {"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-10T23:17:45.487336332Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 10 23:17:45.487436 containerd[1478]: time="2025-09-10T23:17:45.487423938Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 10 23:17:45.487568 containerd[1478]: time="2025-09-10T23:17:45.487440604Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:45.487568 containerd[1478]: time="2025-09-10T23:17:45.487541120Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:45.509315 systemd[1]: Started cri-containerd-23a2bd1ca93cd65e09fdc99bc2259d0661fd499349c93090eacce8c0d834117b.scope - libcontainer container 23a2bd1ca93cd65e09fdc99bc2259d0661fd499349c93090eacce8c0d834117b. Sep 10 23:17:45.518790 systemd-resolved[1329]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 10 23:17:45.535881 containerd[1478]: time="2025-09-10T23:17:45.535843014Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:e752f39a-0b5f-4fee-bd15-1857cffd72d4,Namespace:default,Attempt:0,} returns sandbox id \"23a2bd1ca93cd65e09fdc99bc2259d0661fd499349c93090eacce8c0d834117b\"" Sep 10 23:17:45.537321 containerd[1478]: time="2025-09-10T23:17:45.537294832Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 10 23:17:45.790294 kubelet[1749]: E0910 23:17:45.790166 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:46.289543 systemd[1]: run-containerd-runc-k8s.io-23a2bd1ca93cd65e09fdc99bc2259d0661fd499349c93090eacce8c0d834117b-runc.jSvK5Z.mount: Deactivated successfully. Sep 10 23:17:46.659671 systemd-networkd[1403]: vethfd8f5089: Gained IPv6LL Sep 10 23:17:46.791282 kubelet[1749]: E0910 23:17:46.790635 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:47.275607 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4253356027.mount: Deactivated successfully. Sep 10 23:17:47.778262 kubelet[1749]: E0910 23:17:47.778226 1749 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:47.792268 kubelet[1749]: E0910 23:17:47.792221 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:48.664791 containerd[1478]: time="2025-09-10T23:17:48.664734157Z" 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 10 23:17:48.666285 containerd[1478]: time="2025-09-10T23:17:48.666237270Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Sep 10 23:17:48.668181 containerd[1478]: time="2025-09-10T23:17:48.667188495Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:48.673063 containerd[1478]: time="2025-09-10T23:17:48.673006657Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:48.674309 containerd[1478]: time="2025-09-10T23:17:48.674276462Z" 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.136945057s" Sep 10 23:17:48.674431 containerd[1478]: time="2025-09-10T23:17:48.674313241Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 10 23:17:48.678567 containerd[1478]: time="2025-09-10T23:17:48.678534983Z" level=info msg="CreateContainer within sandbox \"23a2bd1ca93cd65e09fdc99bc2259d0661fd499349c93090eacce8c0d834117b\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 10 23:17:48.688554 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1024526430.mount: Deactivated successfully. Sep 10 23:17:48.689249 containerd[1478]: time="2025-09-10T23:17:48.689216526Z" level=info msg="CreateContainer within sandbox \"23a2bd1ca93cd65e09fdc99bc2259d0661fd499349c93090eacce8c0d834117b\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"1a91343e8c96bfe332f23536719f1a79404c3082fa0798ce74695e993a253743\"" Sep 10 23:17:48.689744 containerd[1478]: time="2025-09-10T23:17:48.689682184Z" level=info msg="StartContainer for \"1a91343e8c96bfe332f23536719f1a79404c3082fa0798ce74695e993a253743\"" Sep 10 23:17:48.716298 systemd[1]: Started cri-containerd-1a91343e8c96bfe332f23536719f1a79404c3082fa0798ce74695e993a253743.scope - libcontainer container 1a91343e8c96bfe332f23536719f1a79404c3082fa0798ce74695e993a253743. Sep 10 23:17:48.737309 containerd[1478]: time="2025-09-10T23:17:48.737268818Z" level=info msg="StartContainer for \"1a91343e8c96bfe332f23536719f1a79404c3082fa0798ce74695e993a253743\" returns successfully" Sep 10 23:17:48.792458 kubelet[1749]: E0910 23:17:48.792417 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:49.793117 kubelet[1749]: E0910 23:17:49.793067 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:50.793958 kubelet[1749]: E0910 23:17:50.793914 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:51.794062 kubelet[1749]: E0910 23:17:51.794021 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:52.795153 kubelet[1749]: E0910 23:17:52.795090 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:53.795582 kubelet[1749]: E0910 23:17:53.795528 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:53.977888 kubelet[1749]: I0910 23:17:53.977822 1749 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=5.839319158 podStartE2EDuration="8.977797229s" podCreationTimestamp="2025-09-10 23:17:45 +0000 UTC" firstStartedPulling="2025-09-10 23:17:45.537021502 +0000 UTC m=+18.478134319" lastFinishedPulling="2025-09-10 23:17:48.675499573 +0000 UTC m=+21.616612390" observedRunningTime="2025-09-10 23:17:48.982257254 +0000 UTC m=+21.923370071" watchObservedRunningTime="2025-09-10 23:17:53.977797229 +0000 UTC m=+26.918910046" Sep 10 23:17:53.984519 systemd[1]: Created slice kubepods-besteffort-pod1a39bb28_7f13_4158_8659_a1d0f4ae2b37.slice - libcontainer container kubepods-besteffort-pod1a39bb28_7f13_4158_8659_a1d0f4ae2b37.slice. Sep 10 23:17:54.025898 kubelet[1749]: I0910 23:17:54.025861 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-7024e9b1-b43b-446b-9e0c-ac88c12b5bf7\" (UniqueName: \"kubernetes.io/nfs/1a39bb28-7f13-4158-8659-a1d0f4ae2b37-pvc-7024e9b1-b43b-446b-9e0c-ac88c12b5bf7\") pod \"test-pod-1\" (UID: \"1a39bb28-7f13-4158-8659-a1d0f4ae2b37\") " pod="default/test-pod-1" Sep 10 23:17:54.025898 kubelet[1749]: I0910 23:17:54.025901 1749 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-6h5vg\" (UniqueName: \"kubernetes.io/projected/1a39bb28-7f13-4158-8659-a1d0f4ae2b37-kube-api-access-6h5vg\") pod \"test-pod-1\" (UID: \"1a39bb28-7f13-4158-8659-a1d0f4ae2b37\") " pod="default/test-pod-1" Sep 10 23:17:54.145858 kernel: FS-Cache: Loaded Sep 10 23:17:54.168672 kernel: RPC: Registered named UNIX socket transport module. Sep 10 23:17:54.168781 kernel: RPC: Registered udp transport module. Sep 10 23:17:54.168802 kernel: RPC: Registered tcp transport module. Sep 10 23:17:54.168836 kernel: RPC: Registered tcp-with-tls transport module. Sep 10 23:17:54.169922 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 10 23:17:54.324299 kernel: NFS: Registering the id_resolver key type Sep 10 23:17:54.324384 kernel: Key type id_resolver registered Sep 10 23:17:54.324403 kernel: Key type id_legacy registered Sep 10 23:17:54.341255 nfsidmap[2758]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 10 23:17:54.342830 nfsidmap[2759]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 10 23:17:54.588015 containerd[1478]: time="2025-09-10T23:17:54.587905143Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1a39bb28-7f13-4158-8659-a1d0f4ae2b37,Namespace:default,Attempt:0,}" Sep 10 23:17:54.605386 systemd-networkd[1403]: vethdc65d020: Link UP Sep 10 23:17:54.606380 kernel: cni0: port 3(vethdc65d020) entered blocking state Sep 10 23:17:54.606426 kernel: cni0: port 3(vethdc65d020) entered disabled state Sep 10 23:17:54.606441 kernel: vethdc65d020: entered allmulticast mode Sep 10 23:17:54.607341 kernel: vethdc65d020: entered promiscuous mode Sep 10 23:17:54.611977 systemd-networkd[1403]: vethdc65d020: Gained carrier Sep 10 23:17:54.612205 kernel: cni0: port 3(vethdc65d020) entered blocking state Sep 10 23:17:54.612239 kernel: cni0: port 3(vethdc65d020) entered forwarding state Sep 10 23:17:54.613426 containerd[1478]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x400000e9a0), "name":"cbr0", "type":"bridge"} Sep 10 23:17:54.613426 containerd[1478]: delegateAdd: netconf sent to delegate plugin: Sep 10 23:17:54.632488 containerd[1478]: {"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-10T23:17:54.632241482Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Sep 10 23:17:54.632488 containerd[1478]: time="2025-09-10T23:17:54.632455450Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Sep 10 23:17:54.632767 containerd[1478]: time="2025-09-10T23:17:54.632474130Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:54.632767 containerd[1478]: time="2025-09-10T23:17:54.632728059Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Sep 10 23:17:54.649308 systemd[1]: Started cri-containerd-c457908af369beba81d04771e292ae50e1ae33fabbb7d155480899e07414f9f0.scope - libcontainer container c457908af369beba81d04771e292ae50e1ae33fabbb7d155480899e07414f9f0. Sep 10 23:17:54.658506 systemd-resolved[1329]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 10 23:17:54.676796 containerd[1478]: time="2025-09-10T23:17:54.676707347Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:1a39bb28-7f13-4158-8659-a1d0f4ae2b37,Namespace:default,Attempt:0,} returns sandbox id \"c457908af369beba81d04771e292ae50e1ae33fabbb7d155480899e07414f9f0\"" Sep 10 23:17:54.677778 containerd[1478]: time="2025-09-10T23:17:54.677748663Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 10 23:17:54.796300 kubelet[1749]: E0910 23:17:54.796260 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:54.891457 containerd[1478]: time="2025-09-10T23:17:54.891410203Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 10 23:17:54.891921 containerd[1478]: time="2025-09-10T23:17:54.891879419Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 10 23:17:54.895207 containerd[1478]: time="2025-09-10T23:17:54.895173974Z" 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 217.393149ms" Sep 10 23:17:54.895207 containerd[1478]: time="2025-09-10T23:17:54.895206575Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 10 23:17:54.898698 containerd[1478]: time="2025-09-10T23:17:54.898670775Z" level=info msg="CreateContainer within sandbox \"c457908af369beba81d04771e292ae50e1ae33fabbb7d155480899e07414f9f0\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 10 23:17:54.909272 containerd[1478]: time="2025-09-10T23:17:54.909219381Z" level=info msg="CreateContainer within sandbox \"c457908af369beba81d04771e292ae50e1ae33fabbb7d155480899e07414f9f0\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"dab8c79232fe893837b73e5b017b7ac7539346925c1867dba8f8686a2f38ddfe\"" Sep 10 23:17:54.909868 containerd[1478]: time="2025-09-10T23:17:54.909842683Z" level=info msg="StartContainer for \"dab8c79232fe893837b73e5b017b7ac7539346925c1867dba8f8686a2f38ddfe\"" Sep 10 23:17:54.933313 systemd[1]: Started cri-containerd-dab8c79232fe893837b73e5b017b7ac7539346925c1867dba8f8686a2f38ddfe.scope - libcontainer container dab8c79232fe893837b73e5b017b7ac7539346925c1867dba8f8686a2f38ddfe. Sep 10 23:17:54.954160 containerd[1478]: time="2025-09-10T23:17:54.954100820Z" level=info msg="StartContainer for \"dab8c79232fe893837b73e5b017b7ac7539346925c1867dba8f8686a2f38ddfe\" returns successfully" Sep 10 23:17:54.990487 kubelet[1749]: I0910 23:17:54.990366 1749 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=9.772077178 podStartE2EDuration="9.990350679s" podCreationTimestamp="2025-09-10 23:17:45 +0000 UTC" firstStartedPulling="2025-09-10 23:17:54.677507534 +0000 UTC m=+27.618620351" lastFinishedPulling="2025-09-10 23:17:54.895781035 +0000 UTC m=+27.836893852" observedRunningTime="2025-09-10 23:17:54.990212354 +0000 UTC m=+27.931325171" watchObservedRunningTime="2025-09-10 23:17:54.990350679 +0000 UTC m=+27.931463496" Sep 10 23:17:55.797203 kubelet[1749]: E0910 23:17:55.797104 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:56.323350 systemd-networkd[1403]: vethdc65d020: Gained IPv6LL Sep 10 23:17:56.797580 kubelet[1749]: E0910 23:17:56.797538 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 10 23:17:57.797872 kubelet[1749]: E0910 23:17:57.797806 1749 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"