Sep 4 23:54:29.765392 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 4 23:54:29.765415 kernel: Linux version 6.12.44-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Thu Sep 4 22:21:34 -00 2025 Sep 4 23:54:29.765424 kernel: KASLR enabled Sep 4 23:54:29.765430 kernel: efi: EFI v2.7 by EDK II Sep 4 23:54:29.765436 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb832018 ACPI 2.0=0xdbfd0018 RNG=0xdbfd0a18 MEMRESERVE=0xdb838218 Sep 4 23:54:29.765441 kernel: random: crng init done Sep 4 23:54:29.765448 kernel: secureboot: Secure boot disabled Sep 4 23:54:29.765454 kernel: ACPI: Early table checksum verification disabled Sep 4 23:54:29.765459 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Sep 4 23:54:29.765467 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Sep 4 23:54:29.765473 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 23:54:29.765478 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 23:54:29.765484 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 23:54:29.765490 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 23:54:29.765496 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 23:54:29.765504 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 23:54:29.765510 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 23:54:29.765516 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 23:54:29.765521 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 4 23:54:29.765537 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Sep 4 23:54:29.765544 kernel: ACPI: Use ACPI SPCR as default console: No Sep 4 23:54:29.765550 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Sep 4 23:54:29.765556 kernel: NODE_DATA(0) allocated [mem 0xdc965a00-0xdc96cfff] Sep 4 23:54:29.765561 kernel: Zone ranges: Sep 4 23:54:29.765567 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Sep 4 23:54:29.765575 kernel: DMA32 empty Sep 4 23:54:29.765581 kernel: Normal empty Sep 4 23:54:29.765587 kernel: Device empty Sep 4 23:54:29.765593 kernel: Movable zone start for each node Sep 4 23:54:29.765598 kernel: Early memory node ranges Sep 4 23:54:29.765604 kernel: node 0: [mem 0x0000000040000000-0x00000000db81ffff] Sep 4 23:54:29.765610 kernel: node 0: [mem 0x00000000db820000-0x00000000db82ffff] Sep 4 23:54:29.765616 kernel: node 0: [mem 0x00000000db830000-0x00000000dc09ffff] Sep 4 23:54:29.765622 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Sep 4 23:54:29.765628 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Sep 4 23:54:29.765634 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Sep 4 23:54:29.765640 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Sep 4 23:54:29.765647 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Sep 4 23:54:29.765658 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Sep 4 23:54:29.765667 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Sep 4 23:54:29.765679 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Sep 4 23:54:29.765686 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Sep 4 23:54:29.765694 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Sep 4 23:54:29.765702 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Sep 4 23:54:29.765708 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Sep 4 23:54:29.765714 kernel: cma: Reserved 16 MiB at 0x00000000d8000000 on node -1 Sep 4 23:54:29.765721 kernel: psci: probing for conduit method from ACPI. Sep 4 23:54:29.765727 kernel: psci: PSCIv1.1 detected in firmware. Sep 4 23:54:29.765753 kernel: psci: Using standard PSCI v0.2 function IDs Sep 4 23:54:29.765760 kernel: psci: Trusted OS migration not required Sep 4 23:54:29.765767 kernel: psci: SMC Calling Convention v1.1 Sep 4 23:54:29.765773 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Sep 4 23:54:29.765779 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Sep 4 23:54:29.765787 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Sep 4 23:54:29.765794 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Sep 4 23:54:29.765800 kernel: Detected PIPT I-cache on CPU0 Sep 4 23:54:29.765806 kernel: CPU features: detected: GIC system register CPU interface Sep 4 23:54:29.765813 kernel: CPU features: detected: Spectre-v4 Sep 4 23:54:29.765819 kernel: CPU features: detected: Spectre-BHB Sep 4 23:54:29.765825 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 4 23:54:29.765831 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 4 23:54:29.765837 kernel: CPU features: detected: ARM erratum 1418040 Sep 4 23:54:29.765844 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 4 23:54:29.766417 kernel: alternatives: applying boot alternatives Sep 4 23:54:29.766430 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=936dbc4ea592050e15794e1e6e7f70cd7cba0dbef72270410b4bbc6a29324de7 Sep 4 23:54:29.766440 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 23:54:29.766447 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 23:54:29.766453 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 23:54:29.766459 kernel: Fallback order for Node 0: 0 Sep 4 23:54:29.766466 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Sep 4 23:54:29.766472 kernel: Policy zone: DMA Sep 4 23:54:29.766478 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 23:54:29.766484 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Sep 4 23:54:29.766491 kernel: software IO TLB: area num 4. Sep 4 23:54:29.766497 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Sep 4 23:54:29.766503 kernel: software IO TLB: mapped [mem 0x00000000d7c00000-0x00000000d8000000] (4MB) Sep 4 23:54:29.766511 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 4 23:54:29.766518 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 23:54:29.766525 kernel: rcu: RCU event tracing is enabled. Sep 4 23:54:29.766543 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 4 23:54:29.766550 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 23:54:29.766556 kernel: Tracing variant of Tasks RCU enabled. Sep 4 23:54:29.766563 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 23:54:29.766569 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 4 23:54:29.766576 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 4 23:54:29.766582 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 4 23:54:29.766588 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 4 23:54:29.766597 kernel: GICv3: 256 SPIs implemented Sep 4 23:54:29.766603 kernel: GICv3: 0 Extended SPIs implemented Sep 4 23:54:29.766610 kernel: Root IRQ handler: gic_handle_irq Sep 4 23:54:29.766616 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 4 23:54:29.766622 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Sep 4 23:54:29.766629 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Sep 4 23:54:29.766635 kernel: ITS [mem 0x08080000-0x0809ffff] Sep 4 23:54:29.766641 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Sep 4 23:54:29.766648 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Sep 4 23:54:29.766655 kernel: GICv3: using LPI property table @0x0000000040130000 Sep 4 23:54:29.766661 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Sep 4 23:54:29.766668 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 23:54:29.766675 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 23:54:29.766682 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 4 23:54:29.766688 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 4 23:54:29.766695 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 4 23:54:29.766701 kernel: arm-pv: using stolen time PV Sep 4 23:54:29.766708 kernel: Console: colour dummy device 80x25 Sep 4 23:54:29.766714 kernel: ACPI: Core revision 20240827 Sep 4 23:54:29.766721 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 4 23:54:29.766728 kernel: pid_max: default: 32768 minimum: 301 Sep 4 23:54:29.766747 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 4 23:54:29.766757 kernel: landlock: Up and running. Sep 4 23:54:29.766763 kernel: SELinux: Initializing. Sep 4 23:54:29.766770 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 23:54:29.766777 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 23:54:29.766784 kernel: rcu: Hierarchical SRCU implementation. Sep 4 23:54:29.766791 kernel: rcu: Max phase no-delay instances is 400. Sep 4 23:54:29.766797 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 4 23:54:29.766804 kernel: Remapping and enabling EFI services. Sep 4 23:54:29.766810 kernel: smp: Bringing up secondary CPUs ... Sep 4 23:54:29.766823 kernel: Detected PIPT I-cache on CPU1 Sep 4 23:54:29.766830 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Sep 4 23:54:29.766837 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Sep 4 23:54:29.766845 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 23:54:29.766852 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 4 23:54:29.766859 kernel: Detected PIPT I-cache on CPU2 Sep 4 23:54:29.766866 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Sep 4 23:54:29.766873 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Sep 4 23:54:29.766881 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 23:54:29.766888 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Sep 4 23:54:29.766895 kernel: Detected PIPT I-cache on CPU3 Sep 4 23:54:29.766901 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Sep 4 23:54:29.766908 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Sep 4 23:54:29.766915 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 23:54:29.766922 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Sep 4 23:54:29.766928 kernel: smp: Brought up 1 node, 4 CPUs Sep 4 23:54:29.766935 kernel: SMP: Total of 4 processors activated. Sep 4 23:54:29.766943 kernel: CPU: All CPU(s) started at EL1 Sep 4 23:54:29.766950 kernel: CPU features: detected: 32-bit EL0 Support Sep 4 23:54:29.766957 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 4 23:54:29.766964 kernel: CPU features: detected: Common not Private translations Sep 4 23:54:29.766970 kernel: CPU features: detected: CRC32 instructions Sep 4 23:54:29.766977 kernel: CPU features: detected: Enhanced Virtualization Traps Sep 4 23:54:29.766984 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 4 23:54:29.766991 kernel: CPU features: detected: LSE atomic instructions Sep 4 23:54:29.766998 kernel: CPU features: detected: Privileged Access Never Sep 4 23:54:29.767008 kernel: CPU features: detected: RAS Extension Support Sep 4 23:54:29.767016 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Sep 4 23:54:29.767023 kernel: alternatives: applying system-wide alternatives Sep 4 23:54:29.767030 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Sep 4 23:54:29.767037 kernel: Memory: 2424480K/2572288K available (11136K kernel code, 2436K rwdata, 9076K rodata, 38976K init, 1038K bss, 125472K reserved, 16384K cma-reserved) Sep 4 23:54:29.767044 kernel: devtmpfs: initialized Sep 4 23:54:29.767051 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 23:54:29.767058 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 4 23:54:29.767065 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 4 23:54:29.767073 kernel: 0 pages in range for non-PLT usage Sep 4 23:54:29.767080 kernel: 508560 pages in range for PLT usage Sep 4 23:54:29.767087 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 23:54:29.767094 kernel: SMBIOS 3.0.0 present. Sep 4 23:54:29.767101 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Sep 4 23:54:29.767107 kernel: DMI: Memory slots populated: 1/1 Sep 4 23:54:29.767114 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 23:54:29.767121 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 4 23:54:29.767128 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 4 23:54:29.767137 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 4 23:54:29.767144 kernel: audit: initializing netlink subsys (disabled) Sep 4 23:54:29.767150 kernel: audit: type=2000 audit(0.029:1): state=initialized audit_enabled=0 res=1 Sep 4 23:54:29.767157 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 23:54:29.767164 kernel: cpuidle: using governor menu Sep 4 23:54:29.767171 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 4 23:54:29.767177 kernel: ASID allocator initialised with 32768 entries Sep 4 23:54:29.767184 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 23:54:29.767191 kernel: Serial: AMBA PL011 UART driver Sep 4 23:54:29.767199 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 23:54:29.767206 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 23:54:29.767213 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 4 23:54:29.767219 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 4 23:54:29.767226 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 23:54:29.767233 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 23:54:29.767240 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 4 23:54:29.767247 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 4 23:54:29.767253 kernel: ACPI: Added _OSI(Module Device) Sep 4 23:54:29.767260 kernel: ACPI: Added _OSI(Processor Device) Sep 4 23:54:29.767268 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 23:54:29.767275 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 23:54:29.767282 kernel: ACPI: Interpreter enabled Sep 4 23:54:29.767288 kernel: ACPI: Using GIC for interrupt routing Sep 4 23:54:29.767295 kernel: ACPI: MCFG table detected, 1 entries Sep 4 23:54:29.767302 kernel: ACPI: CPU0 has been hot-added Sep 4 23:54:29.767309 kernel: ACPI: CPU1 has been hot-added Sep 4 23:54:29.767315 kernel: ACPI: CPU2 has been hot-added Sep 4 23:54:29.767322 kernel: ACPI: CPU3 has been hot-added Sep 4 23:54:29.767331 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Sep 4 23:54:29.767337 kernel: printk: legacy console [ttyAMA0] enabled Sep 4 23:54:29.767344 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 4 23:54:29.767501 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 4 23:54:29.767578 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Sep 4 23:54:29.767639 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Sep 4 23:54:29.767695 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Sep 4 23:54:29.767787 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Sep 4 23:54:29.767798 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Sep 4 23:54:29.767805 kernel: PCI host bridge to bus 0000:00 Sep 4 23:54:29.767873 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Sep 4 23:54:29.767927 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Sep 4 23:54:29.767978 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Sep 4 23:54:29.768029 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 4 23:54:29.768113 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Sep 4 23:54:29.768193 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 4 23:54:29.768260 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Sep 4 23:54:29.768320 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Sep 4 23:54:29.768381 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Sep 4 23:54:29.768440 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Sep 4 23:54:29.768499 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Sep 4 23:54:29.768573 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Sep 4 23:54:29.768628 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Sep 4 23:54:29.768679 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Sep 4 23:54:29.768820 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Sep 4 23:54:29.768832 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Sep 4 23:54:29.768840 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Sep 4 23:54:29.768847 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Sep 4 23:54:29.768857 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Sep 4 23:54:29.768864 kernel: iommu: Default domain type: Translated Sep 4 23:54:29.768871 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 4 23:54:29.768878 kernel: efivars: Registered efivars operations Sep 4 23:54:29.768885 kernel: vgaarb: loaded Sep 4 23:54:29.768892 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 4 23:54:29.768899 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 23:54:29.768906 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 23:54:29.768912 kernel: pnp: PnP ACPI init Sep 4 23:54:29.768987 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Sep 4 23:54:29.768998 kernel: pnp: PnP ACPI: found 1 devices Sep 4 23:54:29.769005 kernel: NET: Registered PF_INET protocol family Sep 4 23:54:29.769012 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 4 23:54:29.769019 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 4 23:54:29.769026 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 23:54:29.769034 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 4 23:54:29.769041 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 4 23:54:29.769050 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 4 23:54:29.769057 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 23:54:29.769064 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 23:54:29.769071 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 23:54:29.769077 kernel: PCI: CLS 0 bytes, default 64 Sep 4 23:54:29.769084 kernel: kvm [1]: HYP mode not available Sep 4 23:54:29.769091 kernel: Initialise system trusted keyrings Sep 4 23:54:29.769098 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 4 23:54:29.769105 kernel: Key type asymmetric registered Sep 4 23:54:29.769113 kernel: Asymmetric key parser 'x509' registered Sep 4 23:54:29.769119 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Sep 4 23:54:29.769126 kernel: io scheduler mq-deadline registered Sep 4 23:54:29.769133 kernel: io scheduler kyber registered Sep 4 23:54:29.769140 kernel: io scheduler bfq registered Sep 4 23:54:29.769147 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Sep 4 23:54:29.769154 kernel: ACPI: button: Power Button [PWRB] Sep 4 23:54:29.769161 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Sep 4 23:54:29.769223 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Sep 4 23:54:29.769232 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 23:54:29.769241 kernel: thunder_xcv, ver 1.0 Sep 4 23:54:29.769255 kernel: thunder_bgx, ver 1.0 Sep 4 23:54:29.769262 kernel: nicpf, ver 1.0 Sep 4 23:54:29.769269 kernel: nicvf, ver 1.0 Sep 4 23:54:29.769339 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 4 23:54:29.769395 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-09-04T23:54:29 UTC (1757030069) Sep 4 23:54:29.769404 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 4 23:54:29.769412 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Sep 4 23:54:29.769420 kernel: watchdog: NMI not fully supported Sep 4 23:54:29.769427 kernel: watchdog: Hard watchdog permanently disabled Sep 4 23:54:29.769434 kernel: NET: Registered PF_INET6 protocol family Sep 4 23:54:29.769441 kernel: Segment Routing with IPv6 Sep 4 23:54:29.769448 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 23:54:29.769455 kernel: NET: Registered PF_PACKET protocol family Sep 4 23:54:29.769462 kernel: Key type dns_resolver registered Sep 4 23:54:29.769469 kernel: registered taskstats version 1 Sep 4 23:54:29.769475 kernel: Loading compiled-in X.509 certificates Sep 4 23:54:29.769484 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.44-flatcar: 076c0e39153760a09e2827c98096964655099fd6' Sep 4 23:54:29.769491 kernel: Demotion targets for Node 0: null Sep 4 23:54:29.769498 kernel: Key type .fscrypt registered Sep 4 23:54:29.769504 kernel: Key type fscrypt-provisioning registered Sep 4 23:54:29.769511 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 23:54:29.769518 kernel: ima: Allocated hash algorithm: sha1 Sep 4 23:54:29.769525 kernel: ima: No architecture policies found Sep 4 23:54:29.769540 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 4 23:54:29.769550 kernel: clk: Disabling unused clocks Sep 4 23:54:29.769557 kernel: PM: genpd: Disabling unused power domains Sep 4 23:54:29.769563 kernel: Warning: unable to open an initial console. Sep 4 23:54:29.769570 kernel: Freeing unused kernel memory: 38976K Sep 4 23:54:29.769577 kernel: Run /init as init process Sep 4 23:54:29.769584 kernel: with arguments: Sep 4 23:54:29.769591 kernel: /init Sep 4 23:54:29.769598 kernel: with environment: Sep 4 23:54:29.769604 kernel: HOME=/ Sep 4 23:54:29.769611 kernel: TERM=linux Sep 4 23:54:29.769620 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 23:54:29.769627 systemd[1]: Successfully made /usr/ read-only. Sep 4 23:54:29.769637 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 4 23:54:29.769646 systemd[1]: Detected virtualization kvm. Sep 4 23:54:29.769653 systemd[1]: Detected architecture arm64. Sep 4 23:54:29.769660 systemd[1]: Running in initrd. Sep 4 23:54:29.769667 systemd[1]: No hostname configured, using default hostname. Sep 4 23:54:29.769677 systemd[1]: Hostname set to . Sep 4 23:54:29.769684 systemd[1]: Initializing machine ID from VM UUID. Sep 4 23:54:29.769691 systemd[1]: Queued start job for default target initrd.target. Sep 4 23:54:29.769699 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 23:54:29.769706 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 23:54:29.769714 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 23:54:29.769722 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 23:54:29.769739 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 23:54:29.769751 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 23:54:29.769759 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 23:54:29.769767 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 23:54:29.769775 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 23:54:29.769782 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 23:54:29.769790 systemd[1]: Reached target paths.target - Path Units. Sep 4 23:54:29.769797 systemd[1]: Reached target slices.target - Slice Units. Sep 4 23:54:29.769807 systemd[1]: Reached target swap.target - Swaps. Sep 4 23:54:29.769814 systemd[1]: Reached target timers.target - Timer Units. Sep 4 23:54:29.769822 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 23:54:29.769829 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 23:54:29.769837 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 23:54:29.769844 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 4 23:54:29.769852 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 23:54:29.769860 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 23:54:29.769869 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 23:54:29.769876 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 23:54:29.769884 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 23:54:29.769892 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 23:54:29.769899 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 23:54:29.769907 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 4 23:54:29.769915 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 23:54:29.769922 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 23:54:29.769929 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 23:54:29.769938 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:54:29.769946 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 23:54:29.769954 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 23:54:29.769962 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 23:54:29.769992 systemd-journald[244]: Collecting audit messages is disabled. Sep 4 23:54:29.770012 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 23:54:29.770021 systemd-journald[244]: Journal started Sep 4 23:54:29.770041 systemd-journald[244]: Runtime Journal (/run/log/journal/abafb2a1727c48cfad60215845310460) is 6M, max 48.5M, 42.4M free. Sep 4 23:54:29.764640 systemd-modules-load[246]: Inserted module 'overlay' Sep 4 23:54:29.772191 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 23:54:29.773224 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:54:29.778761 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 4 23:54:29.781327 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 23:54:29.782930 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 23:54:29.785692 kernel: Bridge firewalling registered Sep 4 23:54:29.784665 systemd-modules-load[246]: Inserted module 'br_netfilter' Sep 4 23:54:29.786995 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 23:54:29.790745 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 23:54:29.792664 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 23:54:29.794553 systemd-tmpfiles[264]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 4 23:54:29.803365 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 23:54:29.804597 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:54:29.807117 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 23:54:29.809907 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 23:54:29.810943 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 23:54:29.813156 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 23:54:29.814326 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 23:54:29.828010 dracut-cmdline[284]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=936dbc4ea592050e15794e1e6e7f70cd7cba0dbef72270410b4bbc6a29324de7 Sep 4 23:54:29.843044 systemd-resolved[287]: Positive Trust Anchors: Sep 4 23:54:29.843059 systemd-resolved[287]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 23:54:29.843091 systemd-resolved[287]: 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 4 23:54:29.847947 systemd-resolved[287]: Defaulting to hostname 'linux'. Sep 4 23:54:29.849078 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 23:54:29.850675 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 23:54:29.909758 kernel: SCSI subsystem initialized Sep 4 23:54:29.914746 kernel: Loading iSCSI transport class v2.0-870. Sep 4 23:54:29.921795 kernel: iscsi: registered transport (tcp) Sep 4 23:54:29.936025 kernel: iscsi: registered transport (qla4xxx) Sep 4 23:54:29.936054 kernel: QLogic iSCSI HBA Driver Sep 4 23:54:29.951591 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 23:54:29.971728 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 23:54:29.973715 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 23:54:30.018900 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 23:54:30.021011 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 23:54:30.080762 kernel: raid6: neonx8 gen() 15603 MB/s Sep 4 23:54:30.097755 kernel: raid6: neonx4 gen() 15676 MB/s Sep 4 23:54:30.114751 kernel: raid6: neonx2 gen() 13155 MB/s Sep 4 23:54:30.131747 kernel: raid6: neonx1 gen() 10457 MB/s Sep 4 23:54:30.148749 kernel: raid6: int64x8 gen() 6871 MB/s Sep 4 23:54:30.165755 kernel: raid6: int64x4 gen() 7334 MB/s Sep 4 23:54:30.182750 kernel: raid6: int64x2 gen() 6077 MB/s Sep 4 23:54:30.199758 kernel: raid6: int64x1 gen() 5049 MB/s Sep 4 23:54:30.199797 kernel: raid6: using algorithm neonx4 gen() 15676 MB/s Sep 4 23:54:30.216752 kernel: raid6: .... xor() 12320 MB/s, rmw enabled Sep 4 23:54:30.216764 kernel: raid6: using neon recovery algorithm Sep 4 23:54:30.221766 kernel: xor: measuring software checksum speed Sep 4 23:54:30.221781 kernel: 8regs : 21601 MB/sec Sep 4 23:54:30.222838 kernel: 32regs : 21670 MB/sec Sep 4 23:54:30.222860 kernel: arm64_neon : 28147 MB/sec Sep 4 23:54:30.222877 kernel: xor: using function: arm64_neon (28147 MB/sec) Sep 4 23:54:30.274762 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 23:54:30.280521 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 23:54:30.282766 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 23:54:30.309365 systemd-udevd[497]: Using default interface naming scheme 'v255'. Sep 4 23:54:30.313408 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 23:54:30.315167 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 23:54:30.339163 dracut-pre-trigger[505]: rd.md=0: removing MD RAID activation Sep 4 23:54:30.361699 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 23:54:30.363726 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 23:54:30.420698 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 23:54:30.423155 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 23:54:30.466260 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Sep 4 23:54:30.471231 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 4 23:54:30.474895 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 4 23:54:30.474930 kernel: GPT:9289727 != 19775487 Sep 4 23:54:30.474939 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 4 23:54:30.476227 kernel: GPT:9289727 != 19775487 Sep 4 23:54:30.476274 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 4 23:54:30.487161 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 23:54:30.493556 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:54:30.493675 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:54:30.499018 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:54:30.501997 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:54:30.525356 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 23:54:30.526637 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 23:54:30.533849 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 4 23:54:30.534782 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 4 23:54:30.538221 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:54:30.547180 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 4 23:54:30.558821 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 4 23:54:30.559774 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 23:54:30.561483 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 23:54:30.563155 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 23:54:30.565318 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 23:54:30.566857 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 23:54:30.592100 disk-uuid[588]: Primary Header is updated. Sep 4 23:54:30.592100 disk-uuid[588]: Secondary Entries is updated. Sep 4 23:54:30.592100 disk-uuid[588]: Secondary Header is updated. Sep 4 23:54:30.595871 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 23:54:30.600932 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 23:54:31.605679 disk-uuid[592]: The operation has completed successfully. Sep 4 23:54:31.606538 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 4 23:54:31.631533 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 23:54:31.631645 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 23:54:31.656585 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 23:54:31.681541 sh[610]: Success Sep 4 23:54:31.693175 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 23:54:31.693211 kernel: device-mapper: uevent: version 1.0.3 Sep 4 23:54:31.694073 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 4 23:54:31.700757 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Sep 4 23:54:31.724465 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 23:54:31.726849 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 23:54:31.743992 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 23:54:31.749877 kernel: BTRFS: device fsid 7cf88bee-c029-4534-8152-24a8f9f8db3f devid 1 transid 40 /dev/mapper/usr (253:0) scanned by mount (623) Sep 4 23:54:31.749908 kernel: BTRFS info (device dm-0): first mount of filesystem 7cf88bee-c029-4534-8152-24a8f9f8db3f Sep 4 23:54:31.749918 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 4 23:54:31.753868 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 23:54:31.753895 kernel: BTRFS info (device dm-0): enabling free space tree Sep 4 23:54:31.754686 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 23:54:31.755777 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 4 23:54:31.756874 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 23:54:31.757531 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 23:54:31.758862 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 23:54:31.779642 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (653) Sep 4 23:54:31.779679 kernel: BTRFS info (device vda6): first mount of filesystem 6c344b23-2ce1-4a61-81ba-a1268f9a3fe2 Sep 4 23:54:31.779689 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 23:54:31.782758 kernel: BTRFS info (device vda6): turning on async discard Sep 4 23:54:31.782788 kernel: BTRFS info (device vda6): enabling free space tree Sep 4 23:54:31.786756 kernel: BTRFS info (device vda6): last unmount of filesystem 6c344b23-2ce1-4a61-81ba-a1268f9a3fe2 Sep 4 23:54:31.787604 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 23:54:31.789231 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 23:54:31.846791 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 23:54:31.849260 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 23:54:31.885996 systemd-networkd[798]: lo: Link UP Sep 4 23:54:31.886009 systemd-networkd[798]: lo: Gained carrier Sep 4 23:54:31.886771 systemd-networkd[798]: Enumeration completed Sep 4 23:54:31.886859 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 23:54:31.887221 systemd-networkd[798]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:54:31.887224 systemd-networkd[798]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 23:54:31.887650 systemd-networkd[798]: eth0: Link UP Sep 4 23:54:31.887939 systemd-networkd[798]: eth0: Gained carrier Sep 4 23:54:31.893250 ignition[700]: Ignition 2.21.0 Sep 4 23:54:31.887948 systemd-networkd[798]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:54:31.893257 ignition[700]: Stage: fetch-offline Sep 4 23:54:31.888497 systemd[1]: Reached target network.target - Network. Sep 4 23:54:31.893287 ignition[700]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:54:31.893295 ignition[700]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 23:54:31.893457 ignition[700]: parsed url from cmdline: "" Sep 4 23:54:31.893460 ignition[700]: no config URL provided Sep 4 23:54:31.893465 ignition[700]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 23:54:31.893471 ignition[700]: no config at "/usr/lib/ignition/user.ign" Sep 4 23:54:31.893490 ignition[700]: op(1): [started] loading QEMU firmware config module Sep 4 23:54:31.893494 ignition[700]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 4 23:54:31.904599 ignition[700]: op(1): [finished] loading QEMU firmware config module Sep 4 23:54:31.905778 systemd-networkd[798]: eth0: DHCPv4 address 10.0.0.94/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 23:54:31.911803 ignition[700]: parsing config with SHA512: 7d514e20b3aa66931c767c27fa9150d49764b8a759c3d486a399d0d5f03b97c7ace255f15c54990e9ee9078975c4639da56a5a4653fe71cdbda8a5898d6b4212 Sep 4 23:54:31.914719 unknown[700]: fetched base config from "system" Sep 4 23:54:31.914747 unknown[700]: fetched user config from "qemu" Sep 4 23:54:31.915000 ignition[700]: fetch-offline: fetch-offline passed Sep 4 23:54:31.915053 ignition[700]: Ignition finished successfully Sep 4 23:54:31.917163 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 23:54:31.918664 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 4 23:54:31.919434 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 23:54:31.948332 ignition[811]: Ignition 2.21.0 Sep 4 23:54:31.948352 ignition[811]: Stage: kargs Sep 4 23:54:31.948478 ignition[811]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:54:31.948486 ignition[811]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 23:54:31.949072 ignition[811]: kargs: kargs passed Sep 4 23:54:31.949110 ignition[811]: Ignition finished successfully Sep 4 23:54:31.951481 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 23:54:31.953648 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 23:54:31.978704 ignition[819]: Ignition 2.21.0 Sep 4 23:54:31.978721 ignition[819]: Stage: disks Sep 4 23:54:31.978880 ignition[819]: no configs at "/usr/lib/ignition/base.d" Sep 4 23:54:31.978889 ignition[819]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 23:54:31.982010 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 23:54:31.980099 ignition[819]: disks: disks passed Sep 4 23:54:31.983385 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 23:54:31.980173 ignition[819]: Ignition finished successfully Sep 4 23:54:31.984507 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 23:54:31.985723 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 23:54:31.987235 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 23:54:31.988479 systemd[1]: Reached target basic.target - Basic System. Sep 4 23:54:31.991140 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 23:54:32.015350 systemd-fsck[830]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 4 23:54:32.020180 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 23:54:32.022476 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 23:54:32.089753 kernel: EXT4-fs (vda9): mounted filesystem c1aea666-7bbc-4a3b-a66d-c37ebbad8baa r/w with ordered data mode. Quota mode: none. Sep 4 23:54:32.090222 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 23:54:32.091334 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 23:54:32.093497 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 23:54:32.094951 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 23:54:32.095779 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 4 23:54:32.095821 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 23:54:32.095848 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 23:54:32.108435 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 23:54:32.110902 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 23:54:32.114986 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (838) Sep 4 23:54:32.115008 kernel: BTRFS info (device vda6): first mount of filesystem 6c344b23-2ce1-4a61-81ba-a1268f9a3fe2 Sep 4 23:54:32.115018 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 23:54:32.116754 kernel: BTRFS info (device vda6): turning on async discard Sep 4 23:54:32.116777 kernel: BTRFS info (device vda6): enabling free space tree Sep 4 23:54:32.117434 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 23:54:32.149232 initrd-setup-root[863]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 23:54:32.152432 initrd-setup-root[870]: cut: /sysroot/etc/group: No such file or directory Sep 4 23:54:32.155682 initrd-setup-root[877]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 23:54:32.159617 initrd-setup-root[884]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 23:54:32.229388 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 23:54:32.231620 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 23:54:32.233156 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 23:54:32.250745 kernel: BTRFS info (device vda6): last unmount of filesystem 6c344b23-2ce1-4a61-81ba-a1268f9a3fe2 Sep 4 23:54:32.270892 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 23:54:32.283231 ignition[953]: INFO : Ignition 2.21.0 Sep 4 23:54:32.283231 ignition[953]: INFO : Stage: mount Sep 4 23:54:32.284913 ignition[953]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 23:54:32.284913 ignition[953]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 23:54:32.284913 ignition[953]: INFO : mount: mount passed Sep 4 23:54:32.284913 ignition[953]: INFO : Ignition finished successfully Sep 4 23:54:32.285591 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 23:54:32.287593 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 23:54:32.863641 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 23:54:32.865151 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 23:54:32.881268 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (965) Sep 4 23:54:32.881300 kernel: BTRFS info (device vda6): first mount of filesystem 6c344b23-2ce1-4a61-81ba-a1268f9a3fe2 Sep 4 23:54:32.881310 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 23:54:32.884251 kernel: BTRFS info (device vda6): turning on async discard Sep 4 23:54:32.884277 kernel: BTRFS info (device vda6): enabling free space tree Sep 4 23:54:32.885557 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 23:54:32.915697 ignition[982]: INFO : Ignition 2.21.0 Sep 4 23:54:32.915697 ignition[982]: INFO : Stage: files Sep 4 23:54:32.917178 ignition[982]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 23:54:32.917178 ignition[982]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 23:54:32.917178 ignition[982]: DEBUG : files: compiled without relabeling support, skipping Sep 4 23:54:32.919714 ignition[982]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 23:54:32.919714 ignition[982]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 23:54:32.919714 ignition[982]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 23:54:32.919714 ignition[982]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 23:54:32.923963 ignition[982]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 23:54:32.923963 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 4 23:54:32.923963 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 4 23:54:32.923963 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 23:54:32.923963 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 23:54:32.923963 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 4 23:54:32.919859 unknown[982]: wrote ssh authorized keys file for user: core Sep 4 23:54:32.932646 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 4 23:54:32.932646 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 4 23:54:32.932646 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.33.0-arm64.raw: attempt #1 Sep 4 23:54:33.199952 systemd-networkd[798]: eth0: Gained IPv6LL Sep 4 23:54:33.470647 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 4 23:54:34.202768 ignition[982]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.33.0-arm64.raw" Sep 4 23:54:34.202768 ignition[982]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 4 23:54:34.206528 ignition[982]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 23:54:34.206528 ignition[982]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 4 23:54:34.206528 ignition[982]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 4 23:54:34.206528 ignition[982]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 4 23:54:34.220697 ignition[982]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 23:54:34.224213 ignition[982]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 4 23:54:34.226657 ignition[982]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 4 23:54:34.226657 ignition[982]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 23:54:34.226657 ignition[982]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 23:54:34.226657 ignition[982]: INFO : files: files passed Sep 4 23:54:34.226657 ignition[982]: INFO : Ignition finished successfully Sep 4 23:54:34.228177 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 23:54:34.232336 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 23:54:34.234876 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 23:54:34.249426 initrd-setup-root-after-ignition[1010]: grep: /sysroot/oem/oem-release: No such file or directory Sep 4 23:54:34.249705 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 23:54:34.251785 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 23:54:34.254361 initrd-setup-root-after-ignition[1013]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 23:54:34.254361 initrd-setup-root-after-ignition[1013]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 23:54:34.257114 initrd-setup-root-after-ignition[1017]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 23:54:34.256381 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 23:54:34.258441 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 23:54:34.261819 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 23:54:34.310916 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 23:54:34.311048 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 23:54:34.313327 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 23:54:34.316612 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 23:54:34.318277 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 23:54:34.319205 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 23:54:34.351100 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 23:54:34.353357 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 23:54:34.378181 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 23:54:34.380618 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 23:54:34.382921 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 23:54:34.384698 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 23:54:34.384843 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 23:54:34.388036 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 23:54:34.389058 systemd[1]: Stopped target basic.target - Basic System. Sep 4 23:54:34.391034 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 23:54:34.394376 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 23:54:34.396134 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 23:54:34.397874 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 4 23:54:34.399893 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 23:54:34.401917 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 23:54:34.404854 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 23:54:34.406827 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 23:54:34.408539 systemd[1]: Stopped target swap.target - Swaps. Sep 4 23:54:34.410556 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 23:54:34.410696 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 23:54:34.412640 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 23:54:34.414548 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 23:54:34.416337 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 23:54:34.419798 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 23:54:34.420851 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 23:54:34.420983 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 23:54:34.423469 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 23:54:34.423597 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 23:54:34.425380 systemd[1]: Stopped target paths.target - Path Units. Sep 4 23:54:34.426693 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 23:54:34.426818 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 23:54:34.428885 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 23:54:34.430066 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 23:54:34.431554 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 23:54:34.431644 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 23:54:34.433711 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 23:54:34.433821 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 23:54:34.435139 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 23:54:34.435271 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 23:54:34.436844 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 23:54:34.436953 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 23:54:34.439105 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 23:54:34.441057 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 23:54:34.442620 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 23:54:34.442748 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 23:54:34.445332 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 23:54:34.445446 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 23:54:34.450791 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 23:54:34.453904 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 23:54:34.462864 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 23:54:34.469723 ignition[1037]: INFO : Ignition 2.21.0 Sep 4 23:54:34.469723 ignition[1037]: INFO : Stage: umount Sep 4 23:54:34.472356 ignition[1037]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 23:54:34.472356 ignition[1037]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 4 23:54:34.472356 ignition[1037]: INFO : umount: umount passed Sep 4 23:54:34.472356 ignition[1037]: INFO : Ignition finished successfully Sep 4 23:54:34.476469 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 23:54:34.476590 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 23:54:34.477917 systemd[1]: Stopped target network.target - Network. Sep 4 23:54:34.479324 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 23:54:34.479390 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 23:54:34.480863 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 23:54:34.480904 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 23:54:34.482267 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 23:54:34.482312 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 23:54:34.483839 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 23:54:34.483878 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 23:54:34.485523 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 23:54:34.486916 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 23:54:34.495927 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 23:54:34.496048 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 23:54:34.498850 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 4 23:54:34.499137 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 23:54:34.499179 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 23:54:34.502611 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 4 23:54:34.504640 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 23:54:34.504839 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 23:54:34.507633 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 4 23:54:34.507881 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 4 23:54:34.509762 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 23:54:34.509799 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 23:54:34.512437 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 23:54:34.513286 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 23:54:34.513345 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 23:54:34.515718 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 23:54:34.515778 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 23:54:34.518772 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 23:54:34.518824 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 23:54:34.520683 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 23:54:34.524244 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 4 23:54:34.524572 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 23:54:34.524660 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 23:54:34.527142 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 23:54:34.527229 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 23:54:34.532433 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 23:54:34.532567 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 23:54:34.535397 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 23:54:34.535573 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 23:54:34.537553 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 23:54:34.537597 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 23:54:34.539197 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 23:54:34.539229 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 23:54:34.540585 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 23:54:34.540631 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 23:54:34.542723 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 23:54:34.542784 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 23:54:34.545148 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 23:54:34.545203 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 23:54:34.548196 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 23:54:34.549584 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 4 23:54:34.549641 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 23:54:34.552782 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 23:54:34.552825 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 23:54:34.555943 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 4 23:54:34.555987 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 23:54:34.558832 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 23:54:34.558876 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 23:54:34.560854 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 23:54:34.560900 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:54:34.577077 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 23:54:34.577985 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 23:54:34.579076 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 23:54:34.581251 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 23:54:34.590635 systemd[1]: Switching root. Sep 4 23:54:34.632894 systemd-journald[244]: Journal stopped Sep 4 23:54:35.354597 systemd-journald[244]: Received SIGTERM from PID 1 (systemd). Sep 4 23:54:35.354651 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 23:54:35.354671 kernel: SELinux: policy capability open_perms=1 Sep 4 23:54:35.354680 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 23:54:35.354690 kernel: SELinux: policy capability always_check_network=0 Sep 4 23:54:35.354703 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 23:54:35.354712 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 23:54:35.354721 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 23:54:35.354832 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 23:54:35.354844 kernel: SELinux: policy capability userspace_initial_context=0 Sep 4 23:54:35.354854 kernel: audit: type=1403 audit(1757030074.759:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 23:54:35.354868 systemd[1]: Successfully loaded SELinux policy in 30.891ms. Sep 4 23:54:35.354885 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.144ms. Sep 4 23:54:35.354896 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 4 23:54:35.354906 systemd[1]: Detected virtualization kvm. Sep 4 23:54:35.354916 systemd[1]: Detected architecture arm64. Sep 4 23:54:35.354926 systemd[1]: Detected first boot. Sep 4 23:54:35.354936 systemd[1]: Initializing machine ID from VM UUID. Sep 4 23:54:35.354947 zram_generator::config[1084]: No configuration found. Sep 4 23:54:35.354959 kernel: NET: Registered PF_VSOCK protocol family Sep 4 23:54:35.354968 systemd[1]: Populated /etc with preset unit settings. Sep 4 23:54:35.354978 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 4 23:54:35.354988 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 23:54:35.354998 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 23:54:35.355008 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 23:54:35.355019 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 23:54:35.355036 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 23:54:35.355046 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 23:54:35.355056 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 23:54:35.355066 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 23:54:35.355076 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 23:54:35.355086 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 23:54:35.355096 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 23:54:35.355106 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 23:54:35.355135 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 23:54:35.355146 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 23:54:35.355157 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 23:54:35.355168 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 23:54:35.355178 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 23:54:35.355188 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 4 23:54:35.355198 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 23:54:35.355209 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 23:54:35.355219 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 23:54:35.355231 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 23:54:35.355241 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 23:54:35.355250 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 23:54:35.355266 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 23:54:35.355278 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 23:54:35.355288 systemd[1]: Reached target slices.target - Slice Units. Sep 4 23:54:35.355298 systemd[1]: Reached target swap.target - Swaps. Sep 4 23:54:35.355307 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 23:54:35.355318 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 23:54:35.355330 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 4 23:54:35.355340 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 23:54:35.355350 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 23:54:35.355360 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 23:54:35.355370 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 23:54:35.355381 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 23:54:35.355397 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 23:54:35.355408 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 23:54:35.355417 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 23:54:35.355429 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 23:54:35.355439 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 23:54:35.355450 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 4 23:54:35.355460 systemd[1]: Reached target machines.target - Containers. Sep 4 23:54:35.355470 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 23:54:35.355501 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:54:35.355517 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 23:54:35.355527 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 23:54:35.355539 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:54:35.355549 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 23:54:35.355559 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:54:35.355569 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 23:54:35.355580 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:54:35.355590 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 23:54:35.355600 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 23:54:35.355610 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 23:54:35.355629 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 23:54:35.355641 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 23:54:35.355651 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 4 23:54:35.355661 kernel: loop: module loaded Sep 4 23:54:35.355671 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 23:54:35.355699 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 23:54:35.355709 kernel: ACPI: bus type drm_connector registered Sep 4 23:54:35.355718 kernel: fuse: init (API version 7.41) Sep 4 23:54:35.355727 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 23:54:35.355745 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 23:54:35.355757 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 4 23:54:35.355768 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 23:54:35.355778 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 23:54:35.355788 systemd[1]: Stopped verity-setup.service. Sep 4 23:54:35.355800 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 23:54:35.355810 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 23:54:35.355844 systemd-journald[1159]: Collecting audit messages is disabled. Sep 4 23:54:35.355866 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 23:54:35.355877 systemd-journald[1159]: Journal started Sep 4 23:54:35.355898 systemd-journald[1159]: Runtime Journal (/run/log/journal/abafb2a1727c48cfad60215845310460) is 6M, max 48.5M, 42.4M free. Sep 4 23:54:35.138229 systemd[1]: Queued start job for default target multi-user.target. Sep 4 23:54:35.160835 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 4 23:54:35.161247 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 23:54:35.357787 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 23:54:35.358256 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 23:54:35.359320 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 23:54:35.360306 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 23:54:35.361381 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 23:54:35.363762 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 23:54:35.365008 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 23:54:35.365179 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 23:54:35.366509 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:54:35.366721 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:54:35.367948 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 23:54:35.368126 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 23:54:35.369252 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:54:35.369427 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:54:35.370975 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 23:54:35.371130 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 23:54:35.374145 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:54:35.374323 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:54:35.375484 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 23:54:35.376769 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 23:54:35.377979 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 23:54:35.380760 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 4 23:54:35.392168 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 23:54:35.394562 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 23:54:35.396501 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 23:54:35.397465 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 23:54:35.397504 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 23:54:35.399335 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 4 23:54:35.413547 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 23:54:35.414565 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:54:35.416010 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 23:54:35.417762 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 23:54:35.418868 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 23:54:35.420882 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 23:54:35.422079 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 23:54:35.423174 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 23:54:35.425406 systemd-journald[1159]: Time spent on flushing to /var/log/journal/abafb2a1727c48cfad60215845310460 is 18.930ms for 869 entries. Sep 4 23:54:35.425406 systemd-journald[1159]: System Journal (/var/log/journal/abafb2a1727c48cfad60215845310460) is 8M, max 195.6M, 187.6M free. Sep 4 23:54:35.453595 systemd-journald[1159]: Received client request to flush runtime journal. Sep 4 23:54:35.426061 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 23:54:35.428441 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 23:54:35.432338 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 23:54:35.433577 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 23:54:35.436328 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 23:54:35.439270 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 23:54:35.445618 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 23:54:35.454111 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 4 23:54:35.455395 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 23:54:35.458918 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Sep 4 23:54:35.458931 systemd-tmpfiles[1201]: ACLs are not supported, ignoring. Sep 4 23:54:35.460336 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 23:54:35.464217 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 23:54:35.464754 kernel: loop0: detected capacity change from 0 to 138376 Sep 4 23:54:35.475763 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 23:54:35.477459 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 23:54:35.490761 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 4 23:54:35.496791 kernel: loop1: detected capacity change from 0 to 107312 Sep 4 23:54:35.499795 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 23:54:35.502659 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 23:54:35.526170 systemd-tmpfiles[1222]: ACLs are not supported, ignoring. Sep 4 23:54:35.526190 systemd-tmpfiles[1222]: ACLs are not supported, ignoring. Sep 4 23:54:35.528746 kernel: loop2: detected capacity change from 0 to 211168 Sep 4 23:54:35.529799 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 23:54:35.565837 kernel: loop3: detected capacity change from 0 to 138376 Sep 4 23:54:35.575012 kernel: loop4: detected capacity change from 0 to 107312 Sep 4 23:54:35.580780 kernel: loop5: detected capacity change from 0 to 211168 Sep 4 23:54:35.585187 (sd-merge)[1228]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 4 23:54:35.585571 (sd-merge)[1228]: Merged extensions into '/usr'. Sep 4 23:54:35.589578 systemd[1]: Reload requested from client PID 1200 ('systemd-sysext') (unit systemd-sysext.service)... Sep 4 23:54:35.589599 systemd[1]: Reloading... Sep 4 23:54:35.647061 zram_generator::config[1253]: No configuration found. Sep 4 23:54:35.703338 ldconfig[1195]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 23:54:35.720254 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 4 23:54:35.782473 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 23:54:35.782575 systemd[1]: Reloading finished in 192 ms. Sep 4 23:54:35.811275 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 23:54:35.812470 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 23:54:35.827097 systemd[1]: Starting ensure-sysext.service... Sep 4 23:54:35.828790 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 23:54:35.836834 systemd[1]: Reload requested from client PID 1288 ('systemctl') (unit ensure-sysext.service)... Sep 4 23:54:35.836854 systemd[1]: Reloading... Sep 4 23:54:35.844184 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 4 23:54:35.844519 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 4 23:54:35.844835 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 23:54:35.845101 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 23:54:35.845804 systemd-tmpfiles[1289]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 23:54:35.846098 systemd-tmpfiles[1289]: ACLs are not supported, ignoring. Sep 4 23:54:35.846205 systemd-tmpfiles[1289]: ACLs are not supported, ignoring. Sep 4 23:54:35.849105 systemd-tmpfiles[1289]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 23:54:35.849201 systemd-tmpfiles[1289]: Skipping /boot Sep 4 23:54:35.857921 systemd-tmpfiles[1289]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 23:54:35.858034 systemd-tmpfiles[1289]: Skipping /boot Sep 4 23:54:35.882814 zram_generator::config[1319]: No configuration found. Sep 4 23:54:35.944836 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 4 23:54:36.006552 systemd[1]: Reloading finished in 169 ms. Sep 4 23:54:36.016440 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 23:54:36.030285 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 23:54:36.038971 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 4 23:54:36.041926 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 23:54:36.051720 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 23:54:36.054454 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 23:54:36.057962 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 23:54:36.061971 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 23:54:36.065474 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:54:36.066615 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:54:36.074970 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:54:36.077412 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:54:36.078712 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:54:36.078859 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 4 23:54:36.080495 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 23:54:36.082890 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 23:54:36.086404 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:54:36.086574 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:54:36.088282 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:54:36.088422 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:54:36.096575 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:54:36.097797 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:54:36.101377 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:54:36.103742 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:54:36.108012 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 23:54:36.109881 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:54:36.110016 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 4 23:54:36.113081 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 23:54:36.113484 systemd-udevd[1357]: Using default interface naming scheme 'v255'. Sep 4 23:54:36.114672 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 23:54:36.116309 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 23:54:36.124021 augenrules[1392]: No rules Sep 4 23:54:36.131031 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 23:54:36.133576 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 23:54:36.133798 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 4 23:54:36.135143 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:54:36.135289 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:54:36.140462 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 23:54:36.147593 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 23:54:36.148423 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 23:54:36.150820 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 23:54:36.157259 systemd[1]: Finished ensure-sysext.service. Sep 4 23:54:36.163102 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 4 23:54:36.164643 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 23:54:36.167064 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 23:54:36.171871 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 23:54:36.181965 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 23:54:36.182928 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 23:54:36.182975 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 4 23:54:36.186006 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 23:54:36.189329 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 4 23:54:36.190287 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 4 23:54:36.195206 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 23:54:36.196780 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 23:54:36.198566 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 23:54:36.198718 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 23:54:36.200783 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 23:54:36.200933 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 23:54:36.211662 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 4 23:54:36.211717 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 23:54:36.212336 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 23:54:36.216816 systemd-resolved[1355]: Positive Trust Anchors: Sep 4 23:54:36.216835 systemd-resolved[1355]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 23:54:36.216866 systemd-resolved[1355]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 4 23:54:36.224423 augenrules[1431]: /sbin/augenrules: No change Sep 4 23:54:36.225264 systemd-resolved[1355]: Defaulting to hostname 'linux'. Sep 4 23:54:36.226681 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 23:54:36.229772 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 23:54:36.237221 augenrules[1462]: No rules Sep 4 23:54:36.241830 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 23:54:36.242022 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 4 23:54:36.281454 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 4 23:54:36.285884 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 23:54:36.317086 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 23:54:36.328540 systemd-networkd[1437]: lo: Link UP Sep 4 23:54:36.328549 systemd-networkd[1437]: lo: Gained carrier Sep 4 23:54:36.330865 systemd-networkd[1437]: Enumeration completed Sep 4 23:54:36.331028 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 23:54:36.331356 systemd-networkd[1437]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:54:36.331360 systemd-networkd[1437]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 23:54:36.331783 systemd-networkd[1437]: eth0: Link UP Sep 4 23:54:36.331963 systemd-networkd[1437]: eth0: Gained carrier Sep 4 23:54:36.331977 systemd-networkd[1437]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 23:54:36.332032 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 4 23:54:36.333633 systemd[1]: Reached target network.target - Network. Sep 4 23:54:36.334517 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 23:54:36.335672 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 23:54:36.336700 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 4 23:54:36.338130 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 23:54:36.339138 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 23:54:36.339170 systemd[1]: Reached target paths.target - Path Units. Sep 4 23:54:36.339861 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 23:54:36.340958 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 23:54:36.341922 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 23:54:36.343018 systemd[1]: Reached target timers.target - Timer Units. Sep 4 23:54:36.344897 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 23:54:36.347920 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 23:54:36.349789 systemd-networkd[1437]: eth0: DHCPv4 address 10.0.0.94/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 4 23:54:36.350972 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 4 23:54:36.352214 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 4 23:54:36.353271 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 4 23:54:36.354633 systemd-timesyncd[1439]: Network configuration changed, trying to establish connection. Sep 4 23:54:36.355515 systemd-timesyncd[1439]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 4 23:54:36.355569 systemd-timesyncd[1439]: Initial clock synchronization to Thu 2025-09-04 23:54:36.480324 UTC. Sep 4 23:54:36.357594 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 23:54:36.359070 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 4 23:54:36.361325 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 4 23:54:36.364076 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 23:54:36.365546 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 23:54:36.366558 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 23:54:36.367381 systemd[1]: Reached target basic.target - Basic System. Sep 4 23:54:36.368200 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 23:54:36.368229 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 23:54:36.370860 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 23:54:36.373260 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 23:54:36.375032 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 23:54:36.378971 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 23:54:36.391720 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 23:54:36.392526 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 4 23:54:36.395650 jq[1498]: false Sep 4 23:54:36.395981 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 23:54:36.399258 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 23:54:36.402103 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 23:54:36.405020 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 23:54:36.408761 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 23:54:36.409173 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 23:54:36.410088 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 23:54:36.413120 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 23:54:36.415936 extend-filesystems[1499]: Found /dev/vda6 Sep 4 23:54:36.416239 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 23:54:36.417679 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 23:54:36.417867 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 23:54:36.422940 extend-filesystems[1499]: Found /dev/vda9 Sep 4 23:54:36.423773 jq[1510]: true Sep 4 23:54:36.429055 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 23:54:36.430193 extend-filesystems[1499]: Checking size of /dev/vda9 Sep 4 23:54:36.441911 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 23:54:36.444831 update_engine[1508]: I20250904 23:54:36.442989 1508 main.cc:92] Flatcar Update Engine starting Sep 4 23:54:36.442153 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 23:54:36.452607 dbus-daemon[1495]: [system] SELinux support is enabled Sep 4 23:54:36.455998 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 4 23:54:36.456103 (ntainerd)[1523]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 23:54:36.456475 update_engine[1508]: I20250904 23:54:36.456419 1508 update_check_scheduler.cc:74] Next update check in 3m40s Sep 4 23:54:36.457950 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 23:54:36.465063 jq[1521]: true Sep 4 23:54:36.465263 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 4 23:54:36.465293 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 23:54:36.467959 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 4 23:54:36.467986 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 23:54:36.469541 systemd[1]: Started update-engine.service - Update Engine. Sep 4 23:54:36.472023 systemd-logind[1505]: Watching system buttons on /dev/input/event0 (Power Button) Sep 4 23:54:36.472552 systemd-logind[1505]: New seat seat0. Sep 4 23:54:36.475915 extend-filesystems[1499]: Resized partition /dev/vda9 Sep 4 23:54:36.476247 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 23:54:36.479152 extend-filesystems[1539]: resize2fs 1.47.2 (1-Jan-2025) Sep 4 23:54:36.478978 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 23:54:36.480027 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 23:54:36.480811 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 23:54:36.489789 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 4 23:54:36.512435 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 4 23:54:36.528281 extend-filesystems[1539]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 4 23:54:36.528281 extend-filesystems[1539]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 4 23:54:36.528281 extend-filesystems[1539]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 4 23:54:36.528342 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 23:54:36.535211 extend-filesystems[1499]: Resized filesystem in /dev/vda9 Sep 4 23:54:36.529774 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 23:54:36.540621 bash[1559]: Updated "/home/core/.ssh/authorized_keys" Sep 4 23:54:36.546922 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 23:54:36.548325 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 23:54:36.559812 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 4 23:54:36.575229 locksmithd[1537]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 23:54:36.665945 containerd[1523]: time="2025-09-04T23:54:36Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 4 23:54:36.667754 containerd[1523]: time="2025-09-04T23:54:36.666598000Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Sep 4 23:54:36.678561 containerd[1523]: time="2025-09-04T23:54:36.678510440Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.92µs" Sep 4 23:54:36.678561 containerd[1523]: time="2025-09-04T23:54:36.678549680Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 4 23:54:36.678641 containerd[1523]: time="2025-09-04T23:54:36.678568640Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 4 23:54:36.678782 containerd[1523]: time="2025-09-04T23:54:36.678760000Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 4 23:54:36.678807 containerd[1523]: time="2025-09-04T23:54:36.678783720Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 4 23:54:36.678825 containerd[1523]: time="2025-09-04T23:54:36.678809840Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 4 23:54:36.678888 containerd[1523]: time="2025-09-04T23:54:36.678870480Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 4 23:54:36.678910 containerd[1523]: time="2025-09-04T23:54:36.678886720Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 4 23:54:36.679156 containerd[1523]: time="2025-09-04T23:54:36.679120880Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 4 23:54:36.679156 containerd[1523]: time="2025-09-04T23:54:36.679143360Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 23:54:36.679156 containerd[1523]: time="2025-09-04T23:54:36.679154640Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 4 23:54:36.679222 containerd[1523]: time="2025-09-04T23:54:36.679163200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 4 23:54:36.679244 containerd[1523]: time="2025-09-04T23:54:36.679233640Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 4 23:54:36.679428 containerd[1523]: time="2025-09-04T23:54:36.679408200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 4 23:54:36.679450 containerd[1523]: time="2025-09-04T23:54:36.679442440Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 4 23:54:36.679468 containerd[1523]: time="2025-09-04T23:54:36.679453440Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 4 23:54:36.679512 containerd[1523]: time="2025-09-04T23:54:36.679495640Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 4 23:54:36.679738 containerd[1523]: time="2025-09-04T23:54:36.679713400Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 4 23:54:36.679847 containerd[1523]: time="2025-09-04T23:54:36.679828160Z" level=info msg="metadata content store policy set" policy=shared Sep 4 23:54:36.683481 containerd[1523]: time="2025-09-04T23:54:36.683443680Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 4 23:54:36.683527 containerd[1523]: time="2025-09-04T23:54:36.683504360Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 4 23:54:36.683527 containerd[1523]: time="2025-09-04T23:54:36.683520840Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 4 23:54:36.683580 containerd[1523]: time="2025-09-04T23:54:36.683534400Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 4 23:54:36.683580 containerd[1523]: time="2025-09-04T23:54:36.683546800Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 4 23:54:36.683580 containerd[1523]: time="2025-09-04T23:54:36.683559040Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 4 23:54:36.683580 containerd[1523]: time="2025-09-04T23:54:36.683570040Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 4 23:54:36.683644 containerd[1523]: time="2025-09-04T23:54:36.683581200Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 4 23:54:36.683644 containerd[1523]: time="2025-09-04T23:54:36.683592520Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 4 23:54:36.683644 containerd[1523]: time="2025-09-04T23:54:36.683606640Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 4 23:54:36.683644 containerd[1523]: time="2025-09-04T23:54:36.683616160Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 4 23:54:36.683644 containerd[1523]: time="2025-09-04T23:54:36.683627400Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 4 23:54:36.683783 containerd[1523]: time="2025-09-04T23:54:36.683750520Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 4 23:54:36.683810 containerd[1523]: time="2025-09-04T23:54:36.683800880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 4 23:54:36.683828 containerd[1523]: time="2025-09-04T23:54:36.683819240Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 4 23:54:36.683845 containerd[1523]: time="2025-09-04T23:54:36.683832200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 4 23:54:36.683863 containerd[1523]: time="2025-09-04T23:54:36.683843800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 4 23:54:36.683863 containerd[1523]: time="2025-09-04T23:54:36.683854520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 4 23:54:36.683906 containerd[1523]: time="2025-09-04T23:54:36.683865560Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 4 23:54:36.683906 containerd[1523]: time="2025-09-04T23:54:36.683875880Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 4 23:54:36.683906 containerd[1523]: time="2025-09-04T23:54:36.683886760Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 4 23:54:36.683906 containerd[1523]: time="2025-09-04T23:54:36.683898000Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 4 23:54:36.683975 containerd[1523]: time="2025-09-04T23:54:36.683907960Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 4 23:54:36.684120 containerd[1523]: time="2025-09-04T23:54:36.684089080Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 4 23:54:36.684120 containerd[1523]: time="2025-09-04T23:54:36.684116240Z" level=info msg="Start snapshots syncer" Sep 4 23:54:36.684187 containerd[1523]: time="2025-09-04T23:54:36.684148160Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 4 23:54:36.684597 containerd[1523]: time="2025-09-04T23:54:36.684541160Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 4 23:54:36.684700 containerd[1523]: time="2025-09-04T23:54:36.684619600Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 4 23:54:36.684841 containerd[1523]: time="2025-09-04T23:54:36.684818680Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 4 23:54:36.685004 containerd[1523]: time="2025-09-04T23:54:36.684940000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 4 23:54:36.685004 containerd[1523]: time="2025-09-04T23:54:36.684976320Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 4 23:54:36.685438 containerd[1523]: time="2025-09-04T23:54:36.685407520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 4 23:54:36.685480 containerd[1523]: time="2025-09-04T23:54:36.685437440Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 4 23:54:36.685480 containerd[1523]: time="2025-09-04T23:54:36.685456800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 4 23:54:36.685480 containerd[1523]: time="2025-09-04T23:54:36.685473080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 4 23:54:36.685551 containerd[1523]: time="2025-09-04T23:54:36.685498160Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 4 23:54:36.685551 containerd[1523]: time="2025-09-04T23:54:36.685540440Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 4 23:54:36.685591 containerd[1523]: time="2025-09-04T23:54:36.685557080Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 4 23:54:36.685591 containerd[1523]: time="2025-09-04T23:54:36.685573840Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 4 23:54:36.685774 containerd[1523]: time="2025-09-04T23:54:36.685617280Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 4 23:54:36.685774 containerd[1523]: time="2025-09-04T23:54:36.685639800Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 4 23:54:36.685774 containerd[1523]: time="2025-09-04T23:54:36.685654280Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 4 23:54:36.685774 containerd[1523]: time="2025-09-04T23:54:36.685667600Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 4 23:54:36.685774 containerd[1523]: time="2025-09-04T23:54:36.685676480Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 4 23:54:36.685774 containerd[1523]: time="2025-09-04T23:54:36.685690200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 4 23:54:36.685774 containerd[1523]: time="2025-09-04T23:54:36.685703920Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 4 23:54:36.685908 containerd[1523]: time="2025-09-04T23:54:36.685850040Z" level=info msg="runtime interface created" Sep 4 23:54:36.685908 containerd[1523]: time="2025-09-04T23:54:36.685858400Z" level=info msg="created NRI interface" Sep 4 23:54:36.685908 containerd[1523]: time="2025-09-04T23:54:36.685873640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 4 23:54:36.685908 containerd[1523]: time="2025-09-04T23:54:36.685886400Z" level=info msg="Connect containerd service" Sep 4 23:54:36.686073 containerd[1523]: time="2025-09-04T23:54:36.685924200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 23:54:36.686826 containerd[1523]: time="2025-09-04T23:54:36.686794800Z" 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 4 23:54:36.764019 containerd[1523]: time="2025-09-04T23:54:36.763959400Z" level=info msg="Start subscribing containerd event" Sep 4 23:54:36.764173 containerd[1523]: time="2025-09-04T23:54:36.764158120Z" level=info msg="Start recovering state" Sep 4 23:54:36.764346 containerd[1523]: time="2025-09-04T23:54:36.764296720Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 23:54:36.764417 containerd[1523]: time="2025-09-04T23:54:36.764396880Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 23:54:36.766845 containerd[1523]: time="2025-09-04T23:54:36.764404080Z" level=info msg="Start event monitor" Sep 4 23:54:36.766942 containerd[1523]: time="2025-09-04T23:54:36.766927280Z" level=info msg="Start cni network conf syncer for default" Sep 4 23:54:36.766993 containerd[1523]: time="2025-09-04T23:54:36.766982760Z" level=info msg="Start streaming server" Sep 4 23:54:36.767044 containerd[1523]: time="2025-09-04T23:54:36.767033840Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 4 23:54:36.767110 containerd[1523]: time="2025-09-04T23:54:36.767090400Z" level=info msg="runtime interface starting up..." Sep 4 23:54:36.767160 containerd[1523]: time="2025-09-04T23:54:36.767149560Z" level=info msg="starting plugins..." Sep 4 23:54:36.767216 containerd[1523]: time="2025-09-04T23:54:36.767203480Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 4 23:54:36.767394 containerd[1523]: time="2025-09-04T23:54:36.767377240Z" level=info msg="containerd successfully booted in 0.104060s" Sep 4 23:54:36.767480 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 23:54:36.973883 sshd_keygen[1518]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 23:54:36.992253 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 23:54:36.994816 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 23:54:37.014243 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 23:54:37.014454 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 23:54:37.016973 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 23:54:37.038796 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 23:54:37.041373 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 23:54:37.043252 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 4 23:54:37.044363 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 23:54:37.552200 systemd-networkd[1437]: eth0: Gained IPv6LL Sep 4 23:54:37.556839 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 23:54:37.558451 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 23:54:37.562260 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 4 23:54:37.564574 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 23:54:37.572435 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 23:54:37.586572 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 4 23:54:37.586811 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 4 23:54:37.588619 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 23:54:37.591783 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 23:54:38.105868 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 23:54:38.107160 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 23:54:38.111109 (kubelet)[1629]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 4 23:54:38.111910 systemd[1]: Startup finished in 2.013s (kernel) + 5.179s (initrd) + 3.383s (userspace) = 10.576s. Sep 4 23:54:38.463811 kubelet[1629]: E0904 23:54:38.463697 1629 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 4 23:54:38.469868 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 4 23:54:38.470016 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 4 23:54:38.470336 systemd[1]: kubelet.service: Consumed 742ms CPU time, 257.7M memory peak. Sep 4 23:54:42.684548 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 23:54:42.687526 systemd[1]: Started sshd@0-10.0.0.94:22-10.0.0.1:60696.service - OpenSSH per-connection server daemon (10.0.0.1:60696). Sep 4 23:54:42.753949 sshd[1642]: Accepted publickey for core from 10.0.0.1 port 60696 ssh2: RSA SHA256:dz8a5vpzhl9T1tN+PlbA3wzUJkL1bHm+PkgBuWVD7dg Sep 4 23:54:42.757099 sshd-session[1642]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:54:42.764425 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 23:54:42.767138 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 23:54:42.777768 systemd-logind[1505]: New session 1 of user core. Sep 4 23:54:42.790352 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 23:54:42.792968 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 23:54:42.819390 (systemd)[1646]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 23:54:42.822392 systemd-logind[1505]: New session c1 of user core. Sep 4 23:54:42.946928 systemd[1646]: Queued start job for default target default.target. Sep 4 23:54:42.963779 systemd[1646]: Created slice app.slice - User Application Slice. Sep 4 23:54:42.963806 systemd[1646]: Reached target paths.target - Paths. Sep 4 23:54:42.963845 systemd[1646]: Reached target timers.target - Timers. Sep 4 23:54:42.965122 systemd[1646]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 23:54:42.974486 systemd[1646]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 23:54:42.974571 systemd[1646]: Reached target sockets.target - Sockets. Sep 4 23:54:42.974614 systemd[1646]: Reached target basic.target - Basic System. Sep 4 23:54:42.974643 systemd[1646]: Reached target default.target - Main User Target. Sep 4 23:54:42.974670 systemd[1646]: Startup finished in 146ms. Sep 4 23:54:42.974813 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 23:54:42.976252 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 23:54:43.042260 systemd[1]: Started sshd@1-10.0.0.94:22-10.0.0.1:60702.service - OpenSSH per-connection server daemon (10.0.0.1:60702). Sep 4 23:54:43.091801 sshd[1657]: Accepted publickey for core from 10.0.0.1 port 60702 ssh2: RSA SHA256:dz8a5vpzhl9T1tN+PlbA3wzUJkL1bHm+PkgBuWVD7dg Sep 4 23:54:43.093558 sshd-session[1657]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:54:43.097644 systemd-logind[1505]: New session 2 of user core. Sep 4 23:54:43.105937 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 23:54:43.156785 sshd[1659]: Connection closed by 10.0.0.1 port 60702 Sep 4 23:54:43.157226 sshd-session[1657]: pam_unix(sshd:session): session closed for user core Sep 4 23:54:43.173178 systemd[1]: sshd@1-10.0.0.94:22-10.0.0.1:60702.service: Deactivated successfully. Sep 4 23:54:43.174929 systemd[1]: session-2.scope: Deactivated successfully. Sep 4 23:54:43.177621 systemd-logind[1505]: Session 2 logged out. Waiting for processes to exit. Sep 4 23:54:43.179657 systemd[1]: Started sshd@2-10.0.0.94:22-10.0.0.1:60712.service - OpenSSH per-connection server daemon (10.0.0.1:60712). Sep 4 23:54:43.181939 systemd-logind[1505]: Removed session 2. Sep 4 23:54:43.242135 sshd[1665]: Accepted publickey for core from 10.0.0.1 port 60712 ssh2: RSA SHA256:dz8a5vpzhl9T1tN+PlbA3wzUJkL1bHm+PkgBuWVD7dg Sep 4 23:54:43.243366 sshd-session[1665]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:54:43.247048 systemd-logind[1505]: New session 3 of user core. Sep 4 23:54:43.257910 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 23:54:43.307550 sshd[1667]: Connection closed by 10.0.0.1 port 60712 Sep 4 23:54:43.307911 sshd-session[1665]: pam_unix(sshd:session): session closed for user core Sep 4 23:54:43.317802 systemd[1]: sshd@2-10.0.0.94:22-10.0.0.1:60712.service: Deactivated successfully. Sep 4 23:54:43.319404 systemd[1]: session-3.scope: Deactivated successfully. Sep 4 23:54:43.320046 systemd-logind[1505]: Session 3 logged out. Waiting for processes to exit. Sep 4 23:54:43.322145 systemd[1]: Started sshd@3-10.0.0.94:22-10.0.0.1:60714.service - OpenSSH per-connection server daemon (10.0.0.1:60714). Sep 4 23:54:43.322928 systemd-logind[1505]: Removed session 3. Sep 4 23:54:43.364626 sshd[1673]: Accepted publickey for core from 10.0.0.1 port 60714 ssh2: RSA SHA256:dz8a5vpzhl9T1tN+PlbA3wzUJkL1bHm+PkgBuWVD7dg Sep 4 23:54:43.365905 sshd-session[1673]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:54:43.369636 systemd-logind[1505]: New session 4 of user core. Sep 4 23:54:43.378896 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 23:54:43.429546 sshd[1675]: Connection closed by 10.0.0.1 port 60714 Sep 4 23:54:43.429869 sshd-session[1673]: pam_unix(sshd:session): session closed for user core Sep 4 23:54:43.439925 systemd[1]: sshd@3-10.0.0.94:22-10.0.0.1:60714.service: Deactivated successfully. Sep 4 23:54:43.441457 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 23:54:43.442087 systemd-logind[1505]: Session 4 logged out. Waiting for processes to exit. Sep 4 23:54:43.444289 systemd[1]: Started sshd@4-10.0.0.94:22-10.0.0.1:60718.service - OpenSSH per-connection server daemon (10.0.0.1:60718). Sep 4 23:54:43.445713 systemd-logind[1505]: Removed session 4. Sep 4 23:54:43.492979 sshd[1681]: Accepted publickey for core from 10.0.0.1 port 60718 ssh2: RSA SHA256:dz8a5vpzhl9T1tN+PlbA3wzUJkL1bHm+PkgBuWVD7dg Sep 4 23:54:43.494151 sshd-session[1681]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 23:54:43.498132 systemd-logind[1505]: New session 5 of user core. Sep 4 23:54:43.509922 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 23:54:43.566651 sudo[1684]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 4 23:54:43.567295 sudo[1684]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 23:54:43.577992 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 4 23:54:43.601642 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 4 23:54:43.603797 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 4 23:54:44.007382 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 23:54:44.007684 systemd[1]: kubelet.service: Consumed 742ms CPU time, 257.7M memory peak. Sep 4 23:54:44.009887 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 23:54:44.028530 systemd[1]: Reload requested from client PID 1725 ('systemctl') (unit session-5.scope)... Sep 4 23:54:44.028546 systemd[1]: Reloading... Sep 4 23:54:44.102762 zram_generator::config[1769]: No configuration found. Sep 4 23:54:44.283003 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 4 23:54:44.368018 systemd[1]: Reloading finished in 339 ms. Sep 4 23:54:44.410186 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 4 23:54:44.410257 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 4 23:54:44.410521 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 23:54:44.410563 systemd[1]: kubelet.service: Consumed 94ms CPU time, 94.9M memory peak. Sep 4 23:54:44.413006 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 4 23:54:44.530512 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 4 23:54:44.534386 (kubelet)[1813]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 4 23:54:44.566330 kubelet[1813]: 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 4 23:54:44.566809 kubelet[1813]: 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 4 23:54:44.566809 kubelet[1813]: 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 4 23:54:44.566809 kubelet[1813]: I0904 23:54:44.566690 1813 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 4 23:54:44.887973 kubelet[1813]: I0904 23:54:44.887874 1813 server.go:530] "Kubelet version" kubeletVersion="v1.33.0" Sep 4 23:54:44.887973 kubelet[1813]: I0904 23:54:44.887905 1813 server.go:532] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 4 23:54:44.888163 kubelet[1813]: I0904 23:54:44.888143 1813 server.go:956] "Client rotation is on, will bootstrap in background" Sep 4 23:54:44.912129 kubelet[1813]: I0904 23:54:44.912081 1813 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 4 23:54:44.921007 kubelet[1813]: I0904 23:54:44.920977 1813 server.go:1446] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Sep 4 23:54:44.923705 kubelet[1813]: I0904 23:54:44.923684 1813 server.go:782] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 4 23:54:44.924754 kubelet[1813]: I0904 23:54:44.924705 1813 container_manager_linux.go:267] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 4 23:54:44.924956 kubelet[1813]: I0904 23:54:44.924776 1813 container_manager_linux.go:272] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.94","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":"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},{"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}],"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 4 23:54:44.925077 kubelet[1813]: I0904 23:54:44.925015 1813 topology_manager.go:138] "Creating topology manager with none policy" Sep 4 23:54:44.925077 kubelet[1813]: I0904 23:54:44.925024 1813 container_manager_linux.go:303] "Creating device plugin manager" Sep 4 23:54:44.925771 kubelet[1813]: I0904 23:54:44.925725 1813 state_mem.go:36] "Initialized new in-memory state store" Sep 4 23:54:44.928720 kubelet[1813]: I0904 23:54:44.928673 1813 kubelet.go:480] "Attempting to sync node with API server" Sep 4 23:54:44.928720 kubelet[1813]: I0904 23:54:44.928709 1813 kubelet.go:375] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 4 23:54:44.928720 kubelet[1813]: I0904 23:54:44.928732 1813 kubelet.go:386] "Adding apiserver pod source" Sep 4 23:54:44.929168 kubelet[1813]: I0904 23:54:44.928757 1813 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 4 23:54:44.929168 kubelet[1813]: E0904 23:54:44.928811 1813 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:44.929505 kubelet[1813]: E0904 23:54:44.929486 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:44.929825 kubelet[1813]: I0904 23:54:44.929801 1813 kuberuntime_manager.go:279] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" Sep 4 23:54:44.930533 kubelet[1813]: I0904 23:54:44.930508 1813 kubelet.go:935] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Sep 4 23:54:44.930639 kubelet[1813]: W0904 23:54:44.930627 1813 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 4 23:54:44.933118 kubelet[1813]: I0904 23:54:44.933098 1813 watchdog_linux.go:99] "Systemd watchdog is not enabled" Sep 4 23:54:44.933172 kubelet[1813]: I0904 23:54:44.933147 1813 server.go:1289] "Started kubelet" Sep 4 23:54:44.934652 kubelet[1813]: I0904 23:54:44.934232 1813 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 4 23:54:44.934781 kubelet[1813]: I0904 23:54:44.934697 1813 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 4 23:54:44.935040 kubelet[1813]: I0904 23:54:44.935021 1813 server.go:255] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 4 23:54:44.935093 kubelet[1813]: I0904 23:54:44.935073 1813 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Sep 4 23:54:44.936079 kubelet[1813]: I0904 23:54:44.935999 1813 server.go:317] "Adding debug handlers to kubelet server" Sep 4 23:54:44.937647 kubelet[1813]: I0904 23:54:44.937627 1813 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 4 23:54:44.938148 kubelet[1813]: E0904 23:54:44.938071 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:44.938252 kubelet[1813]: I0904 23:54:44.938240 1813 volume_manager.go:297] "Starting Kubelet Volume Manager" Sep 4 23:54:44.938735 kubelet[1813]: I0904 23:54:44.938715 1813 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Sep 4 23:54:44.938888 kubelet[1813]: I0904 23:54:44.938875 1813 reconciler.go:26] "Reconciler: start to sync state" Sep 4 23:54:44.940008 kubelet[1813]: I0904 23:54:44.939987 1813 factory.go:223] Registration of the systemd container factory successfully Sep 4 23:54:44.940117 kubelet[1813]: E0904 23:54:44.938708 1813 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.94.1862398d981cd9c0 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.94,UID:10.0.0.94,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.94,},FirstTimestamp:2025-09-04 23:54:44.933114304 +0000 UTC m=+0.395479693,LastTimestamp:2025-09-04 23:54:44.933114304 +0000 UTC m=+0.395479693,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.94,}" Sep 4 23:54:44.940273 kubelet[1813]: I0904 23:54:44.940250 1813 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 4 23:54:44.941803 kubelet[1813]: E0904 23:54:44.941770 1813 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 4 23:54:44.941899 kubelet[1813]: E0904 23:54:44.941874 1813 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 4 23:54:44.942071 kubelet[1813]: E0904 23:54:44.942001 1813 reflector.go:200] "Failed to watch" err="failed to list *v1.Node: nodes \"10.0.0.94\" 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 4 23:54:44.942658 kubelet[1813]: E0904 23:54:44.942316 1813 kubelet.go:1600] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 4 23:54:44.942946 kubelet[1813]: I0904 23:54:44.942926 1813 factory.go:223] Registration of the containerd container factory successfully Sep 4 23:54:44.950012 kubelet[1813]: E0904 23:54:44.949908 1813 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.94.1862398d98a84fab default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.94,UID:10.0.0.94,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.0.0.94,},FirstTimestamp:2025-09-04 23:54:44.942253995 +0000 UTC m=+0.404619385,LastTimestamp:2025-09-04 23:54:44.942253995 +0000 UTC m=+0.404619385,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.94,}" Sep 4 23:54:44.954975 kubelet[1813]: I0904 23:54:44.954950 1813 cpu_manager.go:221] "Starting CPU manager" policy="none" Sep 4 23:54:44.955080 kubelet[1813]: I0904 23:54:44.955065 1813 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Sep 4 23:54:44.955136 kubelet[1813]: I0904 23:54:44.955126 1813 state_mem.go:36] "Initialized new in-memory state store" Sep 4 23:54:44.964695 kubelet[1813]: E0904 23:54:44.964647 1813 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.94\" not found" node="10.0.0.94" Sep 4 23:54:45.038671 kubelet[1813]: E0904 23:54:45.038622 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:45.062314 kubelet[1813]: I0904 23:54:45.062253 1813 policy_none.go:49] "None policy: Start" Sep 4 23:54:45.062314 kubelet[1813]: I0904 23:54:45.062285 1813 memory_manager.go:186] "Starting memorymanager" policy="None" Sep 4 23:54:45.062314 kubelet[1813]: I0904 23:54:45.062298 1813 state_mem.go:35] "Initializing new in-memory state store" Sep 4 23:54:45.069611 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 4 23:54:45.082825 kubelet[1813]: I0904 23:54:45.082765 1813 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv4" Sep 4 23:54:45.083690 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 4 23:54:45.085232 kubelet[1813]: I0904 23:54:45.084820 1813 kubelet_network_linux.go:49] "Initialized iptables rules." protocol="IPv6" Sep 4 23:54:45.085232 kubelet[1813]: I0904 23:54:45.084850 1813 status_manager.go:230] "Starting to sync pod status with apiserver" Sep 4 23:54:45.085232 kubelet[1813]: I0904 23:54:45.084870 1813 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Sep 4 23:54:45.085232 kubelet[1813]: I0904 23:54:45.084877 1813 kubelet.go:2436] "Starting kubelet main sync loop" Sep 4 23:54:45.085232 kubelet[1813]: E0904 23:54:45.084919 1813 kubelet.go:2460] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Sep 4 23:54:45.088983 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 4 23:54:45.105705 kubelet[1813]: E0904 23:54:45.105672 1813 manager.go:517] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Sep 4 23:54:45.106169 kubelet[1813]: I0904 23:54:45.106155 1813 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 4 23:54:45.106220 kubelet[1813]: I0904 23:54:45.106175 1813 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 4 23:54:45.107179 kubelet[1813]: I0904 23:54:45.107160 1813 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 4 23:54:45.107530 kubelet[1813]: E0904 23:54:45.107499 1813 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Sep 4 23:54:45.107574 kubelet[1813]: E0904 23:54:45.107553 1813 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.94\" not found" Sep 4 23:54:45.207816 kubelet[1813]: I0904 23:54:45.207147 1813 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.94" Sep 4 23:54:45.211576 kubelet[1813]: I0904 23:54:45.211480 1813 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.94" Sep 4 23:54:45.211576 kubelet[1813]: E0904 23:54:45.211514 1813 kubelet_node_status.go:548] "Error updating node status, will retry" err="error getting node \"10.0.0.94\": node \"10.0.0.94\" not found" Sep 4 23:54:45.223406 kubelet[1813]: E0904 23:54:45.223371 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:45.324147 kubelet[1813]: E0904 23:54:45.324111 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:45.424990 kubelet[1813]: E0904 23:54:45.424950 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:45.526173 kubelet[1813]: E0904 23:54:45.526063 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:45.564275 sudo[1684]: pam_unix(sudo:session): session closed for user root Sep 4 23:54:45.565586 sshd[1683]: Connection closed by 10.0.0.1 port 60718 Sep 4 23:54:45.565916 sshd-session[1681]: pam_unix(sshd:session): session closed for user core Sep 4 23:54:45.569283 systemd[1]: sshd@4-10.0.0.94:22-10.0.0.1:60718.service: Deactivated successfully. Sep 4 23:54:45.571302 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 23:54:45.571785 systemd[1]: session-5.scope: Consumed 413ms CPU time, 76.2M memory peak. Sep 4 23:54:45.572956 systemd-logind[1505]: Session 5 logged out. Waiting for processes to exit. Sep 4 23:54:45.574357 systemd-logind[1505]: Removed session 5. Sep 4 23:54:45.627023 kubelet[1813]: E0904 23:54:45.626968 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:45.727799 kubelet[1813]: E0904 23:54:45.727728 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:45.828715 kubelet[1813]: E0904 23:54:45.828620 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:45.890629 kubelet[1813]: I0904 23:54:45.890577 1813 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 4 23:54:45.890788 kubelet[1813]: I0904 23:54:45.890768 1813 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 4 23:54:45.928841 kubelet[1813]: E0904 23:54:45.928788 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:45.929916 kubelet[1813]: E0904 23:54:45.929888 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:46.029280 kubelet[1813]: E0904 23:54:46.029231 1813 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.94\" not found" Sep 4 23:54:46.130905 kubelet[1813]: I0904 23:54:46.130798 1813 kuberuntime_manager.go:1746] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 4 23:54:46.131171 containerd[1523]: time="2025-09-04T23:54:46.131116229Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 4 23:54:46.131617 kubelet[1813]: I0904 23:54:46.131278 1813 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 4 23:54:46.930650 kubelet[1813]: E0904 23:54:46.930577 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:46.930650 kubelet[1813]: I0904 23:54:46.930590 1813 apiserver.go:52] "Watching apiserver" Sep 4 23:54:46.939054 kubelet[1813]: I0904 23:54:46.939026 1813 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Sep 4 23:54:46.945575 systemd[1]: Created slice kubepods-burstable-pod227dd350_cc44_418c_80a0_e604cd14ff89.slice - libcontainer container kubepods-burstable-pod227dd350_cc44_418c_80a0_e604cd14ff89.slice. Sep 4 23:54:46.951097 kubelet[1813]: I0904 23:54:46.951057 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/227dd350-cc44-418c-80a0-e604cd14ff89-run\") pod \"kube-flannel-ds-fq7ld\" (UID: \"227dd350-cc44-418c-80a0-e604cd14ff89\") " pod="kube-flannel/kube-flannel-ds-fq7ld" Sep 4 23:54:46.951206 kubelet[1813]: I0904 23:54:46.951103 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/227dd350-cc44-418c-80a0-e604cd14ff89-cni\") pod \"kube-flannel-ds-fq7ld\" (UID: \"227dd350-cc44-418c-80a0-e604cd14ff89\") " pod="kube-flannel/kube-flannel-ds-fq7ld" Sep 4 23:54:46.951206 kubelet[1813]: I0904 23:54:46.951124 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/227dd350-cc44-418c-80a0-e604cd14ff89-flannel-cfg\") pod \"kube-flannel-ds-fq7ld\" (UID: \"227dd350-cc44-418c-80a0-e604cd14ff89\") " pod="kube-flannel/kube-flannel-ds-fq7ld" Sep 4 23:54:46.951256 kubelet[1813]: I0904 23:54:46.951209 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/227dd350-cc44-418c-80a0-e604cd14ff89-xtables-lock\") pod \"kube-flannel-ds-fq7ld\" (UID: \"227dd350-cc44-418c-80a0-e604cd14ff89\") " pod="kube-flannel/kube-flannel-ds-fq7ld" Sep 4 23:54:46.951281 kubelet[1813]: I0904 23:54:46.951256 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gv75v\" (UniqueName: \"kubernetes.io/projected/227dd350-cc44-418c-80a0-e604cd14ff89-kube-api-access-gv75v\") pod \"kube-flannel-ds-fq7ld\" (UID: \"227dd350-cc44-418c-80a0-e604cd14ff89\") " pod="kube-flannel/kube-flannel-ds-fq7ld" Sep 4 23:54:46.951333 kubelet[1813]: I0904 23:54:46.951310 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b33ea360-6eee-42cb-8db1-06bba29895ff-xtables-lock\") pod \"kube-proxy-j4w9f\" (UID: \"b33ea360-6eee-42cb-8db1-06bba29895ff\") " pod="kube-system/kube-proxy-j4w9f" Sep 4 23:54:46.951370 kubelet[1813]: I0904 23:54:46.951342 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/227dd350-cc44-418c-80a0-e604cd14ff89-cni-plugin\") pod \"kube-flannel-ds-fq7ld\" (UID: \"227dd350-cc44-418c-80a0-e604cd14ff89\") " pod="kube-flannel/kube-flannel-ds-fq7ld" Sep 4 23:54:46.951370 kubelet[1813]: I0904 23:54:46.951362 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b33ea360-6eee-42cb-8db1-06bba29895ff-kube-proxy\") pod \"kube-proxy-j4w9f\" (UID: \"b33ea360-6eee-42cb-8db1-06bba29895ff\") " pod="kube-system/kube-proxy-j4w9f" Sep 4 23:54:46.951416 kubelet[1813]: I0904 23:54:46.951378 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b33ea360-6eee-42cb-8db1-06bba29895ff-lib-modules\") pod \"kube-proxy-j4w9f\" (UID: \"b33ea360-6eee-42cb-8db1-06bba29895ff\") " pod="kube-system/kube-proxy-j4w9f" Sep 4 23:54:46.951439 kubelet[1813]: I0904 23:54:46.951407 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vsfz4\" (UniqueName: \"kubernetes.io/projected/b33ea360-6eee-42cb-8db1-06bba29895ff-kube-api-access-vsfz4\") pod \"kube-proxy-j4w9f\" (UID: \"b33ea360-6eee-42cb-8db1-06bba29895ff\") " pod="kube-system/kube-proxy-j4w9f" Sep 4 23:54:46.979798 systemd[1]: Created slice kubepods-besteffort-podb33ea360_6eee_42cb_8db1_06bba29895ff.slice - libcontainer container kubepods-besteffort-podb33ea360_6eee_42cb_8db1_06bba29895ff.slice. Sep 4 23:54:47.279313 containerd[1523]: time="2025-09-04T23:54:47.279196426Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-fq7ld,Uid:227dd350-cc44-418c-80a0-e604cd14ff89,Namespace:kube-flannel,Attempt:0,}" Sep 4 23:54:47.315415 containerd[1523]: time="2025-09-04T23:54:47.315349102Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-j4w9f,Uid:b33ea360-6eee-42cb-8db1-06bba29895ff,Namespace:kube-system,Attempt:0,}" Sep 4 23:54:47.872179 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3220574580.mount: Deactivated successfully. Sep 4 23:54:47.880957 containerd[1523]: time="2025-09-04T23:54:47.880904807Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 4 23:54:47.882823 containerd[1523]: time="2025-09-04T23:54:47.882782246Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Sep 4 23:54:47.884311 containerd[1523]: time="2025-09-04T23:54:47.884269570Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 4 23:54:47.885333 containerd[1523]: time="2025-09-04T23:54:47.885305085Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Sep 4 23:54:47.886089 containerd[1523]: time="2025-09-04T23:54:47.886056800Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 4 23:54:47.888261 containerd[1523]: time="2025-09-04T23:54:47.888204384Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 4 23:54:47.889357 containerd[1523]: time="2025-09-04T23:54:47.889076512Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 567.029051ms" Sep 4 23:54:47.890342 containerd[1523]: time="2025-09-04T23:54:47.890314375Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"267933\" in 605.110015ms" Sep 4 23:54:47.910762 containerd[1523]: time="2025-09-04T23:54:47.910692368Z" level=info msg="connecting to shim 7e0ea619443d59f2252c1081096c693a1d422933af35d2a96b7fb466fd25f4ba" address="unix:///run/containerd/s/beb5197631c33eedf54d14bbb2b66a88cd3f53aa87543dbbeea787eae4a526f6" namespace=k8s.io protocol=ttrpc version=3 Sep 4 23:54:47.918565 containerd[1523]: time="2025-09-04T23:54:47.918045645Z" level=info msg="connecting to shim d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988" address="unix:///run/containerd/s/f46e99acf278467d3e56b3e8c5a9b106fb5c39b1be3664ad697fc36948820dce" namespace=k8s.io protocol=ttrpc version=3 Sep 4 23:54:47.931775 kubelet[1813]: E0904 23:54:47.931405 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:47.937950 systemd[1]: Started cri-containerd-7e0ea619443d59f2252c1081096c693a1d422933af35d2a96b7fb466fd25f4ba.scope - libcontainer container 7e0ea619443d59f2252c1081096c693a1d422933af35d2a96b7fb466fd25f4ba. Sep 4 23:54:47.944713 systemd[1]: Started cri-containerd-d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988.scope - libcontainer container d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988. Sep 4 23:54:47.965374 containerd[1523]: time="2025-09-04T23:54:47.965332303Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-j4w9f,Uid:b33ea360-6eee-42cb-8db1-06bba29895ff,Namespace:kube-system,Attempt:0,} returns sandbox id \"7e0ea619443d59f2252c1081096c693a1d422933af35d2a96b7fb466fd25f4ba\"" Sep 4 23:54:47.967680 containerd[1523]: time="2025-09-04T23:54:47.967445466Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.4\"" Sep 4 23:54:48.013416 containerd[1523]: time="2025-09-04T23:54:48.013372408Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-fq7ld,Uid:227dd350-cc44-418c-80a0-e604cd14ff89,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988\"" Sep 4 23:54:48.932656 kubelet[1813]: E0904 23:54:48.932615 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:48.941919 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3308105384.mount: Deactivated successfully. Sep 4 23:54:49.184031 containerd[1523]: time="2025-09-04T23:54:49.183908403Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.33.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:49.185094 containerd[1523]: time="2025-09-04T23:54:49.185060214Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.33.4: active requests=0, bytes read=28199961" Sep 4 23:54:49.185928 containerd[1523]: time="2025-09-04T23:54:49.185901571Z" level=info msg="ImageCreate event name:\"sha256:e19c0cda155dad39120317830ddb8b2bc22070f2c6a97973e96fb09ef504ee64\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:49.188144 containerd[1523]: time="2025-09-04T23:54:49.188089510Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:bb04e9247da3aaeb96406b4d530a79fc865695b6807353dd1a28871df0d7f837\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:49.188716 containerd[1523]: time="2025-09-04T23:54:49.188515565Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.33.4\" with image id \"sha256:e19c0cda155dad39120317830ddb8b2bc22070f2c6a97973e96fb09ef504ee64\", repo tag \"registry.k8s.io/kube-proxy:v1.33.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:bb04e9247da3aaeb96406b4d530a79fc865695b6807353dd1a28871df0d7f837\", size \"28198978\" in 1.221019138s" Sep 4 23:54:49.188716 containerd[1523]: time="2025-09-04T23:54:49.188546823Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.33.4\" returns image reference \"sha256:e19c0cda155dad39120317830ddb8b2bc22070f2c6a97973e96fb09ef504ee64\"" Sep 4 23:54:49.189621 containerd[1523]: time="2025-09-04T23:54:49.189597677Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Sep 4 23:54:49.192437 containerd[1523]: time="2025-09-04T23:54:49.192393602Z" level=info msg="CreateContainer within sandbox \"7e0ea619443d59f2252c1081096c693a1d422933af35d2a96b7fb466fd25f4ba\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 4 23:54:49.201523 containerd[1523]: time="2025-09-04T23:54:49.201467405Z" level=info msg="Container da2c32a8d26a73b44fc74d248ead6d8e1814656c87af825e6053028265d4ccde: CDI devices from CRI Config.CDIDevices: []" Sep 4 23:54:49.209532 containerd[1523]: time="2025-09-04T23:54:49.209476631Z" level=info msg="CreateContainer within sandbox \"7e0ea619443d59f2252c1081096c693a1d422933af35d2a96b7fb466fd25f4ba\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"da2c32a8d26a73b44fc74d248ead6d8e1814656c87af825e6053028265d4ccde\"" Sep 4 23:54:49.210322 containerd[1523]: time="2025-09-04T23:54:49.210289700Z" level=info msg="StartContainer for \"da2c32a8d26a73b44fc74d248ead6d8e1814656c87af825e6053028265d4ccde\"" Sep 4 23:54:49.211905 containerd[1523]: time="2025-09-04T23:54:49.211880727Z" level=info msg="connecting to shim da2c32a8d26a73b44fc74d248ead6d8e1814656c87af825e6053028265d4ccde" address="unix:///run/containerd/s/beb5197631c33eedf54d14bbb2b66a88cd3f53aa87543dbbeea787eae4a526f6" protocol=ttrpc version=3 Sep 4 23:54:49.233920 systemd[1]: Started cri-containerd-da2c32a8d26a73b44fc74d248ead6d8e1814656c87af825e6053028265d4ccde.scope - libcontainer container da2c32a8d26a73b44fc74d248ead6d8e1814656c87af825e6053028265d4ccde. Sep 4 23:54:49.268106 containerd[1523]: time="2025-09-04T23:54:49.268024198Z" level=info msg="StartContainer for \"da2c32a8d26a73b44fc74d248ead6d8e1814656c87af825e6053028265d4ccde\" returns successfully" Sep 4 23:54:49.932808 kubelet[1813]: E0904 23:54:49.932765 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:50.200615 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2979642036.mount: Deactivated successfully. Sep 4 23:54:50.233143 containerd[1523]: time="2025-09-04T23:54:50.233070092Z" 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 4 23:54:50.234367 containerd[1523]: time="2025-09-04T23:54:50.234238980Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=5125564" Sep 4 23:54:50.235096 containerd[1523]: time="2025-09-04T23:54:50.235064605Z" level=info msg="ImageCreate event name:\"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:50.237663 containerd[1523]: time="2025-09-04T23:54:50.237622383Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:50.238827 containerd[1523]: time="2025-09-04T23:54:50.238795202Z" 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 1.049164103s" Sep 4 23:54:50.239008 containerd[1523]: time="2025-09-04T23:54:50.238913767Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:bf6e087b7c89143a757bb62f368860d2454e71afe59ae44ecb1ab473fd00b759\"" Sep 4 23:54:50.242922 containerd[1523]: time="2025-09-04T23:54:50.242858792Z" level=info msg="CreateContainer within sandbox \"d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 4 23:54:50.251353 containerd[1523]: time="2025-09-04T23:54:50.251304325Z" level=info msg="Container c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2: CDI devices from CRI Config.CDIDevices: []" Sep 4 23:54:50.253285 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3872093898.mount: Deactivated successfully. Sep 4 23:54:50.258184 containerd[1523]: time="2025-09-04T23:54:50.258127407Z" level=info msg="CreateContainer within sandbox \"d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2\"" Sep 4 23:54:50.258798 containerd[1523]: time="2025-09-04T23:54:50.258770853Z" level=info msg="StartContainer for \"c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2\"" Sep 4 23:54:50.259898 containerd[1523]: time="2025-09-04T23:54:50.259858116Z" level=info msg="connecting to shim c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2" address="unix:///run/containerd/s/f46e99acf278467d3e56b3e8c5a9b106fb5c39b1be3664ad697fc36948820dce" protocol=ttrpc version=3 Sep 4 23:54:50.293145 systemd[1]: Started cri-containerd-c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2.scope - libcontainer container c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2. Sep 4 23:54:50.318219 systemd[1]: cri-containerd-c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2.scope: Deactivated successfully. Sep 4 23:54:50.319741 containerd[1523]: time="2025-09-04T23:54:50.319706494Z" level=info msg="StartContainer for \"c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2\" returns successfully" Sep 4 23:54:50.320957 containerd[1523]: time="2025-09-04T23:54:50.320920906Z" level=info msg="received exit event container_id:\"c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2\" id:\"c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2\" pid:2151 exited_at:{seconds:1757030090 nanos:320480137}" Sep 4 23:54:50.321048 containerd[1523]: time="2025-09-04T23:54:50.321031129Z" level=info msg="TaskExit event in podsandbox handler container_id:\"c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2\" id:\"c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2\" pid:2151 exited_at:{seconds:1757030090 nanos:320480137}" Sep 4 23:54:50.337519 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-c17c116c2110c5d27eee2acc2088cd23df63a9ec1feedbf6a4bd2e1a3cfe03b2-rootfs.mount: Deactivated successfully. Sep 4 23:54:50.932908 kubelet[1813]: E0904 23:54:50.932858 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:51.103110 containerd[1523]: time="2025-09-04T23:54:51.103060864Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Sep 4 23:54:51.116083 kubelet[1813]: I0904 23:54:51.116011 1813 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-j4w9f" podStartSLOduration=4.893504775 podStartE2EDuration="6.115994728s" podCreationTimestamp="2025-09-04 23:54:45 +0000 UTC" firstStartedPulling="2025-09-04 23:54:47.966982492 +0000 UTC m=+3.429347881" lastFinishedPulling="2025-09-04 23:54:49.189472445 +0000 UTC m=+4.651837834" observedRunningTime="2025-09-04 23:54:50.109247014 +0000 UTC m=+5.571612403" watchObservedRunningTime="2025-09-04 23:54:51.115994728 +0000 UTC m=+6.578360157" Sep 4 23:54:51.933530 kubelet[1813]: E0904 23:54:51.933464 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:52.587331 containerd[1523]: time="2025-09-04T23:54:52.586151154Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:52.587331 containerd[1523]: time="2025-09-04T23:54:52.586725521Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=28419854" Sep 4 23:54:52.587838 containerd[1523]: time="2025-09-04T23:54:52.587798376Z" level=info msg="ImageCreate event name:\"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:52.591523 containerd[1523]: time="2025-09-04T23:54:52.591482040Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:52.592852 containerd[1523]: time="2025-09-04T23:54:52.592819532Z" 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.48970843s" Sep 4 23:54:52.592898 containerd[1523]: time="2025-09-04T23:54:52.592859375Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:253e2cac1f011511dce473642669aa3b75987d78cb108ecc51c8c2fa69f3e587\"" Sep 4 23:54:52.596383 containerd[1523]: time="2025-09-04T23:54:52.596339331Z" level=info msg="CreateContainer within sandbox \"d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 4 23:54:52.603688 containerd[1523]: time="2025-09-04T23:54:52.602447210Z" level=info msg="Container 801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d: CDI devices from CRI Config.CDIDevices: []" Sep 4 23:54:52.605192 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3110633401.mount: Deactivated successfully. Sep 4 23:54:52.611406 containerd[1523]: time="2025-09-04T23:54:52.611366360Z" level=info msg="CreateContainer within sandbox \"d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d\"" Sep 4 23:54:52.611937 containerd[1523]: time="2025-09-04T23:54:52.611906896Z" level=info msg="StartContainer for \"801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d\"" Sep 4 23:54:52.613126 containerd[1523]: time="2025-09-04T23:54:52.613056192Z" level=info msg="connecting to shim 801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d" address="unix:///run/containerd/s/f46e99acf278467d3e56b3e8c5a9b106fb5c39b1be3664ad697fc36948820dce" protocol=ttrpc version=3 Sep 4 23:54:52.632940 systemd[1]: Started cri-containerd-801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d.scope - libcontainer container 801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d. Sep 4 23:54:52.656696 systemd[1]: cri-containerd-801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d.scope: Deactivated successfully. Sep 4 23:54:52.658011 containerd[1523]: time="2025-09-04T23:54:52.657804821Z" level=info msg="received exit event container_id:\"801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d\" id:\"801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d\" pid:2227 exited_at:{seconds:1757030092 nanos:657513929}" Sep 4 23:54:52.658481 containerd[1523]: time="2025-09-04T23:54:52.658449576Z" level=info msg="TaskExit event in podsandbox handler container_id:\"801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d\" id:\"801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d\" pid:2227 exited_at:{seconds:1757030092 nanos:657513929}" Sep 4 23:54:52.659267 kubelet[1813]: I0904 23:54:52.659233 1813 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Sep 4 23:54:52.666059 containerd[1523]: time="2025-09-04T23:54:52.666016042Z" level=info msg="StartContainer for \"801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d\" returns successfully" Sep 4 23:54:52.675979 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-801011929aa55a3248e797a6ed537751884aa17b23b557274efb84eb1befa41d-rootfs.mount: Deactivated successfully. Sep 4 23:54:52.933931 kubelet[1813]: E0904 23:54:52.933803 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:53.121345 containerd[1523]: time="2025-09-04T23:54:53.121309065Z" level=info msg="CreateContainer within sandbox \"d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 4 23:54:53.128767 containerd[1523]: time="2025-09-04T23:54:53.128058723Z" level=info msg="Container 281e2e929b5d66693743f696fbc7854e29b46b30727d830df247a1ba9cc31fad: CDI devices from CRI Config.CDIDevices: []" Sep 4 23:54:53.136916 containerd[1523]: time="2025-09-04T23:54:53.136875465Z" level=info msg="CreateContainer within sandbox \"d808af2fbae3e8e5c43d42bb75e061d7ca12b2ee73291ccd2a167e5c9fa88988\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"281e2e929b5d66693743f696fbc7854e29b46b30727d830df247a1ba9cc31fad\"" Sep 4 23:54:53.137609 containerd[1523]: time="2025-09-04T23:54:53.137567137Z" level=info msg="StartContainer for \"281e2e929b5d66693743f696fbc7854e29b46b30727d830df247a1ba9cc31fad\"" Sep 4 23:54:53.138482 containerd[1523]: time="2025-09-04T23:54:53.138456253Z" level=info msg="connecting to shim 281e2e929b5d66693743f696fbc7854e29b46b30727d830df247a1ba9cc31fad" address="unix:///run/containerd/s/f46e99acf278467d3e56b3e8c5a9b106fb5c39b1be3664ad697fc36948820dce" protocol=ttrpc version=3 Sep 4 23:54:53.156938 systemd[1]: Started cri-containerd-281e2e929b5d66693743f696fbc7854e29b46b30727d830df247a1ba9cc31fad.scope - libcontainer container 281e2e929b5d66693743f696fbc7854e29b46b30727d830df247a1ba9cc31fad. Sep 4 23:54:53.182634 containerd[1523]: time="2025-09-04T23:54:53.182598228Z" level=info msg="StartContainer for \"281e2e929b5d66693743f696fbc7854e29b46b30727d830df247a1ba9cc31fad\" returns successfully" Sep 4 23:54:53.934439 kubelet[1813]: E0904 23:54:53.934391 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:54.124948 kubelet[1813]: I0904 23:54:54.124891 1813 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-fq7ld" podStartSLOduration=4.545770879 podStartE2EDuration="9.124871736s" podCreationTimestamp="2025-09-04 23:54:45 +0000 UTC" firstStartedPulling="2025-09-04 23:54:48.014656125 +0000 UTC m=+3.477021514" lastFinishedPulling="2025-09-04 23:54:52.593757022 +0000 UTC m=+8.056122371" observedRunningTime="2025-09-04 23:54:54.124015197 +0000 UTC m=+9.586380586" watchObservedRunningTime="2025-09-04 23:54:54.124871736 +0000 UTC m=+9.587237125" Sep 4 23:54:54.248879 systemd-networkd[1437]: flannel.1: Link UP Sep 4 23:54:54.248889 systemd-networkd[1437]: flannel.1: Gained carrier Sep 4 23:54:54.935340 kubelet[1813]: E0904 23:54:54.935289 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:55.856002 systemd-networkd[1437]: flannel.1: Gained IPv6LL Sep 4 23:54:55.935627 kubelet[1813]: E0904 23:54:55.935583 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:56.476915 systemd[1]: Created slice kubepods-besteffort-pod55a61c00_d85c_4a38_a629_87fe5a3313fd.slice - libcontainer container kubepods-besteffort-pod55a61c00_d85c_4a38_a629_87fe5a3313fd.slice. Sep 4 23:54:56.515091 kubelet[1813]: I0904 23:54:56.515038 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wvvcq\" (UniqueName: \"kubernetes.io/projected/55a61c00-d85c-4a38-a629-87fe5a3313fd-kube-api-access-wvvcq\") pod \"nginx-deployment-7fcdb87857-b8g6k\" (UID: \"55a61c00-d85c-4a38-a629-87fe5a3313fd\") " pod="default/nginx-deployment-7fcdb87857-b8g6k" Sep 4 23:54:56.779884 containerd[1523]: time="2025-09-04T23:54:56.779765981Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-b8g6k,Uid:55a61c00-d85c-4a38-a629-87fe5a3313fd,Namespace:default,Attempt:0,}" Sep 4 23:54:56.797105 systemd-networkd[1437]: cni0: Link UP Sep 4 23:54:56.797111 systemd-networkd[1437]: cni0: Gained carrier Sep 4 23:54:56.799387 systemd-networkd[1437]: cni0: Lost carrier Sep 4 23:54:56.802068 systemd-networkd[1437]: veth4b462a39: Link UP Sep 4 23:54:56.804563 kernel: cni0: port 1(veth4b462a39) entered blocking state Sep 4 23:54:56.804650 kernel: cni0: port 1(veth4b462a39) entered disabled state Sep 4 23:54:56.804671 kernel: veth4b462a39: entered allmulticast mode Sep 4 23:54:56.804684 kernel: veth4b462a39: entered promiscuous mode Sep 4 23:54:56.811444 kernel: cni0: port 1(veth4b462a39) entered blocking state Sep 4 23:54:56.811511 kernel: cni0: port 1(veth4b462a39) entered forwarding state Sep 4 23:54:56.811614 systemd-networkd[1437]: veth4b462a39: Gained carrier Sep 4 23:54:56.812584 systemd-networkd[1437]: cni0: Gained carrier Sep 4 23:54:56.815303 containerd[1523]: 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 4 23:54:56.815303 containerd[1523]: delegateAdd: netconf sent to delegate plugin: Sep 4 23:54:56.842685 containerd[1523]: {"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-04T23:54:56.842626428Z" level=info msg="connecting to shim 70777a68518f996f3d5e9c048a4ec2255a163d749e5913f4292604957d19e7b0" address="unix:///run/containerd/s/958effd1d126027dbf3161a4cb0603bc5c57626d6542b74bf672ce2d7b49ddb7" namespace=k8s.io protocol=ttrpc version=3 Sep 4 23:54:56.880936 systemd[1]: Started cri-containerd-70777a68518f996f3d5e9c048a4ec2255a163d749e5913f4292604957d19e7b0.scope - libcontainer container 70777a68518f996f3d5e9c048a4ec2255a163d749e5913f4292604957d19e7b0. Sep 4 23:54:56.892292 systemd-resolved[1355]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 23:54:56.913969 containerd[1523]: time="2025-09-04T23:54:56.913918433Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-b8g6k,Uid:55a61c00-d85c-4a38-a629-87fe5a3313fd,Namespace:default,Attempt:0,} returns sandbox id \"70777a68518f996f3d5e9c048a4ec2255a163d749e5913f4292604957d19e7b0\"" Sep 4 23:54:56.915647 containerd[1523]: time="2025-09-04T23:54:56.915590576Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 23:54:56.936174 kubelet[1813]: E0904 23:54:56.936114 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:57.936772 kubelet[1813]: E0904 23:54:57.936644 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:58.224875 systemd-networkd[1437]: cni0: Gained IPv6LL Sep 4 23:54:58.415993 systemd-networkd[1437]: veth4b462a39: Gained IPv6LL Sep 4 23:54:58.731801 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2930833011.mount: Deactivated successfully. Sep 4 23:54:58.937748 kubelet[1813]: E0904 23:54:58.937652 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:54:59.501987 containerd[1523]: time="2025-09-04T23:54:59.501934117Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:59.503067 containerd[1523]: time="2025-09-04T23:54:59.502912446Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69986522" Sep 4 23:54:59.503605 containerd[1523]: time="2025-09-04T23:54:59.503566667Z" level=info msg="ImageCreate event name:\"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:59.505850 containerd[1523]: time="2025-09-04T23:54:59.505823933Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:54:59.506826 containerd[1523]: time="2025-09-04T23:54:59.506804383Z" 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.591156339s" Sep 4 23:54:59.506872 containerd[1523]: time="2025-09-04T23:54:59.506830925Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 4 23:54:59.510840 containerd[1523]: time="2025-09-04T23:54:59.510764096Z" level=info msg="CreateContainer within sandbox \"70777a68518f996f3d5e9c048a4ec2255a163d749e5913f4292604957d19e7b0\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 4 23:54:59.516755 containerd[1523]: time="2025-09-04T23:54:59.516686992Z" level=info msg="Container bc342830e82cf4635532da90b361db4752f5b490555fa1e20c9affd70b2e32d3: CDI devices from CRI Config.CDIDevices: []" Sep 4 23:54:59.521350 containerd[1523]: time="2025-09-04T23:54:59.521294120Z" level=info msg="CreateContainer within sandbox \"70777a68518f996f3d5e9c048a4ec2255a163d749e5913f4292604957d19e7b0\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"bc342830e82cf4635532da90b361db4752f5b490555fa1e20c9affd70b2e32d3\"" Sep 4 23:54:59.521783 containerd[1523]: time="2025-09-04T23:54:59.521759304Z" level=info msg="StartContainer for \"bc342830e82cf4635532da90b361db4752f5b490555fa1e20c9affd70b2e32d3\"" Sep 4 23:54:59.522751 containerd[1523]: time="2025-09-04T23:54:59.522565891Z" level=info msg="connecting to shim bc342830e82cf4635532da90b361db4752f5b490555fa1e20c9affd70b2e32d3" address="unix:///run/containerd/s/958effd1d126027dbf3161a4cb0603bc5c57626d6542b74bf672ce2d7b49ddb7" protocol=ttrpc version=3 Sep 4 23:54:59.536952 systemd[1]: Started cri-containerd-bc342830e82cf4635532da90b361db4752f5b490555fa1e20c9affd70b2e32d3.scope - libcontainer container bc342830e82cf4635532da90b361db4752f5b490555fa1e20c9affd70b2e32d3. Sep 4 23:54:59.559853 containerd[1523]: time="2025-09-04T23:54:59.559681729Z" level=info msg="StartContainer for \"bc342830e82cf4635532da90b361db4752f5b490555fa1e20c9affd70b2e32d3\" returns successfully" Sep 4 23:54:59.938786 kubelet[1813]: E0904 23:54:59.938639 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:00.130829 kubelet[1813]: I0904 23:55:00.130755 1813 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-b8g6k" podStartSLOduration=1.53831321 podStartE2EDuration="4.130722245s" podCreationTimestamp="2025-09-04 23:54:56 +0000 UTC" firstStartedPulling="2025-09-04 23:54:56.91509969 +0000 UTC m=+12.377465079" lastFinishedPulling="2025-09-04 23:54:59.507508725 +0000 UTC m=+14.969874114" observedRunningTime="2025-09-04 23:55:00.130611405 +0000 UTC m=+15.592976754" watchObservedRunningTime="2025-09-04 23:55:00.130722245 +0000 UTC m=+15.593087634" Sep 4 23:55:00.938854 kubelet[1813]: E0904 23:55:00.938795 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:01.939486 kubelet[1813]: E0904 23:55:01.939437 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:02.940217 kubelet[1813]: E0904 23:55:02.940161 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:02.993245 systemd[1]: Created slice kubepods-besteffort-pod9e19b24e_6ce8_4124_9bee_cd8c296bf7be.slice - libcontainer container kubepods-besteffort-pod9e19b24e_6ce8_4124_9bee_cd8c296bf7be.slice. Sep 4 23:55:03.051431 kubelet[1813]: I0904 23:55:03.051379 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/9e19b24e-6ce8-4124-9bee-cd8c296bf7be-data\") pod \"nfs-server-provisioner-0\" (UID: \"9e19b24e-6ce8-4124-9bee-cd8c296bf7be\") " pod="default/nfs-server-provisioner-0" Sep 4 23:55:03.051431 kubelet[1813]: I0904 23:55:03.051429 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-spsqz\" (UniqueName: \"kubernetes.io/projected/9e19b24e-6ce8-4124-9bee-cd8c296bf7be-kube-api-access-spsqz\") pod \"nfs-server-provisioner-0\" (UID: \"9e19b24e-6ce8-4124-9bee-cd8c296bf7be\") " pod="default/nfs-server-provisioner-0" Sep 4 23:55:03.298226 containerd[1523]: time="2025-09-04T23:55:03.298121759Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9e19b24e-6ce8-4124-9bee-cd8c296bf7be,Namespace:default,Attempt:0,}" Sep 4 23:55:03.308780 systemd-networkd[1437]: vethbb0e5bd2: Link UP Sep 4 23:55:03.311765 kernel: cni0: port 2(vethbb0e5bd2) entered blocking state Sep 4 23:55:03.311816 kernel: cni0: port 2(vethbb0e5bd2) entered disabled state Sep 4 23:55:03.311846 kernel: vethbb0e5bd2: entered allmulticast mode Sep 4 23:55:03.311859 kernel: vethbb0e5bd2: entered promiscuous mode Sep 4 23:55:03.319203 kernel: cni0: port 2(vethbb0e5bd2) entered blocking state Sep 4 23:55:03.319283 kernel: cni0: port 2(vethbb0e5bd2) entered forwarding state Sep 4 23:55:03.319280 systemd-networkd[1437]: vethbb0e5bd2: Gained carrier Sep 4 23:55:03.321767 containerd[1523]: 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)(0x40001047f0), "name":"cbr0", "type":"bridge"} Sep 4 23:55:03.321767 containerd[1523]: delegateAdd: netconf sent to delegate plugin: Sep 4 23:55:03.385501 containerd[1523]: {"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-04T23:55:03.385437079Z" level=info msg="connecting to shim 80c52086c76bcdf125fea6a098e6475f2a896568bbd645ef57c191482bbb6f76" address="unix:///run/containerd/s/9a8b992385f227db4fd25f69aad37b9e4583e3fad69481c3b8999df29e093871" namespace=k8s.io protocol=ttrpc version=3 Sep 4 23:55:03.416980 systemd[1]: Started cri-containerd-80c52086c76bcdf125fea6a098e6475f2a896568bbd645ef57c191482bbb6f76.scope - libcontainer container 80c52086c76bcdf125fea6a098e6475f2a896568bbd645ef57c191482bbb6f76. Sep 4 23:55:03.426982 systemd-resolved[1355]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 23:55:03.446851 containerd[1523]: time="2025-09-04T23:55:03.446803102Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:9e19b24e-6ce8-4124-9bee-cd8c296bf7be,Namespace:default,Attempt:0,} returns sandbox id \"80c52086c76bcdf125fea6a098e6475f2a896568bbd645ef57c191482bbb6f76\"" Sep 4 23:55:03.448395 containerd[1523]: time="2025-09-04T23:55:03.448365219Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 4 23:55:03.940879 kubelet[1813]: E0904 23:55:03.940839 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:04.928990 kubelet[1813]: E0904 23:55:04.928935 1813 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:04.941287 kubelet[1813]: E0904 23:55:04.941253 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:05.021895 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3112671342.mount: Deactivated successfully. Sep 4 23:55:05.072171 systemd-networkd[1437]: vethbb0e5bd2: Gained IPv6LL Sep 4 23:55:05.941574 kubelet[1813]: E0904 23:55:05.941529 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:06.358686 containerd[1523]: time="2025-09-04T23:55:06.358568861Z" 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 4 23:55:06.359305 containerd[1523]: time="2025-09-04T23:55:06.359277531Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Sep 4 23:55:06.360268 containerd[1523]: time="2025-09-04T23:55:06.360238283Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:55:06.362749 containerd[1523]: time="2025-09-04T23:55:06.362709846Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:55:06.363612 containerd[1523]: time="2025-09-04T23:55:06.363573526Z" 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 2.915172772s" Sep 4 23:55:06.363612 containerd[1523]: time="2025-09-04T23:55:06.363605056Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Sep 4 23:55:06.373671 containerd[1523]: time="2025-09-04T23:55:06.373634113Z" level=info msg="CreateContainer within sandbox \"80c52086c76bcdf125fea6a098e6475f2a896568bbd645ef57c191482bbb6f76\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 4 23:55:06.389660 containerd[1523]: time="2025-09-04T23:55:06.389620305Z" level=info msg="Container 6a04239677b67b56d1093b6911bde6b6d3354eef2977e2d425d94d6e6af94f68: CDI devices from CRI Config.CDIDevices: []" Sep 4 23:55:06.401562 containerd[1523]: time="2025-09-04T23:55:06.401508045Z" level=info msg="CreateContainer within sandbox \"80c52086c76bcdf125fea6a098e6475f2a896568bbd645ef57c191482bbb6f76\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"6a04239677b67b56d1093b6911bde6b6d3354eef2977e2d425d94d6e6af94f68\"" Sep 4 23:55:06.402134 containerd[1523]: time="2025-09-04T23:55:06.402108560Z" level=info msg="StartContainer for \"6a04239677b67b56d1093b6911bde6b6d3354eef2977e2d425d94d6e6af94f68\"" Sep 4 23:55:06.403197 containerd[1523]: time="2025-09-04T23:55:06.403136534Z" level=info msg="connecting to shim 6a04239677b67b56d1093b6911bde6b6d3354eef2977e2d425d94d6e6af94f68" address="unix:///run/containerd/s/9a8b992385f227db4fd25f69aad37b9e4583e3fad69481c3b8999df29e093871" protocol=ttrpc version=3 Sep 4 23:55:06.420882 systemd[1]: Started cri-containerd-6a04239677b67b56d1093b6911bde6b6d3354eef2977e2d425d94d6e6af94f68.scope - libcontainer container 6a04239677b67b56d1093b6911bde6b6d3354eef2977e2d425d94d6e6af94f68. Sep 4 23:55:06.445488 containerd[1523]: time="2025-09-04T23:55:06.445431629Z" level=info msg="StartContainer for \"6a04239677b67b56d1093b6911bde6b6d3354eef2977e2d425d94d6e6af94f68\" returns successfully" Sep 4 23:55:06.942655 kubelet[1813]: E0904 23:55:06.942594 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:07.943622 kubelet[1813]: E0904 23:55:07.943552 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:08.943888 kubelet[1813]: E0904 23:55:08.943840 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:09.944051 kubelet[1813]: E0904 23:55:09.944002 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:10.944600 kubelet[1813]: E0904 23:55:10.944528 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:11.693208 kubelet[1813]: I0904 23:55:11.692853 1813 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=6.773702619 podStartE2EDuration="9.692831606s" podCreationTimestamp="2025-09-04 23:55:02 +0000 UTC" firstStartedPulling="2025-09-04 23:55:03.448074198 +0000 UTC m=+18.910439587" lastFinishedPulling="2025-09-04 23:55:06.367203185 +0000 UTC m=+21.829568574" observedRunningTime="2025-09-04 23:55:07.156056572 +0000 UTC m=+22.618421961" watchObservedRunningTime="2025-09-04 23:55:11.692831606 +0000 UTC m=+27.155196995" Sep 4 23:55:11.703983 systemd[1]: Created slice kubepods-besteffort-pod77bff6da_4252_4215_ac3c_2f080b4c0f14.slice - libcontainer container kubepods-besteffort-pod77bff6da_4252_4215_ac3c_2f080b4c0f14.slice. Sep 4 23:55:11.805603 kubelet[1813]: I0904 23:55:11.805546 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-87ed713f-e651-4f67-a799-a582d0892f9f\" (UniqueName: \"kubernetes.io/nfs/77bff6da-4252-4215-ac3c-2f080b4c0f14-pvc-87ed713f-e651-4f67-a799-a582d0892f9f\") pod \"test-pod-1\" (UID: \"77bff6da-4252-4215-ac3c-2f080b4c0f14\") " pod="default/test-pod-1" Sep 4 23:55:11.805603 kubelet[1813]: I0904 23:55:11.805597 1813 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-4kv7l\" (UniqueName: \"kubernetes.io/projected/77bff6da-4252-4215-ac3c-2f080b4c0f14-kube-api-access-4kv7l\") pod \"test-pod-1\" (UID: \"77bff6da-4252-4215-ac3c-2f080b4c0f14\") " pod="default/test-pod-1" Sep 4 23:55:11.926835 kernel: netfs: FS-Cache loaded Sep 4 23:55:11.945539 kubelet[1813]: E0904 23:55:11.945229 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:11.950769 kernel: RPC: Registered named UNIX socket transport module. Sep 4 23:55:11.950876 kernel: RPC: Registered udp transport module. Sep 4 23:55:11.950912 kernel: RPC: Registered tcp transport module. Sep 4 23:55:11.952337 kernel: RPC: Registered tcp-with-tls transport module. Sep 4 23:55:11.952391 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 4 23:55:12.141902 kernel: NFS: Registering the id_resolver key type Sep 4 23:55:12.142013 kernel: Key type id_resolver registered Sep 4 23:55:12.142032 kernel: Key type id_legacy registered Sep 4 23:55:12.164762 nfsidmap[2788]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Sep 4 23:55:12.165490 nfsidmap[2788]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 4 23:55:12.166792 nfsidmap[2789]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Sep 4 23:55:12.166932 nfsidmap[2789]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 4 23:55:12.177947 nfsrahead[2791]: setting /var/lib/kubelet/pods/77bff6da-4252-4215-ac3c-2f080b4c0f14/volumes/kubernetes.io~nfs/pvc-87ed713f-e651-4f67-a799-a582d0892f9f readahead to 128 Sep 4 23:55:12.307873 containerd[1523]: time="2025-09-04T23:55:12.307267357Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:77bff6da-4252-4215-ac3c-2f080b4c0f14,Namespace:default,Attempt:0,}" Sep 4 23:55:12.320327 systemd-networkd[1437]: vethe49f80c8: Link UP Sep 4 23:55:12.323255 kernel: cni0: port 3(vethe49f80c8) entered blocking state Sep 4 23:55:12.323343 kernel: cni0: port 3(vethe49f80c8) entered disabled state Sep 4 23:55:12.323371 kernel: vethe49f80c8: entered allmulticast mode Sep 4 23:55:12.323982 kernel: vethe49f80c8: entered promiscuous mode Sep 4 23:55:12.329678 systemd-networkd[1437]: vethe49f80c8: Gained carrier Sep 4 23:55:12.329832 kernel: cni0: port 3(vethe49f80c8) entered blocking state Sep 4 23:55:12.329857 kernel: cni0: port 3(vethe49f80c8) entered forwarding state Sep 4 23:55:12.332012 containerd[1523]: 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 4 23:55:12.332012 containerd[1523]: delegateAdd: netconf sent to delegate plugin: Sep 4 23:55:12.351824 containerd[1523]: {"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-04T23:55:12.351724546Z" level=info msg="connecting to shim 2d82473b9c3ba0bc0a694dcb9a99496279eab7e08d3940b11b3a87b2a5b8f5e2" address="unix:///run/containerd/s/ef8cdf395e15dbaceea39aad5139c65351cbbecdd45c639fe5e48aa268a3236b" namespace=k8s.io protocol=ttrpc version=3 Sep 4 23:55:12.382993 systemd[1]: Started cri-containerd-2d82473b9c3ba0bc0a694dcb9a99496279eab7e08d3940b11b3a87b2a5b8f5e2.scope - libcontainer container 2d82473b9c3ba0bc0a694dcb9a99496279eab7e08d3940b11b3a87b2a5b8f5e2. Sep 4 23:55:12.396679 systemd-resolved[1355]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 4 23:55:12.430127 containerd[1523]: time="2025-09-04T23:55:12.429778229Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:77bff6da-4252-4215-ac3c-2f080b4c0f14,Namespace:default,Attempt:0,} returns sandbox id \"2d82473b9c3ba0bc0a694dcb9a99496279eab7e08d3940b11b3a87b2a5b8f5e2\"" Sep 4 23:55:12.430824 containerd[1523]: time="2025-09-04T23:55:12.430773712Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 4 23:55:12.684785 containerd[1523]: time="2025-09-04T23:55:12.684334452Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 4 23:55:12.685921 containerd[1523]: time="2025-09-04T23:55:12.685884185Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 4 23:55:12.688569 containerd[1523]: time="2025-09-04T23:55:12.688527818Z" 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 257.713619ms" Sep 4 23:55:12.688569 containerd[1523]: time="2025-09-04T23:55:12.688562023Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:9fddf21fd9c2634e7bf6e633e36b0fb227f6cd5fbe1b3334a16de3ab50f31e5e\"" Sep 4 23:55:12.692196 containerd[1523]: time="2025-09-04T23:55:12.692165652Z" level=info msg="CreateContainer within sandbox \"2d82473b9c3ba0bc0a694dcb9a99496279eab7e08d3940b11b3a87b2a5b8f5e2\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 4 23:55:12.700765 containerd[1523]: time="2025-09-04T23:55:12.700691886Z" level=info msg="Container 58e4bd4e67fbfb1890ddc4d5afeb64327acf90bb65eeff106de630997c2ae0f5: CDI devices from CRI Config.CDIDevices: []" Sep 4 23:55:12.709098 containerd[1523]: time="2025-09-04T23:55:12.709041772Z" level=info msg="CreateContainer within sandbox \"2d82473b9c3ba0bc0a694dcb9a99496279eab7e08d3940b11b3a87b2a5b8f5e2\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"58e4bd4e67fbfb1890ddc4d5afeb64327acf90bb65eeff106de630997c2ae0f5\"" Sep 4 23:55:12.709764 containerd[1523]: time="2025-09-04T23:55:12.709717162Z" level=info msg="StartContainer for \"58e4bd4e67fbfb1890ddc4d5afeb64327acf90bb65eeff106de630997c2ae0f5\"" Sep 4 23:55:12.711208 containerd[1523]: time="2025-09-04T23:55:12.711179801Z" level=info msg="connecting to shim 58e4bd4e67fbfb1890ddc4d5afeb64327acf90bb65eeff106de630997c2ae0f5" address="unix:///run/containerd/s/ef8cdf395e15dbaceea39aad5139c65351cbbecdd45c639fe5e48aa268a3236b" protocol=ttrpc version=3 Sep 4 23:55:12.729919 systemd[1]: Started cri-containerd-58e4bd4e67fbfb1890ddc4d5afeb64327acf90bb65eeff106de630997c2ae0f5.scope - libcontainer container 58e4bd4e67fbfb1890ddc4d5afeb64327acf90bb65eeff106de630997c2ae0f5. Sep 4 23:55:12.756512 containerd[1523]: time="2025-09-04T23:55:12.756121030Z" level=info msg="StartContainer for \"58e4bd4e67fbfb1890ddc4d5afeb64327acf90bb65eeff106de630997c2ae0f5\" returns successfully" Sep 4 23:55:12.946409 kubelet[1813]: E0904 23:55:12.946272 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:13.157816 kubelet[1813]: I0904 23:55:13.157706 1813 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=9.899069833 podStartE2EDuration="10.15768604s" podCreationTimestamp="2025-09-04 23:55:03 +0000 UTC" firstStartedPulling="2025-09-04 23:55:12.43052095 +0000 UTC m=+27.892886299" lastFinishedPulling="2025-09-04 23:55:12.689137157 +0000 UTC m=+28.151502506" observedRunningTime="2025-09-04 23:55:13.157301061 +0000 UTC m=+28.619666410" watchObservedRunningTime="2025-09-04 23:55:13.15768604 +0000 UTC m=+28.620051429" Sep 4 23:55:13.647932 systemd-networkd[1437]: vethe49f80c8: Gained IPv6LL Sep 4 23:55:13.946530 kubelet[1813]: E0904 23:55:13.946388 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 4 23:55:14.947161 kubelet[1813]: E0904 23:55:14.947118 1813 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"