Jul 6 23:21:31.836172 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 6 23:21:31.836195 kernel: Linux version 6.12.35-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 Sun Jul 6 21:57:11 -00 2025 Jul 6 23:21:31.836205 kernel: KASLR enabled Jul 6 23:21:31.836211 kernel: efi: EFI v2.7 by EDK II Jul 6 23:21:31.836217 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb228018 ACPI 2.0=0xdb9b8018 RNG=0xdb9b8a18 MEMRESERVE=0xdb21fd18 Jul 6 23:21:31.836223 kernel: random: crng init done Jul 6 23:21:31.836230 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Jul 6 23:21:31.836236 kernel: secureboot: Secure boot enabled Jul 6 23:21:31.836242 kernel: ACPI: Early table checksum verification disabled Jul 6 23:21:31.836249 kernel: ACPI: RSDP 0x00000000DB9B8018 000024 (v02 BOCHS ) Jul 6 23:21:31.836255 kernel: ACPI: XSDT 0x00000000DB9B8F18 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 6 23:21:31.836262 kernel: ACPI: FACP 0x00000000DB9B8B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:21:31.836268 kernel: ACPI: DSDT 0x00000000DB904018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:21:31.836274 kernel: ACPI: APIC 0x00000000DB9B8C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:21:31.836281 kernel: ACPI: PPTT 0x00000000DB9B8098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:21:31.836289 kernel: ACPI: GTDT 0x00000000DB9B8818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:21:31.836295 kernel: ACPI: MCFG 0x00000000DB9B8A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:21:31.836302 kernel: ACPI: SPCR 0x00000000DB9B8918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:21:31.836308 kernel: ACPI: DBG2 0x00000000DB9B8998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:21:31.836314 kernel: ACPI: IORT 0x00000000DB9B8198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 6 23:21:31.836321 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 6 23:21:31.836327 kernel: ACPI: Use ACPI SPCR as default console: Yes Jul 6 23:21:31.836333 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 6 23:21:31.836340 kernel: NODE_DATA(0) allocated [mem 0xdc737a00-0xdc73efff] Jul 6 23:21:31.836346 kernel: Zone ranges: Jul 6 23:21:31.836353 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 6 23:21:31.836360 kernel: DMA32 empty Jul 6 23:21:31.836366 kernel: Normal empty Jul 6 23:21:31.836372 kernel: Device empty Jul 6 23:21:31.836378 kernel: Movable zone start for each node Jul 6 23:21:31.836384 kernel: Early memory node ranges Jul 6 23:21:31.836390 kernel: node 0: [mem 0x0000000040000000-0x00000000dbb4ffff] Jul 6 23:21:31.836397 kernel: node 0: [mem 0x00000000dbb50000-0x00000000dbe7ffff] Jul 6 23:21:31.836403 kernel: node 0: [mem 0x00000000dbe80000-0x00000000dbe9ffff] Jul 6 23:21:31.836409 kernel: node 0: [mem 0x00000000dbea0000-0x00000000dbedffff] Jul 6 23:21:31.836485 kernel: node 0: [mem 0x00000000dbee0000-0x00000000dbf1ffff] Jul 6 23:21:31.836494 kernel: node 0: [mem 0x00000000dbf20000-0x00000000dbf6ffff] Jul 6 23:21:31.836504 kernel: node 0: [mem 0x00000000dbf70000-0x00000000dcbfffff] Jul 6 23:21:31.836511 kernel: node 0: [mem 0x00000000dcc00000-0x00000000dcfdffff] Jul 6 23:21:31.836517 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Jul 6 23:21:31.836527 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 6 23:21:31.836534 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 6 23:21:31.836541 kernel: cma: Reserved 16 MiB at 0x00000000d7a00000 on node -1 Jul 6 23:21:31.836547 kernel: psci: probing for conduit method from ACPI. Jul 6 23:21:31.836556 kernel: psci: PSCIv1.1 detected in firmware. Jul 6 23:21:31.836562 kernel: psci: Using standard PSCI v0.2 function IDs Jul 6 23:21:31.836569 kernel: psci: Trusted OS migration not required Jul 6 23:21:31.836576 kernel: psci: SMC Calling Convention v1.1 Jul 6 23:21:31.836583 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 6 23:21:31.836591 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Jul 6 23:21:31.836598 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Jul 6 23:21:31.836604 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 6 23:21:31.836611 kernel: Detected PIPT I-cache on CPU0 Jul 6 23:21:31.836619 kernel: CPU features: detected: GIC system register CPU interface Jul 6 23:21:31.836626 kernel: CPU features: detected: Spectre-v4 Jul 6 23:21:31.836633 kernel: CPU features: detected: Spectre-BHB Jul 6 23:21:31.836639 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 6 23:21:31.836646 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 6 23:21:31.836653 kernel: CPU features: detected: ARM erratum 1418040 Jul 6 23:21:31.836660 kernel: CPU features: detected: SSBS not fully self-synchronizing Jul 6 23:21:31.836666 kernel: alternatives: applying boot alternatives Jul 6 23:21:31.836675 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=d1bbaf8ae8f23de11dc703e14022523825f85f007c0c35003d7559228cbdda22 Jul 6 23:21:31.836682 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 6 23:21:31.836689 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 6 23:21:31.836697 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 6 23:21:31.836704 kernel: Fallback order for Node 0: 0 Jul 6 23:21:31.836710 kernel: Built 1 zonelists, mobility grouping on. Total pages: 643072 Jul 6 23:21:31.836717 kernel: Policy zone: DMA Jul 6 23:21:31.836724 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 6 23:21:31.836731 kernel: software IO TLB: SWIOTLB bounce buffer size adjusted to 2MB Jul 6 23:21:31.836738 kernel: software IO TLB: area num 4. Jul 6 23:21:31.836745 kernel: software IO TLB: SWIOTLB bounce buffer size roundup to 4MB Jul 6 23:21:31.836752 kernel: software IO TLB: mapped [mem 0x00000000db504000-0x00000000db904000] (4MB) Jul 6 23:21:31.836758 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 6 23:21:31.836765 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 6 23:21:31.836773 kernel: rcu: RCU event tracing is enabled. Jul 6 23:21:31.836781 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 6 23:21:31.836788 kernel: Trampoline variant of Tasks RCU enabled. Jul 6 23:21:31.836795 kernel: Tracing variant of Tasks RCU enabled. Jul 6 23:21:31.836802 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 6 23:21:31.836809 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 6 23:21:31.836816 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 6 23:21:31.836823 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Jul 6 23:21:31.836830 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 6 23:21:31.836836 kernel: GICv3: 256 SPIs implemented Jul 6 23:21:31.836843 kernel: GICv3: 0 Extended SPIs implemented Jul 6 23:21:31.836850 kernel: Root IRQ handler: gic_handle_irq Jul 6 23:21:31.836858 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Jul 6 23:21:31.836865 kernel: GICv3: GICD_CTRL.DS=1, SCR_EL3.FIQ=0 Jul 6 23:21:31.836872 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 6 23:21:31.836879 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 6 23:21:31.836905 kernel: ITS@0x0000000008080000: allocated 8192 Devices @40110000 (indirect, esz 8, psz 64K, shr 1) Jul 6 23:21:31.836914 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @40120000 (flat, esz 8, psz 64K, shr 1) Jul 6 23:21:31.836922 kernel: GICv3: using LPI property table @0x0000000040130000 Jul 6 23:21:31.836928 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040140000 Jul 6 23:21:31.836935 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 6 23:21:31.836942 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 6 23:21:31.836949 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 6 23:21:31.836956 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 6 23:21:31.836965 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 6 23:21:31.836972 kernel: arm-pv: using stolen time PV Jul 6 23:21:31.836979 kernel: Console: colour dummy device 80x25 Jul 6 23:21:31.836987 kernel: ACPI: Core revision 20240827 Jul 6 23:21:31.836994 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 6 23:21:31.837001 kernel: pid_max: default: 32768 minimum: 301 Jul 6 23:21:31.837008 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 6 23:21:31.837015 kernel: landlock: Up and running. Jul 6 23:21:31.837022 kernel: SELinux: Initializing. Jul 6 23:21:31.837030 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 6 23:21:31.837037 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 6 23:21:31.837044 kernel: rcu: Hierarchical SRCU implementation. Jul 6 23:21:31.837051 kernel: rcu: Max phase no-delay instances is 400. Jul 6 23:21:31.837058 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 6 23:21:31.837065 kernel: Remapping and enabling EFI services. Jul 6 23:21:31.837072 kernel: smp: Bringing up secondary CPUs ... Jul 6 23:21:31.837079 kernel: Detected PIPT I-cache on CPU1 Jul 6 23:21:31.837086 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 6 23:21:31.837094 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040150000 Jul 6 23:21:31.837106 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 6 23:21:31.837113 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 6 23:21:31.837122 kernel: Detected PIPT I-cache on CPU2 Jul 6 23:21:31.837129 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 6 23:21:31.837137 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040160000 Jul 6 23:21:31.837144 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 6 23:21:31.837151 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 6 23:21:31.837158 kernel: Detected PIPT I-cache on CPU3 Jul 6 23:21:31.837167 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 6 23:21:31.837174 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040170000 Jul 6 23:21:31.837182 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 6 23:21:31.837189 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 6 23:21:31.837196 kernel: smp: Brought up 1 node, 4 CPUs Jul 6 23:21:31.837203 kernel: SMP: Total of 4 processors activated. Jul 6 23:21:31.837210 kernel: CPU: All CPU(s) started at EL1 Jul 6 23:21:31.837217 kernel: CPU features: detected: 32-bit EL0 Support Jul 6 23:21:31.837224 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 6 23:21:31.837233 kernel: CPU features: detected: Common not Private translations Jul 6 23:21:31.837241 kernel: CPU features: detected: CRC32 instructions Jul 6 23:21:31.837248 kernel: CPU features: detected: Enhanced Virtualization Traps Jul 6 23:21:31.837255 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 6 23:21:31.837263 kernel: CPU features: detected: LSE atomic instructions Jul 6 23:21:31.837270 kernel: CPU features: detected: Privileged Access Never Jul 6 23:21:31.837278 kernel: CPU features: detected: RAS Extension Support Jul 6 23:21:31.837285 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 6 23:21:31.837292 kernel: alternatives: applying system-wide alternatives Jul 6 23:21:31.837301 kernel: CPU features: detected: Hardware dirty bit management on CPU0-3 Jul 6 23:21:31.837308 kernel: Memory: 2421860K/2572288K available (11136K kernel code, 2436K rwdata, 9076K rodata, 39488K init, 1038K bss, 128092K reserved, 16384K cma-reserved) Jul 6 23:21:31.837316 kernel: devtmpfs: initialized Jul 6 23:21:31.837323 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 6 23:21:31.837330 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 6 23:21:31.837338 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jul 6 23:21:31.837345 kernel: 0 pages in range for non-PLT usage Jul 6 23:21:31.837352 kernel: 508432 pages in range for PLT usage Jul 6 23:21:31.837359 kernel: pinctrl core: initialized pinctrl subsystem Jul 6 23:21:31.837368 kernel: SMBIOS 3.0.0 present. Jul 6 23:21:31.837375 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Jul 6 23:21:31.837383 kernel: DMI: Memory slots populated: 1/1 Jul 6 23:21:31.837390 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 6 23:21:31.837397 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 6 23:21:31.837405 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 6 23:21:31.837412 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 6 23:21:31.837419 kernel: audit: initializing netlink subsys (disabled) Jul 6 23:21:31.837427 kernel: audit: type=2000 audit(0.025:1): state=initialized audit_enabled=0 res=1 Jul 6 23:21:31.837522 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 6 23:21:31.837529 kernel: cpuidle: using governor menu Jul 6 23:21:31.837537 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 6 23:21:31.837544 kernel: ASID allocator initialised with 32768 entries Jul 6 23:21:31.837551 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 6 23:21:31.837559 kernel: Serial: AMBA PL011 UART driver Jul 6 23:21:31.837566 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 6 23:21:31.837574 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Jul 6 23:21:31.837581 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Jul 6 23:21:31.837590 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Jul 6 23:21:31.837598 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 6 23:21:31.837605 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Jul 6 23:21:31.837612 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Jul 6 23:21:31.837620 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Jul 6 23:21:31.837627 kernel: ACPI: Added _OSI(Module Device) Jul 6 23:21:31.837635 kernel: ACPI: Added _OSI(Processor Device) Jul 6 23:21:31.837642 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 6 23:21:31.837650 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 6 23:21:31.837659 kernel: ACPI: Interpreter enabled Jul 6 23:21:31.837667 kernel: ACPI: Using GIC for interrupt routing Jul 6 23:21:31.837674 kernel: ACPI: MCFG table detected, 1 entries Jul 6 23:21:31.837681 kernel: ACPI: CPU0 has been hot-added Jul 6 23:21:31.837689 kernel: ACPI: CPU1 has been hot-added Jul 6 23:21:31.837696 kernel: ACPI: CPU2 has been hot-added Jul 6 23:21:31.837703 kernel: ACPI: CPU3 has been hot-added Jul 6 23:21:31.837711 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 6 23:21:31.837718 kernel: printk: legacy console [ttyAMA0] enabled Jul 6 23:21:31.837727 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 6 23:21:31.837987 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 6 23:21:31.838080 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 6 23:21:31.838145 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 6 23:21:31.838205 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 6 23:21:31.838265 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 6 23:21:31.838275 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 6 23:21:31.838287 kernel: PCI host bridge to bus 0000:00 Jul 6 23:21:31.838358 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 6 23:21:31.838415 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 6 23:21:31.838538 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 6 23:21:31.838606 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 6 23:21:31.838715 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 conventional PCI endpoint Jul 6 23:21:31.838792 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Jul 6 23:21:31.838863 kernel: pci 0000:00:01.0: BAR 0 [io 0x0000-0x001f] Jul 6 23:21:31.838948 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff] Jul 6 23:21:31.839014 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref] Jul 6 23:21:31.839078 kernel: pci 0000:00:01.0: BAR 4 [mem 0x8000000000-0x8000003fff 64bit pref]: assigned Jul 6 23:21:31.839141 kernel: pci 0000:00:01.0: BAR 1 [mem 0x10000000-0x10000fff]: assigned Jul 6 23:21:31.839204 kernel: pci 0000:00:01.0: BAR 0 [io 0x1000-0x101f]: assigned Jul 6 23:21:31.839262 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 6 23:21:31.839319 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 6 23:21:31.839374 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 6 23:21:31.839384 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 6 23:21:31.839391 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 6 23:21:31.839398 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 6 23:21:31.839406 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 6 23:21:31.839413 kernel: iommu: Default domain type: Translated Jul 6 23:21:31.839420 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 6 23:21:31.839429 kernel: efivars: Registered efivars operations Jul 6 23:21:31.839436 kernel: vgaarb: loaded Jul 6 23:21:31.839444 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 6 23:21:31.839451 kernel: VFS: Disk quotas dquot_6.6.0 Jul 6 23:21:31.839494 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 6 23:21:31.839503 kernel: pnp: PnP ACPI init Jul 6 23:21:31.839598 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 6 23:21:31.839610 kernel: pnp: PnP ACPI: found 1 devices Jul 6 23:21:31.839620 kernel: NET: Registered PF_INET protocol family Jul 6 23:21:31.839631 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 6 23:21:31.839641 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 6 23:21:31.839650 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 6 23:21:31.839658 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 6 23:21:31.839665 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jul 6 23:21:31.839673 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 6 23:21:31.839680 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 6 23:21:31.839688 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 6 23:21:31.839697 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 6 23:21:31.839704 kernel: PCI: CLS 0 bytes, default 64 Jul 6 23:21:31.839712 kernel: kvm [1]: HYP mode not available Jul 6 23:21:31.839719 kernel: Initialise system trusted keyrings Jul 6 23:21:31.839727 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 6 23:21:31.839734 kernel: Key type asymmetric registered Jul 6 23:21:31.839741 kernel: Asymmetric key parser 'x509' registered Jul 6 23:21:31.839749 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 6 23:21:31.839756 kernel: io scheduler mq-deadline registered Jul 6 23:21:31.839765 kernel: io scheduler kyber registered Jul 6 23:21:31.839773 kernel: io scheduler bfq registered Jul 6 23:21:31.839781 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 6 23:21:31.839788 kernel: ACPI: button: Power Button [PWRB] Jul 6 23:21:31.839796 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 6 23:21:31.839864 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 6 23:21:31.839875 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 6 23:21:31.839895 kernel: thunder_xcv, ver 1.0 Jul 6 23:21:31.839911 kernel: thunder_bgx, ver 1.0 Jul 6 23:21:31.839922 kernel: nicpf, ver 1.0 Jul 6 23:21:31.839929 kernel: nicvf, ver 1.0 Jul 6 23:21:31.840016 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 6 23:21:31.840078 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-07-06T23:21:31 UTC (1751844091) Jul 6 23:21:31.840088 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 6 23:21:31.840096 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Jul 6 23:21:31.840103 kernel: watchdog: NMI not fully supported Jul 6 23:21:31.840110 kernel: watchdog: Hard watchdog permanently disabled Jul 6 23:21:31.840119 kernel: NET: Registered PF_INET6 protocol family Jul 6 23:21:31.840126 kernel: Segment Routing with IPv6 Jul 6 23:21:31.840134 kernel: In-situ OAM (IOAM) with IPv6 Jul 6 23:21:31.840141 kernel: NET: Registered PF_PACKET protocol family Jul 6 23:21:31.840148 kernel: Key type dns_resolver registered Jul 6 23:21:31.840156 kernel: registered taskstats version 1 Jul 6 23:21:31.840163 kernel: Loading compiled-in X.509 certificates Jul 6 23:21:31.840171 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.35-flatcar: f8c1d02496b1c3f2ac4a0c4b5b2a55d3dc0ca718' Jul 6 23:21:31.840178 kernel: Demotion targets for Node 0: null Jul 6 23:21:31.840187 kernel: Key type .fscrypt registered Jul 6 23:21:31.840194 kernel: Key type fscrypt-provisioning registered Jul 6 23:21:31.840201 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 6 23:21:31.840208 kernel: ima: Allocated hash algorithm: sha1 Jul 6 23:21:31.840215 kernel: ima: No architecture policies found Jul 6 23:21:31.840223 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 6 23:21:31.840230 kernel: clk: Disabling unused clocks Jul 6 23:21:31.840237 kernel: PM: genpd: Disabling unused power domains Jul 6 23:21:31.840244 kernel: Warning: unable to open an initial console. Jul 6 23:21:31.840254 kernel: Freeing unused kernel memory: 39488K Jul 6 23:21:31.840261 kernel: Run /init as init process Jul 6 23:21:31.840269 kernel: with arguments: Jul 6 23:21:31.840276 kernel: /init Jul 6 23:21:31.840283 kernel: with environment: Jul 6 23:21:31.840290 kernel: HOME=/ Jul 6 23:21:31.840297 kernel: TERM=linux Jul 6 23:21:31.840304 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 6 23:21:31.840313 systemd[1]: Successfully made /usr/ read-only. Jul 6 23:21:31.840324 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) Jul 6 23:21:31.840333 systemd[1]: Detected virtualization kvm. Jul 6 23:21:31.840341 systemd[1]: Detected architecture arm64. Jul 6 23:21:31.840348 systemd[1]: Running in initrd. Jul 6 23:21:31.840356 systemd[1]: No hostname configured, using default hostname. Jul 6 23:21:31.840364 systemd[1]: Hostname set to . Jul 6 23:21:31.840372 systemd[1]: Initializing machine ID from VM UUID. Jul 6 23:21:31.840381 systemd[1]: Queued start job for default target initrd.target. Jul 6 23:21:31.840388 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:21:31.840396 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:21:31.840405 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 6 23:21:31.840413 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 6 23:21:31.840420 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 6 23:21:31.840429 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 6 23:21:31.840439 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 6 23:21:31.840448 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 6 23:21:31.840456 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:21:31.840463 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:21:31.840505 systemd[1]: Reached target paths.target - Path Units. Jul 6 23:21:31.840517 systemd[1]: Reached target slices.target - Slice Units. Jul 6 23:21:31.840525 systemd[1]: Reached target swap.target - Swaps. Jul 6 23:21:31.840533 systemd[1]: Reached target timers.target - Timer Units. Jul 6 23:21:31.840545 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 6 23:21:31.840553 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 6 23:21:31.840561 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 6 23:21:31.840568 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 6 23:21:31.840576 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:21:31.840585 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 6 23:21:31.840592 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:21:31.840600 systemd[1]: Reached target sockets.target - Socket Units. Jul 6 23:21:31.840608 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 6 23:21:31.840618 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 6 23:21:31.840626 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 6 23:21:31.840634 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). Jul 6 23:21:31.840642 systemd[1]: Starting systemd-fsck-usr.service... Jul 6 23:21:31.840650 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 6 23:21:31.840657 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 6 23:21:31.840665 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:21:31.840673 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 6 23:21:31.840683 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:21:31.840691 systemd[1]: Finished systemd-fsck-usr.service. Jul 6 23:21:31.840699 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 6 23:21:31.840708 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 6 23:21:31.840739 systemd-journald[244]: Collecting audit messages is disabled. Jul 6 23:21:31.840760 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 6 23:21:31.840768 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 6 23:21:31.840776 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:21:31.840786 kernel: Bridge firewalling registered Jul 6 23:21:31.840796 systemd-journald[244]: Journal started Jul 6 23:21:31.840817 systemd-journald[244]: Runtime Journal (/run/log/journal/b54c041338ab4aba9df4c0860980ee33) is 6M, max 48.5M, 42.4M free. Jul 6 23:21:31.821098 systemd-modules-load[246]: Inserted module 'overlay' Jul 6 23:21:31.839809 systemd-modules-load[246]: Inserted module 'br_netfilter' Jul 6 23:21:31.854105 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 6 23:21:31.854130 systemd[1]: Started systemd-journald.service - Journal Service. Jul 6 23:21:31.858998 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 6 23:21:31.860354 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:21:31.865798 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 6 23:21:31.869034 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 6 23:21:31.889455 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:21:31.891938 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 6 23:21:31.897304 systemd-tmpfiles[272]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 6 23:21:31.900426 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:21:31.901532 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:21:31.905031 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 6 23:21:31.909004 dracut-cmdline[283]: 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=d1bbaf8ae8f23de11dc703e14022523825f85f007c0c35003d7559228cbdda22 Jul 6 23:21:31.949093 systemd-resolved[294]: Positive Trust Anchors: Jul 6 23:21:31.949112 systemd-resolved[294]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 6 23:21:31.949143 systemd-resolved[294]: 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 Jul 6 23:21:31.953968 systemd-resolved[294]: Defaulting to hostname 'linux'. Jul 6 23:21:31.954909 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 6 23:21:31.959516 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:21:31.993930 kernel: SCSI subsystem initialized Jul 6 23:21:31.998910 kernel: Loading iSCSI transport class v2.0-870. Jul 6 23:21:32.009923 kernel: iscsi: registered transport (tcp) Jul 6 23:21:32.022919 kernel: iscsi: registered transport (qla4xxx) Jul 6 23:21:32.022975 kernel: QLogic iSCSI HBA Driver Jul 6 23:21:32.041578 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 6 23:21:32.065526 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 6 23:21:32.066816 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 6 23:21:32.125630 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 6 23:21:32.127979 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 6 23:21:32.192937 kernel: raid6: neonx8 gen() 11881 MB/s Jul 6 23:21:32.209918 kernel: raid6: neonx4 gen() 15742 MB/s Jul 6 23:21:32.226935 kernel: raid6: neonx2 gen() 13022 MB/s Jul 6 23:21:32.243913 kernel: raid6: neonx1 gen() 10530 MB/s Jul 6 23:21:32.260923 kernel: raid6: int64x8 gen() 6854 MB/s Jul 6 23:21:32.277921 kernel: raid6: int64x4 gen() 7331 MB/s Jul 6 23:21:32.294918 kernel: raid6: int64x2 gen() 6096 MB/s Jul 6 23:21:32.311916 kernel: raid6: int64x1 gen() 5049 MB/s Jul 6 23:21:32.311943 kernel: raid6: using algorithm neonx4 gen() 15742 MB/s Jul 6 23:21:32.328923 kernel: raid6: .... xor() 12164 MB/s, rmw enabled Jul 6 23:21:32.328959 kernel: raid6: using neon recovery algorithm Jul 6 23:21:32.334911 kernel: xor: measuring software checksum speed Jul 6 23:21:32.334945 kernel: 8regs : 21641 MB/sec Jul 6 23:21:32.335954 kernel: 32regs : 21216 MB/sec Jul 6 23:21:32.335971 kernel: arm64_neon : 28061 MB/sec Jul 6 23:21:32.335981 kernel: xor: using function: arm64_neon (28061 MB/sec) Jul 6 23:21:32.407962 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 6 23:21:32.416491 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 6 23:21:32.422774 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:21:32.451109 systemd-udevd[496]: Using default interface naming scheme 'v255'. Jul 6 23:21:32.455223 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:21:32.462169 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 6 23:21:32.481580 dracut-pre-trigger[506]: rd.md=0: removing MD RAID activation Jul 6 23:21:32.509945 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 6 23:21:32.512416 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 6 23:21:32.574851 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:21:32.577157 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 6 23:21:32.629232 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Jul 6 23:21:32.629437 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Jul 6 23:21:32.635257 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Jul 6 23:21:32.635306 kernel: GPT:9289727 != 19775487 Jul 6 23:21:32.635316 kernel: GPT:Alternate GPT header not at the end of the disk. Jul 6 23:21:32.636957 kernel: GPT:9289727 != 19775487 Jul 6 23:21:32.636993 kernel: GPT: Use GNU Parted to correct GPT errors. Jul 6 23:21:32.637010 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 6 23:21:32.637201 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 6 23:21:32.637332 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:21:32.640303 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:21:32.642614 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:21:32.668318 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jul 6 23:21:32.670597 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:21:32.677303 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 6 23:21:32.695823 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jul 6 23:21:32.696943 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jul 6 23:21:32.707308 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 6 23:21:32.715588 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jul 6 23:21:32.716689 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 6 23:21:32.718766 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:21:32.720719 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 6 23:21:32.723426 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 6 23:21:32.725211 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 6 23:21:32.740166 disk-uuid[590]: Primary Header is updated. Jul 6 23:21:32.740166 disk-uuid[590]: Secondary Entries is updated. Jul 6 23:21:32.740166 disk-uuid[590]: Secondary Header is updated. Jul 6 23:21:32.745041 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 6 23:21:32.747908 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 6 23:21:33.770931 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 6 23:21:33.776904 disk-uuid[596]: The operation has completed successfully. Jul 6 23:21:33.812592 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 6 23:21:33.812704 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 6 23:21:33.847062 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 6 23:21:33.873943 sh[611]: Success Jul 6 23:21:33.889427 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 6 23:21:33.889476 kernel: device-mapper: uevent: version 1.0.3 Jul 6 23:21:33.889499 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 6 23:21:33.901183 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Jul 6 23:21:33.935446 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 6 23:21:33.939664 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 6 23:21:33.968375 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 6 23:21:33.974179 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 6 23:21:33.974216 kernel: BTRFS: device fsid 2cfafe0a-eb24-4e1d-b9c9-dec7de7e4c4d devid 1 transid 38 /dev/mapper/usr (253:0) scanned by mount (624) Jul 6 23:21:33.975327 kernel: BTRFS info (device dm-0): first mount of filesystem 2cfafe0a-eb24-4e1d-b9c9-dec7de7e4c4d Jul 6 23:21:33.975345 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Jul 6 23:21:33.976933 kernel: BTRFS info (device dm-0): using free-space-tree Jul 6 23:21:33.980299 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 6 23:21:33.981345 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 6 23:21:33.982266 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 6 23:21:33.983026 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 6 23:21:33.993491 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 6 23:21:34.007910 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (652) Jul 6 23:21:34.009975 kernel: BTRFS info (device vda6): first mount of filesystem f2591801-6ba1-4aa7-8261-bdb292e2060d Jul 6 23:21:34.010007 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 6 23:21:34.010924 kernel: BTRFS info (device vda6): using free-space-tree Jul 6 23:21:34.018101 kernel: BTRFS info (device vda6): last unmount of filesystem f2591801-6ba1-4aa7-8261-bdb292e2060d Jul 6 23:21:34.018550 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 6 23:21:34.020600 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 6 23:21:34.106657 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 6 23:21:34.112143 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 6 23:21:34.173178 systemd-networkd[799]: lo: Link UP Jul 6 23:21:34.173189 systemd-networkd[799]: lo: Gained carrier Jul 6 23:21:34.173854 systemd-networkd[799]: Enumeration completed Jul 6 23:21:34.173977 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 6 23:21:34.174336 systemd-networkd[799]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:21:34.174340 systemd-networkd[799]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:21:34.174833 systemd[1]: Reached target network.target - Network. Jul 6 23:21:34.177516 systemd-networkd[799]: eth0: Link UP Jul 6 23:21:34.177520 systemd-networkd[799]: eth0: Gained carrier Jul 6 23:21:34.177530 systemd-networkd[799]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:21:34.203953 systemd-networkd[799]: eth0: DHCPv4 address 10.0.0.25/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 6 23:21:34.231967 ignition[698]: Ignition 2.21.0 Jul 6 23:21:34.231981 ignition[698]: Stage: fetch-offline Jul 6 23:21:34.232019 ignition[698]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:21:34.232028 ignition[698]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 6 23:21:34.232254 ignition[698]: parsed url from cmdline: "" Jul 6 23:21:34.232257 ignition[698]: no config URL provided Jul 6 23:21:34.232262 ignition[698]: reading system config file "/usr/lib/ignition/user.ign" Jul 6 23:21:34.232268 ignition[698]: no config at "/usr/lib/ignition/user.ign" Jul 6 23:21:34.232290 ignition[698]: op(1): [started] loading QEMU firmware config module Jul 6 23:21:34.232294 ignition[698]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 6 23:21:34.254068 ignition[698]: op(1): [finished] loading QEMU firmware config module Jul 6 23:21:34.260387 ignition[698]: parsing config with SHA512: 825dd9e0bda5891dc791b1becbfd5be806345ad34237500d4454840bcddc06bd7a5ea37028f3c1bb0f40adba3852165a75524d29ab2f37991137125417259022 Jul 6 23:21:34.265066 unknown[698]: fetched base config from "system" Jul 6 23:21:34.265077 unknown[698]: fetched user config from "qemu" Jul 6 23:21:34.265957 ignition[698]: fetch-offline: fetch-offline passed Jul 6 23:21:34.267335 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 6 23:21:34.266025 ignition[698]: Ignition finished successfully Jul 6 23:21:34.269448 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 6 23:21:34.272065 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 6 23:21:34.309057 ignition[813]: Ignition 2.21.0 Jul 6 23:21:34.309072 ignition[813]: Stage: kargs Jul 6 23:21:34.309200 ignition[813]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:21:34.309208 ignition[813]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 6 23:21:34.310011 ignition[813]: kargs: kargs passed Jul 6 23:21:34.314566 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 6 23:21:34.310097 ignition[813]: Ignition finished successfully Jul 6 23:21:34.316724 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 6 23:21:34.342969 ignition[821]: Ignition 2.21.0 Jul 6 23:21:34.342983 ignition[821]: Stage: disks Jul 6 23:21:34.343131 ignition[821]: no configs at "/usr/lib/ignition/base.d" Jul 6 23:21:34.343140 ignition[821]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 6 23:21:34.344829 ignition[821]: disks: disks passed Jul 6 23:21:34.345249 ignition[821]: Ignition finished successfully Jul 6 23:21:34.348192 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 6 23:21:34.349564 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 6 23:21:34.350781 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 6 23:21:34.352831 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 6 23:21:34.355809 systemd[1]: Reached target sysinit.target - System Initialization. Jul 6 23:21:34.358649 systemd[1]: Reached target basic.target - Basic System. Jul 6 23:21:34.361959 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 6 23:21:34.393510 systemd-fsck[831]: ROOT: clean, 15/553520 files, 52789/553472 blocks Jul 6 23:21:34.399159 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 6 23:21:34.401019 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 6 23:21:34.486274 kernel: EXT4-fs (vda9): mounted filesystem 8d88df29-f94d-4ab8-8fb6-af875603e6d4 r/w with ordered data mode. Quota mode: none. Jul 6 23:21:34.486326 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 6 23:21:34.487352 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 6 23:21:34.490994 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 6 23:21:34.493166 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 6 23:21:34.494029 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jul 6 23:21:34.494072 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 6 23:21:34.494099 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 6 23:21:34.511536 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 6 23:21:34.513950 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 6 23:21:34.520698 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (840) Jul 6 23:21:34.520737 kernel: BTRFS info (device vda6): first mount of filesystem f2591801-6ba1-4aa7-8261-bdb292e2060d Jul 6 23:21:34.521648 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 6 23:21:34.522292 kernel: BTRFS info (device vda6): using free-space-tree Jul 6 23:21:34.526830 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 6 23:21:34.574503 initrd-setup-root[864]: cut: /sysroot/etc/passwd: No such file or directory Jul 6 23:21:34.579013 initrd-setup-root[871]: cut: /sysroot/etc/group: No such file or directory Jul 6 23:21:34.584028 initrd-setup-root[878]: cut: /sysroot/etc/shadow: No such file or directory Jul 6 23:21:34.587831 initrd-setup-root[885]: cut: /sysroot/etc/gshadow: No such file or directory Jul 6 23:21:34.688459 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 6 23:21:34.690557 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 6 23:21:34.715089 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 6 23:21:34.721920 kernel: BTRFS info (device vda6): last unmount of filesystem f2591801-6ba1-4aa7-8261-bdb292e2060d Jul 6 23:21:34.738023 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 6 23:21:34.749356 ignition[954]: INFO : Ignition 2.21.0 Jul 6 23:21:34.749356 ignition[954]: INFO : Stage: mount Jul 6 23:21:34.750727 ignition[954]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:21:34.750727 ignition[954]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 6 23:21:34.750727 ignition[954]: INFO : mount: mount passed Jul 6 23:21:34.750727 ignition[954]: INFO : Ignition finished successfully Jul 6 23:21:34.751444 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 6 23:21:34.753878 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 6 23:21:34.973602 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 6 23:21:34.975410 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 6 23:21:34.993913 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 (254:6) scanned by mount (967) Jul 6 23:21:34.996150 kernel: BTRFS info (device vda6): first mount of filesystem f2591801-6ba1-4aa7-8261-bdb292e2060d Jul 6 23:21:34.996183 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 6 23:21:34.996193 kernel: BTRFS info (device vda6): using free-space-tree Jul 6 23:21:34.999129 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 6 23:21:35.028673 ignition[984]: INFO : Ignition 2.21.0 Jul 6 23:21:35.028673 ignition[984]: INFO : Stage: files Jul 6 23:21:35.030431 ignition[984]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:21:35.030431 ignition[984]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 6 23:21:35.032810 ignition[984]: DEBUG : files: compiled without relabeling support, skipping Jul 6 23:21:35.032810 ignition[984]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 6 23:21:35.032810 ignition[984]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 6 23:21:35.037119 ignition[984]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 6 23:21:35.037119 ignition[984]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 6 23:21:35.037119 ignition[984]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 6 23:21:35.035035 unknown[984]: wrote ssh authorized keys file for user: core Jul 6 23:21:35.042777 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Jul 6 23:21:35.042777 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Jul 6 23:21:35.046694 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 6 23:21:35.046694 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 6 23:21:35.046694 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 6 23:21:35.046694 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 6 23:21:35.046694 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 6 23:21:35.046694 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.32.4-arm64.raw: attempt #1 Jul 6 23:21:35.286057 systemd-networkd[799]: eth0: Gained IPv6LL Jul 6 23:21:35.519759 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Jul 6 23:21:36.006407 ignition[984]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.4-arm64.raw" Jul 6 23:21:36.006407 ignition[984]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Jul 6 23:21:36.010687 ignition[984]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 6 23:21:36.010687 ignition[984]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 6 23:21:36.010687 ignition[984]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Jul 6 23:21:36.010687 ignition[984]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Jul 6 23:21:36.056773 ignition[984]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 6 23:21:36.061148 ignition[984]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 6 23:21:36.063947 ignition[984]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Jul 6 23:21:36.063947 ignition[984]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 6 23:21:36.063947 ignition[984]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 6 23:21:36.063947 ignition[984]: INFO : files: files passed Jul 6 23:21:36.063947 ignition[984]: INFO : Ignition finished successfully Jul 6 23:21:36.064690 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 6 23:21:36.067504 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 6 23:21:36.069312 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 6 23:21:36.083204 initrd-setup-root-after-ignition[1011]: grep: /sysroot/oem/oem-release: No such file or directory Jul 6 23:21:36.085728 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 6 23:21:36.085875 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 6 23:21:36.087540 initrd-setup-root-after-ignition[1015]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:21:36.087540 initrd-setup-root-after-ignition[1015]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:21:36.091972 initrd-setup-root-after-ignition[1019]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 6 23:21:36.092561 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 6 23:21:36.095319 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 6 23:21:36.097429 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 6 23:21:36.164134 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 6 23:21:36.164274 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 6 23:21:36.168401 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 6 23:21:36.170569 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 6 23:21:36.172575 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 6 23:21:36.173733 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 6 23:21:36.199244 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 6 23:21:36.201988 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 6 23:21:36.226179 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:21:36.227521 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:21:36.230087 systemd[1]: Stopped target timers.target - Timer Units. Jul 6 23:21:36.232031 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 6 23:21:36.232297 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 6 23:21:36.235022 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 6 23:21:36.237160 systemd[1]: Stopped target basic.target - Basic System. Jul 6 23:21:36.238952 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 6 23:21:36.240718 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 6 23:21:36.242827 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 6 23:21:36.245119 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 6 23:21:36.247119 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 6 23:21:36.249151 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 6 23:21:36.251315 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 6 23:21:36.253430 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 6 23:21:36.255301 systemd[1]: Stopped target swap.target - Swaps. Jul 6 23:21:36.257180 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 6 23:21:36.257325 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 6 23:21:36.260290 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:21:36.262298 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:21:36.264175 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 6 23:21:36.264942 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:21:36.266327 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 6 23:21:36.266465 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 6 23:21:36.269318 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 6 23:21:36.269463 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 6 23:21:36.271257 systemd[1]: Stopped target paths.target - Path Units. Jul 6 23:21:36.273112 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 6 23:21:36.274343 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:21:36.275850 systemd[1]: Stopped target slices.target - Slice Units. Jul 6 23:21:36.277559 systemd[1]: Stopped target sockets.target - Socket Units. Jul 6 23:21:36.279082 systemd[1]: iscsid.socket: Deactivated successfully. Jul 6 23:21:36.279177 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 6 23:21:36.280769 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 6 23:21:36.280852 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 6 23:21:36.282810 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 6 23:21:36.282974 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 6 23:21:36.284647 systemd[1]: ignition-files.service: Deactivated successfully. Jul 6 23:21:36.284758 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 6 23:21:36.287063 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 6 23:21:36.288384 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 6 23:21:36.288520 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:21:36.291148 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 6 23:21:36.292693 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 6 23:21:36.292835 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:21:36.294979 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 6 23:21:36.295092 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 6 23:21:36.302322 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 6 23:21:36.302408 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 6 23:21:36.309139 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 6 23:21:36.315096 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 6 23:21:36.315223 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 6 23:21:36.322975 ignition[1039]: INFO : Ignition 2.21.0 Jul 6 23:21:36.322975 ignition[1039]: INFO : Stage: umount Jul 6 23:21:36.322975 ignition[1039]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 6 23:21:36.322975 ignition[1039]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 6 23:21:36.322975 ignition[1039]: INFO : umount: umount passed Jul 6 23:21:36.322975 ignition[1039]: INFO : Ignition finished successfully Jul 6 23:21:36.322680 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 6 23:21:36.322777 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 6 23:21:36.327410 systemd[1]: Stopped target network.target - Network. Jul 6 23:21:36.328945 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 6 23:21:36.329032 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 6 23:21:36.333062 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 6 23:21:36.333133 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 6 23:21:36.334580 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 6 23:21:36.334628 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 6 23:21:36.336148 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 6 23:21:36.336185 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 6 23:21:36.338026 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 6 23:21:36.338084 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 6 23:21:36.339939 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 6 23:21:36.341514 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 6 23:21:36.346138 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 6 23:21:36.346262 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 6 23:21:36.350287 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 6 23:21:36.350635 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 6 23:21:36.350677 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:21:36.354207 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 6 23:21:36.357790 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 6 23:21:36.357922 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 6 23:21:36.361018 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 6 23:21:36.361217 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 6 23:21:36.362074 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 6 23:21:36.362118 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:21:36.364874 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 6 23:21:36.365811 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 6 23:21:36.365872 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 6 23:21:36.367649 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 6 23:21:36.367689 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:21:36.370665 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 6 23:21:36.370712 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 6 23:21:36.372970 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:21:36.377482 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 6 23:21:36.389576 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 6 23:21:36.389715 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 6 23:21:36.395525 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 6 23:21:36.395684 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:21:36.397779 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 6 23:21:36.397847 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 6 23:21:36.399956 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 6 23:21:36.399997 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:21:36.401927 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 6 23:21:36.401981 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 6 23:21:36.404794 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 6 23:21:36.404847 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 6 23:21:36.407958 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 6 23:21:36.408021 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 6 23:21:36.411804 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 6 23:21:36.412850 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 6 23:21:36.412920 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 6 23:21:36.416048 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 6 23:21:36.416097 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:21:36.419682 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 6 23:21:36.419730 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:21:36.428434 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 6 23:21:36.428543 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 6 23:21:36.430827 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 6 23:21:36.433708 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 6 23:21:36.457730 systemd[1]: Switching root. Jul 6 23:21:36.492424 systemd-journald[244]: Journal stopped Jul 6 23:21:37.573528 systemd-journald[244]: Received SIGTERM from PID 1 (systemd). Jul 6 23:21:37.573577 kernel: SELinux: policy capability network_peer_controls=1 Jul 6 23:21:37.573589 kernel: SELinux: policy capability open_perms=1 Jul 6 23:21:37.573599 kernel: SELinux: policy capability extended_socket_class=1 Jul 6 23:21:37.573608 kernel: SELinux: policy capability always_check_network=0 Jul 6 23:21:37.573617 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 6 23:21:37.573627 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 6 23:21:37.573640 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 6 23:21:37.573655 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 6 23:21:37.573668 kernel: SELinux: policy capability userspace_initial_context=0 Jul 6 23:21:37.573678 kernel: audit: type=1403 audit(1751844096.792:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 6 23:21:37.573693 systemd[1]: Successfully loaded SELinux policy in 59.169ms. Jul 6 23:21:37.573711 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 11.736ms. Jul 6 23:21:37.573723 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) Jul 6 23:21:37.573737 systemd[1]: Detected virtualization kvm. Jul 6 23:21:37.573749 systemd[1]: Detected architecture arm64. Jul 6 23:21:37.573760 systemd[1]: Detected first boot. Jul 6 23:21:37.573770 systemd[1]: Initializing machine ID from VM UUID. Jul 6 23:21:37.573780 zram_generator::config[1084]: No configuration found. Jul 6 23:21:37.573792 kernel: NET: Registered PF_VSOCK protocol family Jul 6 23:21:37.573802 systemd[1]: Populated /etc with preset unit settings. Jul 6 23:21:37.573812 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 6 23:21:37.573823 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 6 23:21:37.573834 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 6 23:21:37.573844 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 6 23:21:37.573854 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 6 23:21:37.573864 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 6 23:21:37.573874 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 6 23:21:37.573908 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 6 23:21:37.573926 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 6 23:21:37.573938 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 6 23:21:37.573950 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 6 23:21:37.573960 systemd[1]: Created slice user.slice - User and Session Slice. Jul 6 23:21:37.573970 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 6 23:21:37.573981 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 6 23:21:37.573991 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 6 23:21:37.574002 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 6 23:21:37.574013 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 6 23:21:37.574023 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 6 23:21:37.574033 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Jul 6 23:21:37.574045 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 6 23:21:37.574060 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 6 23:21:37.574070 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 6 23:21:37.574081 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 6 23:21:37.574091 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 6 23:21:37.574102 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 6 23:21:37.574112 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 6 23:21:37.574122 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 6 23:21:37.574134 systemd[1]: Reached target slices.target - Slice Units. Jul 6 23:21:37.574144 systemd[1]: Reached target swap.target - Swaps. Jul 6 23:21:37.574154 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 6 23:21:37.574165 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 6 23:21:37.574175 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 6 23:21:37.574185 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 6 23:21:37.574196 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 6 23:21:37.574206 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 6 23:21:37.574216 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 6 23:21:37.574228 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 6 23:21:37.574238 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 6 23:21:37.574248 systemd[1]: Mounting media.mount - External Media Directory... Jul 6 23:21:37.574259 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 6 23:21:37.574269 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 6 23:21:37.574280 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 6 23:21:37.574290 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). Jul 6 23:21:37.574301 systemd[1]: Reached target machines.target - Containers. Jul 6 23:21:37.574310 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 6 23:21:37.574323 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:21:37.574333 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 6 23:21:37.574344 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 6 23:21:37.574355 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:21:37.574366 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 6 23:21:37.574376 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:21:37.574386 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 6 23:21:37.574397 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:21:37.574409 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 6 23:21:37.574420 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 6 23:21:37.574430 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 6 23:21:37.574441 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 6 23:21:37.574451 systemd[1]: Stopped systemd-fsck-usr.service. Jul 6 23:21:37.574462 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). Jul 6 23:21:37.574472 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 6 23:21:37.574483 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 6 23:21:37.574494 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 6 23:21:37.574507 kernel: fuse: init (API version 7.41) Jul 6 23:21:37.574516 kernel: loop: module loaded Jul 6 23:21:37.574527 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 6 23:21:37.574537 kernel: ACPI: bus type drm_connector registered Jul 6 23:21:37.574548 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 6 23:21:37.574560 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 6 23:21:37.574571 systemd[1]: verity-setup.service: Deactivated successfully. Jul 6 23:21:37.574581 systemd[1]: Stopped verity-setup.service. Jul 6 23:21:37.574592 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 6 23:21:37.574606 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 6 23:21:37.574616 systemd[1]: Mounted media.mount - External Media Directory. Jul 6 23:21:37.574627 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 6 23:21:37.574638 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 6 23:21:37.574649 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 6 23:21:37.574660 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 6 23:21:37.574671 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 6 23:21:37.574683 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 6 23:21:37.574694 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 6 23:21:37.574704 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:21:37.574715 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:21:37.574726 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 6 23:21:37.574736 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 6 23:21:37.574747 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:21:37.574757 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:21:37.574789 systemd-journald[1149]: Collecting audit messages is disabled. Jul 6 23:21:37.574812 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 6 23:21:37.574822 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 6 23:21:37.574835 systemd-journald[1149]: Journal started Jul 6 23:21:37.574856 systemd-journald[1149]: Runtime Journal (/run/log/journal/b54c041338ab4aba9df4c0860980ee33) is 6M, max 48.5M, 42.4M free. Jul 6 23:21:37.296399 systemd[1]: Queued start job for default target multi-user.target. Jul 6 23:21:37.322136 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jul 6 23:21:37.322550 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 6 23:21:37.577910 systemd[1]: Started systemd-journald.service - Journal Service. Jul 6 23:21:37.578480 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:21:37.578658 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:21:37.579992 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 6 23:21:37.581395 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 6 23:21:37.582906 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 6 23:21:37.584195 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 6 23:21:37.597672 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 6 23:21:37.600179 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 6 23:21:37.602346 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 6 23:21:37.603434 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 6 23:21:37.603472 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 6 23:21:37.605591 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 6 23:21:37.610113 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 6 23:21:37.611379 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:21:37.613039 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 6 23:21:37.616156 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 6 23:21:37.617407 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 6 23:21:37.618626 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 6 23:21:37.620155 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 6 23:21:37.623710 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 6 23:21:37.631187 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 6 23:21:37.633873 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 6 23:21:37.639680 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 6 23:21:37.641122 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 6 23:21:37.645591 systemd-journald[1149]: Time spent on flushing to /var/log/journal/b54c041338ab4aba9df4c0860980ee33 is 14.689ms for 865 entries. Jul 6 23:21:37.645591 systemd-journald[1149]: System Journal (/var/log/journal/b54c041338ab4aba9df4c0860980ee33) is 8M, max 195.6M, 187.6M free. Jul 6 23:21:37.694355 systemd-journald[1149]: Received client request to flush runtime journal. Jul 6 23:21:37.694417 kernel: loop0: detected capacity change from 0 to 207008 Jul 6 23:21:37.694438 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 6 23:21:37.644031 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 6 23:21:37.656595 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 6 23:21:37.658624 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 6 23:21:37.660873 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 6 23:21:37.663816 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 6 23:21:37.695770 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 6 23:21:37.707131 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 6 23:21:37.711056 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 6 23:21:37.712009 kernel: loop1: detected capacity change from 0 to 107312 Jul 6 23:21:37.716972 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 6 23:21:37.741016 kernel: loop2: detected capacity change from 0 to 138376 Jul 6 23:21:37.744169 systemd-tmpfiles[1216]: ACLs are not supported, ignoring. Jul 6 23:21:37.744188 systemd-tmpfiles[1216]: ACLs are not supported, ignoring. Jul 6 23:21:37.750682 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 6 23:21:37.765954 kernel: loop3: detected capacity change from 0 to 207008 Jul 6 23:21:37.772940 kernel: loop4: detected capacity change from 0 to 107312 Jul 6 23:21:37.778933 kernel: loop5: detected capacity change from 0 to 138376 Jul 6 23:21:37.784825 (sd-merge)[1223]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Jul 6 23:21:37.785268 (sd-merge)[1223]: Merged extensions into '/usr'. Jul 6 23:21:37.788999 systemd[1]: Reload requested from client PID 1200 ('systemd-sysext') (unit systemd-sysext.service)... Jul 6 23:21:37.789014 systemd[1]: Reloading... Jul 6 23:21:37.842917 zram_generator::config[1249]: No configuration found. Jul 6 23:21:37.949502 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. Jul 6 23:21:37.979981 ldconfig[1195]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 6 23:21:38.014875 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 6 23:21:38.015357 systemd[1]: Reloading finished in 225 ms. Jul 6 23:21:38.042785 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 6 23:21:38.044515 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 6 23:21:38.058320 systemd[1]: Starting ensure-sysext.service... Jul 6 23:21:38.060205 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 6 23:21:38.071697 systemd[1]: Reload requested from client PID 1284 ('systemctl') (unit ensure-sysext.service)... Jul 6 23:21:38.071715 systemd[1]: Reloading... Jul 6 23:21:38.083852 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 6 23:21:38.083909 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 6 23:21:38.084165 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 6 23:21:38.084355 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 6 23:21:38.085025 systemd-tmpfiles[1285]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 6 23:21:38.085303 systemd-tmpfiles[1285]: ACLs are not supported, ignoring. Jul 6 23:21:38.085347 systemd-tmpfiles[1285]: ACLs are not supported, ignoring. Jul 6 23:21:38.088493 systemd-tmpfiles[1285]: Detected autofs mount point /boot during canonicalization of boot. Jul 6 23:21:38.088507 systemd-tmpfiles[1285]: Skipping /boot Jul 6 23:21:38.099518 systemd-tmpfiles[1285]: Detected autofs mount point /boot during canonicalization of boot. Jul 6 23:21:38.099538 systemd-tmpfiles[1285]: Skipping /boot Jul 6 23:21:38.127008 zram_generator::config[1312]: No configuration found. Jul 6 23:21:38.199664 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. Jul 6 23:21:38.264654 systemd[1]: Reloading finished in 192 ms. Jul 6 23:21:38.287842 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 6 23:21:38.294031 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 6 23:21:38.306390 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 6 23:21:38.309051 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 6 23:21:38.311718 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 6 23:21:38.315241 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 6 23:21:38.318122 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 6 23:21:38.322233 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 6 23:21:38.333061 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:21:38.341478 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:21:38.344625 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:21:38.347739 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:21:38.349626 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:21:38.349762 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). Jul 6 23:21:38.359711 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 6 23:21:38.363772 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 6 23:21:38.367512 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:21:38.369004 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:21:38.371120 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:21:38.371269 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:21:38.372559 systemd-udevd[1353]: Using default interface naming scheme 'v255'. Jul 6 23:21:38.375683 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:21:38.375871 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:21:38.382506 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:21:38.384356 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:21:38.386357 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:21:38.405829 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:21:38.406998 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:21:38.407162 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). Jul 6 23:21:38.408625 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 6 23:21:38.412354 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 6 23:21:38.413566 augenrules[1385]: No rules Jul 6 23:21:38.419336 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 6 23:21:38.421151 systemd[1]: audit-rules.service: Deactivated successfully. Jul 6 23:21:38.421359 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 6 23:21:38.424930 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 6 23:21:38.428240 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 6 23:21:38.430621 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:21:38.430799 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:21:38.433621 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:21:38.433801 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:21:38.436545 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:21:38.436702 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:21:38.438266 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 6 23:21:38.457593 systemd[1]: Finished ensure-sysext.service. Jul 6 23:21:38.465082 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 6 23:21:38.467088 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 6 23:21:38.468344 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 6 23:21:38.472271 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 6 23:21:38.475309 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 6 23:21:38.483632 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 6 23:21:38.484576 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 6 23:21:38.484628 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). Jul 6 23:21:38.486509 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 6 23:21:38.493075 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jul 6 23:21:38.494144 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). Jul 6 23:21:38.503666 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 6 23:21:38.503946 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 6 23:21:38.505220 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 6 23:21:38.505388 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 6 23:21:38.507212 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 6 23:21:38.507448 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 6 23:21:38.508740 augenrules[1431]: /sbin/augenrules: No change Jul 6 23:21:38.510702 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 6 23:21:38.510926 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 6 23:21:38.519064 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Jul 6 23:21:38.521412 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 6 23:21:38.521495 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 6 23:21:38.522333 augenrules[1460]: No rules Jul 6 23:21:38.525872 systemd[1]: audit-rules.service: Deactivated successfully. Jul 6 23:21:38.527169 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 6 23:21:38.582117 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jul 6 23:21:38.584689 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 6 23:21:38.635379 systemd-resolved[1351]: Positive Trust Anchors: Jul 6 23:21:38.635393 systemd-resolved[1351]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 6 23:21:38.635426 systemd-resolved[1351]: 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 Jul 6 23:21:38.661032 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 6 23:21:38.663659 systemd-resolved[1351]: Defaulting to hostname 'linux'. Jul 6 23:21:38.666643 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 6 23:21:38.667695 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 6 23:21:38.678834 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jul 6 23:21:38.679991 systemd[1]: Reached target sysinit.target - System Initialization. Jul 6 23:21:38.680683 systemd-networkd[1436]: lo: Link UP Jul 6 23:21:38.680946 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 6 23:21:38.681020 systemd-networkd[1436]: lo: Gained carrier Jul 6 23:21:38.681838 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 6 23:21:38.682391 systemd-networkd[1436]: Enumeration completed Jul 6 23:21:38.683088 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 6 23:21:38.683827 systemd-networkd[1436]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:21:38.683935 systemd-networkd[1436]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 6 23:21:38.684530 systemd-networkd[1436]: eth0: Link UP Jul 6 23:21:38.684612 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 6 23:21:38.684655 systemd[1]: Reached target paths.target - Path Units. Jul 6 23:21:38.684716 systemd-networkd[1436]: eth0: Gained carrier Jul 6 23:21:38.684732 systemd-networkd[1436]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 6 23:21:38.685798 systemd[1]: Reached target time-set.target - System Time Set. Jul 6 23:21:38.686821 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 6 23:21:38.687785 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 6 23:21:38.688776 systemd[1]: Reached target timers.target - Timer Units. Jul 6 23:21:38.692086 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 6 23:21:38.694739 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 6 23:21:38.702507 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 6 23:21:38.703937 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 6 23:21:38.705972 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 6 23:21:38.716952 systemd-networkd[1436]: eth0: DHCPv4 address 10.0.0.25/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 6 23:21:38.718984 systemd-timesyncd[1438]: Network configuration changed, trying to establish connection. Jul 6 23:21:38.719760 systemd-timesyncd[1438]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 6 23:21:38.719811 systemd-timesyncd[1438]: Initial clock synchronization to Sun 2025-07-06 23:21:38.570308 UTC. Jul 6 23:21:38.723008 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 6 23:21:38.726463 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 6 23:21:38.728105 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 6 23:21:38.731309 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 6 23:21:38.747805 systemd[1]: Reached target network.target - Network. Jul 6 23:21:38.748933 systemd[1]: Reached target sockets.target - Socket Units. Jul 6 23:21:38.751051 systemd[1]: Reached target basic.target - Basic System. Jul 6 23:21:38.752158 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 6 23:21:38.752267 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 6 23:21:38.753783 systemd[1]: Starting containerd.service - containerd container runtime... Jul 6 23:21:38.758541 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 6 23:21:38.765817 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 6 23:21:38.768051 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 6 23:21:38.770132 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 6 23:21:38.771163 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). Jul 6 23:21:38.772302 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 6 23:21:38.783405 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 6 23:21:38.787254 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 6 23:21:38.796312 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 6 23:21:38.807275 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 6 23:21:38.811398 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 6 23:21:38.813432 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 6 23:21:38.814043 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 6 23:21:38.819748 systemd[1]: Starting update-engine.service - Update Engine... Jul 6 23:21:38.822207 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 6 23:21:38.823203 jq[1496]: false Jul 6 23:21:38.842116 jq[1513]: true Jul 6 23:21:38.843488 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 6 23:21:38.847615 extend-filesystems[1497]: Found /dev/vda6 Jul 6 23:21:38.848003 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 6 23:21:38.848220 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 6 23:21:38.848635 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 6 23:21:38.848796 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 6 23:21:38.856697 systemd[1]: motdgen.service: Deactivated successfully. Jul 6 23:21:38.856988 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 6 23:21:38.865738 extend-filesystems[1497]: Found /dev/vda9 Jul 6 23:21:38.871793 extend-filesystems[1497]: Checking size of /dev/vda9 Jul 6 23:21:38.872113 (ntainerd)[1520]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 6 23:21:38.875428 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 6 23:21:38.886416 jq[1519]: true Jul 6 23:21:38.903573 extend-filesystems[1497]: Resized partition /dev/vda9 Jul 6 23:21:38.915611 extend-filesystems[1536]: resize2fs 1.47.2 (1-Jan-2025) Jul 6 23:21:38.917315 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 6 23:21:38.925923 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Jul 6 23:21:38.957272 systemd-logind[1504]: Watching system buttons on /dev/input/event0 (Power Button) Jul 6 23:21:38.957632 systemd-logind[1504]: New seat seat0. Jul 6 23:21:38.958610 systemd[1]: Started systemd-logind.service - User Login Management. Jul 6 23:21:38.964501 update_engine[1511]: I20250706 23:21:38.963825 1511 main.cc:92] Flatcar Update Engine starting Jul 6 23:21:38.966297 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Jul 6 23:21:38.968374 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 6 23:21:38.967922 dbus-daemon[1494]: [system] SELinux support is enabled Jul 6 23:21:39.009336 update_engine[1511]: I20250706 23:21:38.980995 1511 update_check_scheduler.cc:74] Next update check in 7m11s Jul 6 23:21:38.972752 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). Jul 6 23:21:38.983325 dbus-daemon[1494]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 6 23:21:38.972779 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 6 23:21:38.975868 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). Jul 6 23:21:38.975915 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 6 23:21:38.981643 systemd[1]: Started update-engine.service - Update Engine. Jul 6 23:21:38.990488 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 6 23:21:39.010764 extend-filesystems[1536]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Jul 6 23:21:39.010764 extend-filesystems[1536]: old_desc_blocks = 1, new_desc_blocks = 1 Jul 6 23:21:39.010764 extend-filesystems[1536]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Jul 6 23:21:39.019341 extend-filesystems[1497]: Resized filesystem in /dev/vda9 Jul 6 23:21:39.013882 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 6 23:21:39.014158 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 6 23:21:39.043968 bash[1555]: Updated "/home/core/.ssh/authorized_keys" Jul 6 23:21:39.044310 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 6 23:21:39.046995 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 6 23:21:39.049991 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 6 23:21:39.077996 locksmithd[1556]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 6 23:21:39.207209 containerd[1520]: time="2025-07-06T23:21:39Z" 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 Jul 6 23:21:39.212129 containerd[1520]: time="2025-07-06T23:21:39.209958367Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jul 6 23:21:39.219399 containerd[1520]: time="2025-07-06T23:21:39.219338856Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.756µs" Jul 6 23:21:39.219399 containerd[1520]: time="2025-07-06T23:21:39.219382741Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 6 23:21:39.219399 containerd[1520]: time="2025-07-06T23:21:39.219402957Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 6 23:21:39.219611 containerd[1520]: time="2025-07-06T23:21:39.219580342Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 6 23:21:39.219611 containerd[1520]: time="2025-07-06T23:21:39.219602441Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 6 23:21:39.219661 containerd[1520]: time="2025-07-06T23:21:39.219630075Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 6 23:21:39.219704 containerd[1520]: time="2025-07-06T23:21:39.219688052Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 6 23:21:39.219731 containerd[1520]: time="2025-07-06T23:21:39.219704343Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 6 23:21:39.220015 containerd[1520]: time="2025-07-06T23:21:39.219980803Z" 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 Jul 6 23:21:39.220015 containerd[1520]: time="2025-07-06T23:21:39.220002353Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 6 23:21:39.220067 containerd[1520]: time="2025-07-06T23:21:39.220021979Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 6 23:21:39.220067 containerd[1520]: time="2025-07-06T23:21:39.220030301Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 6 23:21:39.220126 containerd[1520]: time="2025-07-06T23:21:39.220109906Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 6 23:21:39.220331 containerd[1520]: time="2025-07-06T23:21:39.220300638Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 6 23:21:39.220355 containerd[1520]: time="2025-07-06T23:21:39.220334827Z" 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 Jul 6 23:21:39.220355 containerd[1520]: time="2025-07-06T23:21:39.220345386Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 6 23:21:39.220400 containerd[1520]: time="2025-07-06T23:21:39.220386877Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 6 23:21:39.220624 containerd[1520]: time="2025-07-06T23:21:39.220610031Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 6 23:21:39.220685 containerd[1520]: time="2025-07-06T23:21:39.220669657Z" level=info msg="metadata content store policy set" policy=shared Jul 6 23:21:39.230406 containerd[1520]: time="2025-07-06T23:21:39.230356242Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 6 23:21:39.230527 containerd[1520]: time="2025-07-06T23:21:39.230417634Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 6 23:21:39.230527 containerd[1520]: time="2025-07-06T23:21:39.230433375Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 6 23:21:39.230527 containerd[1520]: time="2025-07-06T23:21:39.230445111Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 6 23:21:39.230527 containerd[1520]: time="2025-07-06T23:21:39.230457044Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 6 23:21:39.230527 containerd[1520]: time="2025-07-06T23:21:39.230469252Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 6 23:21:39.230527 containerd[1520]: time="2025-07-06T23:21:39.230480125Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 6 23:21:39.230527 containerd[1520]: time="2025-07-06T23:21:39.230492333Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 6 23:21:39.230527 containerd[1520]: time="2025-07-06T23:21:39.230502931Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 6 23:21:39.230527 containerd[1520]: time="2025-07-06T23:21:39.230521655Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 6 23:21:39.230699 containerd[1520]: time="2025-07-06T23:21:39.230531939Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 6 23:21:39.230699 containerd[1520]: time="2025-07-06T23:21:39.230561418Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 6 23:21:39.230734 containerd[1520]: time="2025-07-06T23:21:39.230711405Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 6 23:21:39.230764 containerd[1520]: time="2025-07-06T23:21:39.230733426Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 6 23:21:39.230764 containerd[1520]: time="2025-07-06T23:21:39.230749716Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 6 23:21:39.230764 containerd[1520]: time="2025-07-06T23:21:39.230760236Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 6 23:21:39.230833 containerd[1520]: time="2025-07-06T23:21:39.230771344Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 6 23:21:39.230833 containerd[1520]: time="2025-07-06T23:21:39.230781864Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 6 23:21:39.230833 containerd[1520]: time="2025-07-06T23:21:39.230792620Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 6 23:21:39.230833 containerd[1520]: time="2025-07-06T23:21:39.230802433Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 6 23:21:39.230833 containerd[1520]: time="2025-07-06T23:21:39.230812835Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 6 23:21:39.230970 containerd[1520]: time="2025-07-06T23:21:39.230823198Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 6 23:21:39.230970 containerd[1520]: time="2025-07-06T23:21:39.230859311Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 6 23:21:39.231720 containerd[1520]: time="2025-07-06T23:21:39.231688534Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 6 23:21:39.231720 containerd[1520]: time="2025-07-06T23:21:39.231712165Z" level=info msg="Start snapshots syncer" Jul 6 23:21:39.231784 containerd[1520]: time="2025-07-06T23:21:39.231738504Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 6 23:21:39.232265 containerd[1520]: time="2025-07-06T23:21:39.231985210Z" 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\"}" Jul 6 23:21:39.232488 containerd[1520]: time="2025-07-06T23:21:39.232445296Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 6 23:21:39.232687 containerd[1520]: time="2025-07-06T23:21:39.232653495Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 6 23:21:39.232997 containerd[1520]: time="2025-07-06T23:21:39.232968502Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 6 23:21:39.233243 containerd[1520]: time="2025-07-06T23:21:39.233022711Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 6 23:21:39.233243 containerd[1520]: time="2025-07-06T23:21:39.233060119Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 6 23:21:39.233243 containerd[1520]: time="2025-07-06T23:21:39.233078450Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 6 23:21:39.233243 containerd[1520]: time="2025-07-06T23:21:39.233098822Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 6 23:21:39.233243 containerd[1520]: time="2025-07-06T23:21:39.233113503Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 6 23:21:39.233243 containerd[1520]: time="2025-07-06T23:21:39.233165043Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 6 23:21:39.233375 containerd[1520]: time="2025-07-06T23:21:39.233228751Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 6 23:21:39.233375 containerd[1520]: time="2025-07-06T23:21:39.233293047Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 6 23:21:39.233375 containerd[1520]: time="2025-07-06T23:21:39.233312988Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 6 23:21:39.233375 containerd[1520]: time="2025-07-06T23:21:39.233368924Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 6 23:21:39.233440 containerd[1520]: time="2025-07-06T23:21:39.233385488Z" 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 Jul 6 23:21:39.233471 containerd[1520]: time="2025-07-06T23:21:39.233398952Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 6 23:21:39.233493 containerd[1520]: time="2025-07-06T23:21:39.233464191Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 6 23:21:39.233493 containerd[1520]: time="2025-07-06T23:21:39.233479578Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 6 23:21:39.233543 containerd[1520]: time="2025-07-06T23:21:39.233490883Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 6 23:21:39.233543 containerd[1520]: time="2025-07-06T23:21:39.233508351Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 6 23:21:39.234057 containerd[1520]: time="2025-07-06T23:21:39.233928949Z" level=info msg="runtime interface created" Jul 6 23:21:39.234057 containerd[1520]: time="2025-07-06T23:21:39.234054245Z" level=info msg="created NRI interface" Jul 6 23:21:39.234108 containerd[1520]: time="2025-07-06T23:21:39.234066453Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 6 23:21:39.234108 containerd[1520]: time="2025-07-06T23:21:39.234084117Z" level=info msg="Connect containerd service" Jul 6 23:21:39.234153 containerd[1520]: time="2025-07-06T23:21:39.234116736Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 6 23:21:39.234878 containerd[1520]: time="2025-07-06T23:21:39.234838602Z" 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" Jul 6 23:21:39.438288 containerd[1520]: time="2025-07-06T23:21:39.437570476Z" level=info msg="Start subscribing containerd event" Jul 6 23:21:39.438288 containerd[1520]: time="2025-07-06T23:21:39.437645528Z" level=info msg="Start recovering state" Jul 6 23:21:39.438288 containerd[1520]: time="2025-07-06T23:21:39.437733298Z" level=info msg="Start event monitor" Jul 6 23:21:39.438288 containerd[1520]: time="2025-07-06T23:21:39.437756615Z" level=info msg="Start cni network conf syncer for default" Jul 6 23:21:39.438288 containerd[1520]: time="2025-07-06T23:21:39.437771609Z" level=info msg="Start streaming server" Jul 6 23:21:39.438288 containerd[1520]: time="2025-07-06T23:21:39.437783307Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 6 23:21:39.438288 containerd[1520]: time="2025-07-06T23:21:39.437790883Z" level=info msg="runtime interface starting up..." Jul 6 23:21:39.438288 containerd[1520]: time="2025-07-06T23:21:39.437796299Z" level=info msg="starting plugins..." Jul 6 23:21:39.438288 containerd[1520]: time="2025-07-06T23:21:39.437811176Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 6 23:21:39.438520 containerd[1520]: time="2025-07-06T23:21:39.438344117Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 6 23:21:39.438520 containerd[1520]: time="2025-07-06T23:21:39.438393419Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 6 23:21:39.438693 containerd[1520]: time="2025-07-06T23:21:39.438674982Z" level=info msg="containerd successfully booted in 0.231948s" Jul 6 23:21:39.438828 systemd[1]: Started containerd.service - containerd container runtime. Jul 6 23:21:39.657844 sshd_keygen[1514]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 6 23:21:39.682969 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 6 23:21:39.687559 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 6 23:21:39.717195 systemd[1]: issuegen.service: Deactivated successfully. Jul 6 23:21:39.719104 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 6 23:21:39.723976 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 6 23:21:39.754419 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 6 23:21:39.765434 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 6 23:21:39.766347 systemd-networkd[1436]: eth0: Gained IPv6LL Jul 6 23:21:39.772597 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Jul 6 23:21:39.773830 systemd[1]: Reached target getty.target - Login Prompts. Jul 6 23:21:39.775219 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 6 23:21:39.784719 systemd[1]: Reached target network-online.target - Network is Online. Jul 6 23:21:39.789267 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jul 6 23:21:39.791994 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:21:39.794297 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 6 23:21:39.830798 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 6 23:21:39.834111 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 6 23:21:39.834471 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jul 6 23:21:39.838271 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 6 23:21:40.471524 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:21:40.473252 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 6 23:21:40.477302 (kubelet)[1628]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Jul 6 23:21:40.477990 systemd[1]: Startup finished in 2.150s (kernel) + 5.140s (initrd) + 3.755s (userspace) = 11.047s. Jul 6 23:21:41.024128 kubelet[1628]: E0706 23:21:41.024061 1628 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" Jul 6 23:21:41.026346 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Jul 6 23:21:41.026485 systemd[1]: kubelet.service: Failed with result 'exit-code'. Jul 6 23:21:41.026811 systemd[1]: kubelet.service: Consumed 863ms CPU time, 257.8M memory peak. Jul 6 23:21:44.672280 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 6 23:21:44.673552 systemd[1]: Started sshd@0-10.0.0.25:22-10.0.0.1:43020.service - OpenSSH per-connection server daemon (10.0.0.1:43020). Jul 6 23:21:44.764909 sshd[1642]: Accepted publickey for core from 10.0.0.1 port 43020 ssh2: RSA SHA256:CSJlI8/o3cgAW3JnP3N/e8VY57OeBgyk25K3mGio6wo Jul 6 23:21:44.766858 sshd-session[1642]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:21:44.773422 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 6 23:21:44.775118 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 6 23:21:44.783593 systemd-logind[1504]: New session 1 of user core. Jul 6 23:21:44.798826 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 6 23:21:44.801503 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 6 23:21:44.828138 (systemd)[1646]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 6 23:21:44.830246 systemd-logind[1504]: New session c1 of user core. Jul 6 23:21:44.941690 systemd[1646]: Queued start job for default target default.target. Jul 6 23:21:44.953859 systemd[1646]: Created slice app.slice - User Application Slice. Jul 6 23:21:44.953915 systemd[1646]: Reached target paths.target - Paths. Jul 6 23:21:44.953956 systemd[1646]: Reached target timers.target - Timers. Jul 6 23:21:44.955242 systemd[1646]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 6 23:21:44.967921 systemd[1646]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 6 23:21:44.968042 systemd[1646]: Reached target sockets.target - Sockets. Jul 6 23:21:44.968092 systemd[1646]: Reached target basic.target - Basic System. Jul 6 23:21:44.968120 systemd[1646]: Reached target default.target - Main User Target. Jul 6 23:21:44.968147 systemd[1646]: Startup finished in 132ms. Jul 6 23:21:44.968376 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 6 23:21:44.970093 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 6 23:21:45.034804 systemd[1]: Started sshd@1-10.0.0.25:22-10.0.0.1:43032.service - OpenSSH per-connection server daemon (10.0.0.1:43032). Jul 6 23:21:45.103073 sshd[1657]: Accepted publickey for core from 10.0.0.1 port 43032 ssh2: RSA SHA256:CSJlI8/o3cgAW3JnP3N/e8VY57OeBgyk25K3mGio6wo Jul 6 23:21:45.104434 sshd-session[1657]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:21:45.109060 systemd-logind[1504]: New session 2 of user core. Jul 6 23:21:45.129092 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 6 23:21:45.185905 sshd[1659]: Connection closed by 10.0.0.1 port 43032 Jul 6 23:21:45.186944 sshd-session[1657]: pam_unix(sshd:session): session closed for user core Jul 6 23:21:45.196306 systemd[1]: sshd@1-10.0.0.25:22-10.0.0.1:43032.service: Deactivated successfully. Jul 6 23:21:45.199223 systemd[1]: session-2.scope: Deactivated successfully. Jul 6 23:21:45.200954 systemd-logind[1504]: Session 2 logged out. Waiting for processes to exit. Jul 6 23:21:45.203798 systemd[1]: Started sshd@2-10.0.0.25:22-10.0.0.1:43036.service - OpenSSH per-connection server daemon (10.0.0.1:43036). Jul 6 23:21:45.204512 systemd-logind[1504]: Removed session 2. Jul 6 23:21:45.264471 sshd[1665]: Accepted publickey for core from 10.0.0.1 port 43036 ssh2: RSA SHA256:CSJlI8/o3cgAW3JnP3N/e8VY57OeBgyk25K3mGio6wo Jul 6 23:21:45.265911 sshd-session[1665]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:21:45.269972 systemd-logind[1504]: New session 3 of user core. Jul 6 23:21:45.281129 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 6 23:21:45.333928 sshd[1667]: Connection closed by 10.0.0.1 port 43036 Jul 6 23:21:45.334594 sshd-session[1665]: pam_unix(sshd:session): session closed for user core Jul 6 23:21:45.346950 systemd[1]: sshd@2-10.0.0.25:22-10.0.0.1:43036.service: Deactivated successfully. Jul 6 23:21:45.348613 systemd[1]: session-3.scope: Deactivated successfully. Jul 6 23:21:45.350527 systemd-logind[1504]: Session 3 logged out. Waiting for processes to exit. Jul 6 23:21:45.355821 systemd[1]: Started sshd@3-10.0.0.25:22-10.0.0.1:43044.service - OpenSSH per-connection server daemon (10.0.0.1:43044). Jul 6 23:21:45.356526 systemd-logind[1504]: Removed session 3. Jul 6 23:21:45.410649 sshd[1673]: Accepted publickey for core from 10.0.0.1 port 43044 ssh2: RSA SHA256:CSJlI8/o3cgAW3JnP3N/e8VY57OeBgyk25K3mGio6wo Jul 6 23:21:45.411995 sshd-session[1673]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:21:45.420789 systemd-logind[1504]: New session 4 of user core. Jul 6 23:21:45.431148 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 6 23:21:45.484659 sshd[1675]: Connection closed by 10.0.0.1 port 43044 Jul 6 23:21:45.485015 sshd-session[1673]: pam_unix(sshd:session): session closed for user core Jul 6 23:21:45.503145 systemd[1]: sshd@3-10.0.0.25:22-10.0.0.1:43044.service: Deactivated successfully. Jul 6 23:21:45.505533 systemd[1]: session-4.scope: Deactivated successfully. Jul 6 23:21:45.509947 systemd-logind[1504]: Session 4 logged out. Waiting for processes to exit. Jul 6 23:21:45.511773 systemd[1]: Started sshd@4-10.0.0.25:22-10.0.0.1:43056.service - OpenSSH per-connection server daemon (10.0.0.1:43056). Jul 6 23:21:45.512765 systemd-logind[1504]: Removed session 4. Jul 6 23:21:45.571559 sshd[1681]: Accepted publickey for core from 10.0.0.1 port 43056 ssh2: RSA SHA256:CSJlI8/o3cgAW3JnP3N/e8VY57OeBgyk25K3mGio6wo Jul 6 23:21:45.573119 sshd-session[1681]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 6 23:21:45.581215 systemd-logind[1504]: New session 5 of user core. Jul 6 23:21:45.592115 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 6 23:21:45.662503 sudo[1684]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Jul 6 23:21:45.662787 sudo[1684]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 6 23:21:45.686098 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jul 6 23:21:45.728988 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 6 23:21:45.729233 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jul 6 23:21:46.361827 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:21:46.362003 systemd[1]: kubelet.service: Consumed 863ms CPU time, 257.8M memory peak. Jul 6 23:21:46.364290 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:21:46.387624 systemd[1]: Reload requested from client PID 1725 ('systemctl') (unit session-5.scope)... Jul 6 23:21:46.387649 systemd[1]: Reloading... Jul 6 23:21:46.470910 zram_generator::config[1770]: No configuration found. Jul 6 23:21:46.662295 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. Jul 6 23:21:46.752970 systemd[1]: Reloading finished in 364 ms. Jul 6 23:21:46.808479 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Jul 6 23:21:46.808571 systemd[1]: kubelet.service: Failed with result 'signal'. Jul 6 23:21:46.808824 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:21:46.808902 systemd[1]: kubelet.service: Consumed 97ms CPU time, 95M memory peak. Jul 6 23:21:46.810712 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Jul 6 23:21:46.919645 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Jul 6 23:21:46.924067 (kubelet)[1812]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Jul 6 23:21:46.959825 kubelet[1812]: 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. Jul 6 23:21:46.959825 kubelet[1812]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Jul 6 23:21:46.959825 kubelet[1812]: 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. Jul 6 23:21:46.960199 kubelet[1812]: I0706 23:21:46.959924 1812 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Jul 6 23:21:47.540706 kubelet[1812]: I0706 23:21:47.540643 1812 server.go:520] "Kubelet version" kubeletVersion="v1.32.4" Jul 6 23:21:47.540706 kubelet[1812]: I0706 23:21:47.540695 1812 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Jul 6 23:21:47.541174 kubelet[1812]: I0706 23:21:47.541145 1812 server.go:954] "Client rotation is on, will bootstrap in background" Jul 6 23:21:47.610835 kubelet[1812]: I0706 23:21:47.610589 1812 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Jul 6 23:21:47.627669 kubelet[1812]: I0706 23:21:47.627637 1812 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Jul 6 23:21:47.631354 kubelet[1812]: I0706 23:21:47.631328 1812 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Jul 6 23:21:47.632051 kubelet[1812]: I0706 23:21:47.632000 1812 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Jul 6 23:21:47.632222 kubelet[1812]: I0706 23:21:47.632054 1812 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.25","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.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Jul 6 23:21:47.632369 kubelet[1812]: I0706 23:21:47.632357 1812 topology_manager.go:138] "Creating topology manager with none policy" Jul 6 23:21:47.632369 kubelet[1812]: I0706 23:21:47.632368 1812 container_manager_linux.go:304] "Creating device plugin manager" Jul 6 23:21:47.632635 kubelet[1812]: I0706 23:21:47.632617 1812 state_mem.go:36] "Initialized new in-memory state store" Jul 6 23:21:47.639506 kubelet[1812]: I0706 23:21:47.639474 1812 kubelet.go:446] "Attempting to sync node with API server" Jul 6 23:21:47.639506 kubelet[1812]: I0706 23:21:47.639508 1812 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Jul 6 23:21:47.639620 kubelet[1812]: I0706 23:21:47.639535 1812 kubelet.go:352] "Adding apiserver pod source" Jul 6 23:21:47.639620 kubelet[1812]: I0706 23:21:47.639546 1812 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Jul 6 23:21:47.639773 kubelet[1812]: E0706 23:21:47.639746 1812 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:47.639861 kubelet[1812]: E0706 23:21:47.639848 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:47.649293 kubelet[1812]: I0706 23:21:47.649255 1812 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" Jul 6 23:21:47.650031 kubelet[1812]: I0706 23:21:47.650016 1812 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Jul 6 23:21:47.650148 kubelet[1812]: W0706 23:21:47.650136 1812 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Jul 6 23:21:47.651381 kubelet[1812]: I0706 23:21:47.651354 1812 watchdog_linux.go:99] "Systemd watchdog is not enabled" Jul 6 23:21:47.651423 kubelet[1812]: I0706 23:21:47.651398 1812 server.go:1287] "Started kubelet" Jul 6 23:21:47.651591 kubelet[1812]: I0706 23:21:47.651554 1812 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Jul 6 23:21:47.655049 kubelet[1812]: I0706 23:21:47.652465 1812 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Jul 6 23:21:47.655049 kubelet[1812]: I0706 23:21:47.652735 1812 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Jul 6 23:21:47.655049 kubelet[1812]: I0706 23:21:47.652961 1812 server.go:479] "Adding debug handlers to kubelet server" Jul 6 23:21:47.655049 kubelet[1812]: I0706 23:21:47.653640 1812 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Jul 6 23:21:47.655193 kubelet[1812]: I0706 23:21:47.655059 1812 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Jul 6 23:21:47.656619 kubelet[1812]: E0706 23:21:47.656577 1812 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.25\" not found" Jul 6 23:21:47.656619 kubelet[1812]: I0706 23:21:47.656625 1812 volume_manager.go:297] "Starting Kubelet Volume Manager" Jul 6 23:21:47.656922 kubelet[1812]: I0706 23:21:47.656806 1812 desired_state_of_world_populator.go:150] "Desired state populator starts to run" Jul 6 23:21:47.656922 kubelet[1812]: I0706 23:21:47.656866 1812 reconciler.go:26] "Reconciler: start to sync state" Jul 6 23:21:47.659078 kubelet[1812]: I0706 23:21:47.658969 1812 factory.go:221] Registration of the containerd container factory successfully Jul 6 23:21:47.659078 kubelet[1812]: I0706 23:21:47.658997 1812 factory.go:221] Registration of the systemd container factory successfully Jul 6 23:21:47.659172 kubelet[1812]: I0706 23:21:47.659086 1812 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Jul 6 23:21:47.659869 kubelet[1812]: E0706 23:21:47.659846 1812 kubelet.go:1555] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Jul 6 23:21:47.666175 kubelet[1812]: E0706 23:21:47.666129 1812 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.25\" not found" node="10.0.0.25" Jul 6 23:21:47.672085 kubelet[1812]: I0706 23:21:47.672057 1812 cpu_manager.go:221] "Starting CPU manager" policy="none" Jul 6 23:21:47.672085 kubelet[1812]: I0706 23:21:47.672076 1812 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Jul 6 23:21:47.672223 kubelet[1812]: I0706 23:21:47.672102 1812 state_mem.go:36] "Initialized new in-memory state store" Jul 6 23:21:47.751298 kubelet[1812]: I0706 23:21:47.751257 1812 policy_none.go:49] "None policy: Start" Jul 6 23:21:47.751298 kubelet[1812]: I0706 23:21:47.751289 1812 memory_manager.go:186] "Starting memorymanager" policy="None" Jul 6 23:21:47.751298 kubelet[1812]: I0706 23:21:47.751305 1812 state_mem.go:35] "Initializing new in-memory state store" Jul 6 23:21:47.756777 kubelet[1812]: E0706 23:21:47.756750 1812 kubelet_node_status.go:466] "Error getting the current node from lister" err="node \"10.0.0.25\" not found" Jul 6 23:21:47.758959 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Jul 6 23:21:47.770208 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Jul 6 23:21:47.774389 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Jul 6 23:21:47.784240 kubelet[1812]: I0706 23:21:47.784166 1812 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Jul 6 23:21:47.785329 kubelet[1812]: I0706 23:21:47.785258 1812 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Jul 6 23:21:47.785329 kubelet[1812]: I0706 23:21:47.785294 1812 status_manager.go:227] "Starting to sync pod status with apiserver" Jul 6 23:21:47.785329 kubelet[1812]: I0706 23:21:47.785319 1812 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Jul 6 23:21:47.785329 kubelet[1812]: I0706 23:21:47.785326 1812 kubelet.go:2382] "Starting kubelet main sync loop" Jul 6 23:21:47.785444 kubelet[1812]: E0706 23:21:47.785372 1812 kubelet.go:2406] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Jul 6 23:21:47.787930 kubelet[1812]: I0706 23:21:47.787540 1812 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Jul 6 23:21:47.787930 kubelet[1812]: I0706 23:21:47.787730 1812 eviction_manager.go:189] "Eviction manager: starting control loop" Jul 6 23:21:47.787930 kubelet[1812]: I0706 23:21:47.787741 1812 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Jul 6 23:21:47.788042 kubelet[1812]: I0706 23:21:47.787990 1812 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Jul 6 23:21:47.789159 kubelet[1812]: E0706 23:21:47.789132 1812 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Jul 6 23:21:47.789221 kubelet[1812]: E0706 23:21:47.789171 1812 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.25\" not found" Jul 6 23:21:47.889051 kubelet[1812]: I0706 23:21:47.888951 1812 kubelet_node_status.go:75] "Attempting to register node" node="10.0.0.25" Jul 6 23:21:47.896399 kubelet[1812]: I0706 23:21:47.896364 1812 kubelet_node_status.go:78] "Successfully registered node" node="10.0.0.25" Jul 6 23:21:47.910472 kubelet[1812]: I0706 23:21:47.910421 1812 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Jul 6 23:21:47.910776 containerd[1520]: time="2025-07-06T23:21:47.910732269Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Jul 6 23:21:47.911191 kubelet[1812]: I0706 23:21:47.910882 1812 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Jul 6 23:21:47.952290 sudo[1684]: pam_unix(sudo:session): session closed for user root Jul 6 23:21:47.953911 sshd[1683]: Connection closed by 10.0.0.1 port 43056 Jul 6 23:21:47.954407 sshd-session[1681]: pam_unix(sshd:session): session closed for user core Jul 6 23:21:47.957544 systemd[1]: sshd@4-10.0.0.25:22-10.0.0.1:43056.service: Deactivated successfully. Jul 6 23:21:47.959590 systemd[1]: session-5.scope: Deactivated successfully. Jul 6 23:21:47.959937 systemd[1]: session-5.scope: Consumed 521ms CPU time, 75.7M memory peak. Jul 6 23:21:47.961709 systemd-logind[1504]: Session 5 logged out. Waiting for processes to exit. Jul 6 23:21:47.963312 systemd-logind[1504]: Removed session 5. Jul 6 23:21:48.543761 kubelet[1812]: I0706 23:21:48.543702 1812 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Jul 6 23:21:48.544152 kubelet[1812]: W0706 23:21:48.543906 1812 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 6 23:21:48.544152 kubelet[1812]: W0706 23:21:48.543947 1812 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 6 23:21:48.544152 kubelet[1812]: W0706 23:21:48.543991 1812 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Jul 6 23:21:48.640669 kubelet[1812]: I0706 23:21:48.640615 1812 apiserver.go:52] "Watching apiserver" Jul 6 23:21:48.640863 kubelet[1812]: E0706 23:21:48.640569 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:48.654974 systemd[1]: Created slice kubepods-burstable-pod429902c1_8b61_48cd_9103_cfe297ebd2b0.slice - libcontainer container kubepods-burstable-pod429902c1_8b61_48cd_9103_cfe297ebd2b0.slice. Jul 6 23:21:48.657739 kubelet[1812]: I0706 23:21:48.657696 1812 desired_state_of_world_populator.go:158] "Finished populating initial desired state of world" Jul 6 23:21:48.664420 kubelet[1812]: I0706 23:21:48.662838 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/429902c1-8b61-48cd-9103-cfe297ebd2b0-flannel-cfg\") pod \"kube-flannel-ds-4cgwl\" (UID: \"429902c1-8b61-48cd-9103-cfe297ebd2b0\") " pod="kube-flannel/kube-flannel-ds-4cgwl" Jul 6 23:21:48.664420 kubelet[1812]: I0706 23:21:48.662872 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/429902c1-8b61-48cd-9103-cfe297ebd2b0-xtables-lock\") pod \"kube-flannel-ds-4cgwl\" (UID: \"429902c1-8b61-48cd-9103-cfe297ebd2b0\") " pod="kube-flannel/kube-flannel-ds-4cgwl" Jul 6 23:21:48.664420 kubelet[1812]: I0706 23:21:48.662907 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/9401828a-b5b9-4e5f-9162-23eb850741c7-kube-proxy\") pod \"kube-proxy-tht9g\" (UID: \"9401828a-b5b9-4e5f-9162-23eb850741c7\") " pod="kube-system/kube-proxy-tht9g" Jul 6 23:21:48.664420 kubelet[1812]: I0706 23:21:48.662923 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/9401828a-b5b9-4e5f-9162-23eb850741c7-xtables-lock\") pod \"kube-proxy-tht9g\" (UID: \"9401828a-b5b9-4e5f-9162-23eb850741c7\") " pod="kube-system/kube-proxy-tht9g" Jul 6 23:21:48.664420 kubelet[1812]: I0706 23:21:48.662938 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/9401828a-b5b9-4e5f-9162-23eb850741c7-lib-modules\") pod \"kube-proxy-tht9g\" (UID: \"9401828a-b5b9-4e5f-9162-23eb850741c7\") " pod="kube-system/kube-proxy-tht9g" Jul 6 23:21:48.664819 kubelet[1812]: I0706 23:21:48.662952 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/429902c1-8b61-48cd-9103-cfe297ebd2b0-cni-plugin\") pod \"kube-flannel-ds-4cgwl\" (UID: \"429902c1-8b61-48cd-9103-cfe297ebd2b0\") " pod="kube-flannel/kube-flannel-ds-4cgwl" Jul 6 23:21:48.664819 kubelet[1812]: I0706 23:21:48.662965 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/429902c1-8b61-48cd-9103-cfe297ebd2b0-cni\") pod \"kube-flannel-ds-4cgwl\" (UID: \"429902c1-8b61-48cd-9103-cfe297ebd2b0\") " pod="kube-flannel/kube-flannel-ds-4cgwl" Jul 6 23:21:48.664819 kubelet[1812]: I0706 23:21:48.662993 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-j5s68\" (UniqueName: \"kubernetes.io/projected/9401828a-b5b9-4e5f-9162-23eb850741c7-kube-api-access-j5s68\") pod \"kube-proxy-tht9g\" (UID: \"9401828a-b5b9-4e5f-9162-23eb850741c7\") " pod="kube-system/kube-proxy-tht9g" Jul 6 23:21:48.664819 kubelet[1812]: I0706 23:21:48.663011 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/429902c1-8b61-48cd-9103-cfe297ebd2b0-run\") pod \"kube-flannel-ds-4cgwl\" (UID: \"429902c1-8b61-48cd-9103-cfe297ebd2b0\") " pod="kube-flannel/kube-flannel-ds-4cgwl" Jul 6 23:21:48.664819 kubelet[1812]: I0706 23:21:48.663024 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mxx2p\" (UniqueName: \"kubernetes.io/projected/429902c1-8b61-48cd-9103-cfe297ebd2b0-kube-api-access-mxx2p\") pod \"kube-flannel-ds-4cgwl\" (UID: \"429902c1-8b61-48cd-9103-cfe297ebd2b0\") " pod="kube-flannel/kube-flannel-ds-4cgwl" Jul 6 23:21:48.679027 systemd[1]: Created slice kubepods-besteffort-pod9401828a_b5b9_4e5f_9162_23eb850741c7.slice - libcontainer container kubepods-besteffort-pod9401828a_b5b9_4e5f_9162_23eb850741c7.slice. Jul 6 23:21:48.977926 containerd[1520]: time="2025-07-06T23:21:48.977800004Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4cgwl,Uid:429902c1-8b61-48cd-9103-cfe297ebd2b0,Namespace:kube-flannel,Attempt:0,}" Jul 6 23:21:49.009964 containerd[1520]: time="2025-07-06T23:21:49.009778350Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tht9g,Uid:9401828a-b5b9-4e5f-9162-23eb850741c7,Namespace:kube-system,Attempt:0,}" Jul 6 23:21:49.464026 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount755814879.mount: Deactivated successfully. Jul 6 23:21:49.473543 containerd[1520]: time="2025-07-06T23:21:49.473487439Z" 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\"}" Jul 6 23:21:49.474255 containerd[1520]: time="2025-07-06T23:21:49.474164020Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=268705" Jul 6 23:21:49.475224 containerd[1520]: time="2025-07-06T23:21:49.475191230Z" 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\"}" Jul 6 23:21:49.476061 containerd[1520]: time="2025-07-06T23:21:49.476030845Z" level=info msg="ImageCreate event name:\"sha256:afb61768ce381961ca0beff95337601f29dc70ff3ed14e5e4b3e5699057e6aa8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Jul 6 23:21:49.476602 containerd[1520]: time="2025-07-06T23:21:49.476570661Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Jul 6 23:21:49.478485 containerd[1520]: time="2025-07-06T23:21:49.478430122Z" 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\"}" Jul 6 23:21:49.479087 containerd[1520]: time="2025-07-06T23:21:49.479044569Z" 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 493.48715ms" Jul 6 23:21:49.480176 containerd[1520]: time="2025-07-06T23:21:49.480141435Z" 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 462.827442ms" Jul 6 23:21:49.509312 containerd[1520]: time="2025-07-06T23:21:49.509243547Z" level=info msg="connecting to shim 7a3fc8bca24bd5cafd454dd8b520133fb8a3e021fe46fc8c927f76898b7ee4d2" address="unix:///run/containerd/s/fb6f8bd9ed5ed030507caa770c6bac8bc693f7fa9ecc14dd54483e58f173f2cc" namespace=k8s.io protocol=ttrpc version=3 Jul 6 23:21:49.510075 containerd[1520]: time="2025-07-06T23:21:49.510034722Z" level=info msg="connecting to shim 4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5" address="unix:///run/containerd/s/70b235577317bdb239120f0926d8a8749ec3f4f9631151d4529d4c0d3124ae53" namespace=k8s.io protocol=ttrpc version=3 Jul 6 23:21:49.533635 systemd[1]: Started cri-containerd-4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5.scope - libcontainer container 4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5. Jul 6 23:21:49.540759 systemd[1]: Started cri-containerd-7a3fc8bca24bd5cafd454dd8b520133fb8a3e021fe46fc8c927f76898b7ee4d2.scope - libcontainer container 7a3fc8bca24bd5cafd454dd8b520133fb8a3e021fe46fc8c927f76898b7ee4d2. Jul 6 23:21:49.576306 containerd[1520]: time="2025-07-06T23:21:49.573740550Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-tht9g,Uid:9401828a-b5b9-4e5f-9162-23eb850741c7,Namespace:kube-system,Attempt:0,} returns sandbox id \"7a3fc8bca24bd5cafd454dd8b520133fb8a3e021fe46fc8c927f76898b7ee4d2\"" Jul 6 23:21:49.576611 containerd[1520]: time="2025-07-06T23:21:49.576556251Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.6\"" Jul 6 23:21:49.585761 containerd[1520]: time="2025-07-06T23:21:49.585670145Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-4cgwl,Uid:429902c1-8b61-48cd-9103-cfe297ebd2b0,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5\"" Jul 6 23:21:49.642564 kubelet[1812]: E0706 23:21:49.642519 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:50.545321 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1676304188.mount: Deactivated successfully. Jul 6 23:21:50.643388 kubelet[1812]: E0706 23:21:50.643303 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:50.789410 containerd[1520]: time="2025-07-06T23:21:50.789343897Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.6\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:21:50.790568 containerd[1520]: time="2025-07-06T23:21:50.790526193Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.6: active requests=0, bytes read=27378408" Jul 6 23:21:50.791667 containerd[1520]: time="2025-07-06T23:21:50.791630746Z" level=info msg="ImageCreate event name:\"sha256:e29293ef7b817bb7b03ce7484edafe6ca0a7087e54074e7d7dcd3bd3c762eee9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:21:50.793417 containerd[1520]: time="2025-07-06T23:21:50.793384456Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:b13d9da413b983d130bf090b83fce12e1ccc704e95f366da743c18e964d9d7e9\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:21:50.794022 containerd[1520]: time="2025-07-06T23:21:50.793992631Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.6\" with image id \"sha256:e29293ef7b817bb7b03ce7484edafe6ca0a7087e54074e7d7dcd3bd3c762eee9\", repo tag \"registry.k8s.io/kube-proxy:v1.32.6\", repo digest \"registry.k8s.io/kube-proxy@sha256:b13d9da413b983d130bf090b83fce12e1ccc704e95f366da743c18e964d9d7e9\", size \"27377425\" in 1.21737276s" Jul 6 23:21:50.794051 containerd[1520]: time="2025-07-06T23:21:50.794023100Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.6\" returns image reference \"sha256:e29293ef7b817bb7b03ce7484edafe6ca0a7087e54074e7d7dcd3bd3c762eee9\"" Jul 6 23:21:50.795936 containerd[1520]: time="2025-07-06T23:21:50.795903145Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Jul 6 23:21:50.799870 containerd[1520]: time="2025-07-06T23:21:50.799585809Z" level=info msg="CreateContainer within sandbox \"7a3fc8bca24bd5cafd454dd8b520133fb8a3e021fe46fc8c927f76898b7ee4d2\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Jul 6 23:21:50.810445 containerd[1520]: time="2025-07-06T23:21:50.809027018Z" level=info msg="Container f740ec784a2d9e96c859b953d5daa98a3e6d06217e96acf9fa2aeb5fb2756add: CDI devices from CRI Config.CDIDevices: []" Jul 6 23:21:50.812174 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1444888161.mount: Deactivated successfully. Jul 6 23:21:50.816206 containerd[1520]: time="2025-07-06T23:21:50.816161503Z" level=info msg="CreateContainer within sandbox \"7a3fc8bca24bd5cafd454dd8b520133fb8a3e021fe46fc8c927f76898b7ee4d2\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"f740ec784a2d9e96c859b953d5daa98a3e6d06217e96acf9fa2aeb5fb2756add\"" Jul 6 23:21:50.817095 containerd[1520]: time="2025-07-06T23:21:50.817062215Z" level=info msg="StartContainer for \"f740ec784a2d9e96c859b953d5daa98a3e6d06217e96acf9fa2aeb5fb2756add\"" Jul 6 23:21:50.818602 containerd[1520]: time="2025-07-06T23:21:50.818557521Z" level=info msg="connecting to shim f740ec784a2d9e96c859b953d5daa98a3e6d06217e96acf9fa2aeb5fb2756add" address="unix:///run/containerd/s/fb6f8bd9ed5ed030507caa770c6bac8bc693f7fa9ecc14dd54483e58f173f2cc" protocol=ttrpc version=3 Jul 6 23:21:50.846073 systemd[1]: Started cri-containerd-f740ec784a2d9e96c859b953d5daa98a3e6d06217e96acf9fa2aeb5fb2756add.scope - libcontainer container f740ec784a2d9e96c859b953d5daa98a3e6d06217e96acf9fa2aeb5fb2756add. Jul 6 23:21:50.885198 containerd[1520]: time="2025-07-06T23:21:50.882968541Z" level=info msg="StartContainer for \"f740ec784a2d9e96c859b953d5daa98a3e6d06217e96acf9fa2aeb5fb2756add\" returns successfully" Jul 6 23:21:51.643809 kubelet[1812]: E0706 23:21:51.643758 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:51.810102 kubelet[1812]: I0706 23:21:51.810011 1812 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-tht9g" podStartSLOduration=3.590998324 podStartE2EDuration="4.809994612s" podCreationTimestamp="2025-07-06 23:21:47 +0000 UTC" firstStartedPulling="2025-07-06 23:21:49.576105675 +0000 UTC m=+2.649001021" lastFinishedPulling="2025-07-06 23:21:50.795101963 +0000 UTC m=+3.867997309" observedRunningTime="2025-07-06 23:21:51.809868568 +0000 UTC m=+4.882763874" watchObservedRunningTime="2025-07-06 23:21:51.809994612 +0000 UTC m=+4.882889998" Jul 6 23:21:51.983104 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1172327757.mount: Deactivated successfully. Jul 6 23:21:52.008587 containerd[1520]: time="2025-07-06T23:21:52.008537224Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:21:52.009083 containerd[1520]: time="2025-07-06T23:21:52.009039127Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3673532" Jul 6 23:21:52.009861 containerd[1520]: time="2025-07-06T23:21:52.009820587Z" level=info msg="ImageCreate event name:\"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:21:52.012206 containerd[1520]: time="2025-07-06T23:21:52.012172343Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:21:52.013166 containerd[1520]: time="2025-07-06T23:21:52.013055428Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3662650\" in 1.217117625s" Jul 6 23:21:52.013166 containerd[1520]: time="2025-07-06T23:21:52.013086724Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:b45062ceea496fc421523388cb91166abc7715a15c2e2cbab4e6f8c9d5dc0ab8\"" Jul 6 23:21:52.014911 containerd[1520]: time="2025-07-06T23:21:52.014870236Z" level=info msg="CreateContainer within sandbox \"4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Jul 6 23:21:52.021313 containerd[1520]: time="2025-07-06T23:21:52.020738384Z" level=info msg="Container b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f: CDI devices from CRI Config.CDIDevices: []" Jul 6 23:21:52.028185 containerd[1520]: time="2025-07-06T23:21:52.028138035Z" level=info msg="CreateContainer within sandbox \"4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f\"" Jul 6 23:21:52.028682 containerd[1520]: time="2025-07-06T23:21:52.028656044Z" level=info msg="StartContainer for \"b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f\"" Jul 6 23:21:52.029959 containerd[1520]: time="2025-07-06T23:21:52.029934464Z" level=info msg="connecting to shim b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f" address="unix:///run/containerd/s/70b235577317bdb239120f0926d8a8749ec3f4f9631151d4529d4c0d3124ae53" protocol=ttrpc version=3 Jul 6 23:21:52.049107 systemd[1]: Started cri-containerd-b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f.scope - libcontainer container b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f. Jul 6 23:21:52.095687 containerd[1520]: time="2025-07-06T23:21:52.095636079Z" level=info msg="StartContainer for \"b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f\" returns successfully" Jul 6 23:21:52.098333 systemd[1]: cri-containerd-b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f.scope: Deactivated successfully. Jul 6 23:21:52.100777 containerd[1520]: time="2025-07-06T23:21:52.100727590Z" level=info msg="received exit event container_id:\"b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f\" id:\"b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f\" pid:2140 exited_at:{seconds:1751844112 nanos:100316388}" Jul 6 23:21:52.100860 containerd[1520]: time="2025-07-06T23:21:52.100816337Z" level=info msg="TaskExit event in podsandbox handler container_id:\"b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f\" id:\"b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f\" pid:2140 exited_at:{seconds:1751844112 nanos:100316388}" Jul 6 23:21:52.644477 kubelet[1812]: E0706 23:21:52.644419 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:52.802423 containerd[1520]: time="2025-07-06T23:21:52.802389672Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Jul 6 23:21:52.920305 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-b6cfb98e62e20efafb91f92017f1fc2ae6629ba005434da5d144b20724ca851f-rootfs.mount: Deactivated successfully. Jul 6 23:21:53.644815 kubelet[1812]: E0706 23:21:53.644717 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:53.968668 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount405562965.mount: Deactivated successfully. Jul 6 23:21:54.442439 containerd[1520]: time="2025-07-06T23:21:54.442311365Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:21:54.442733 containerd[1520]: time="2025-07-06T23:21:54.442708601Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26874261" Jul 6 23:21:54.443651 containerd[1520]: time="2025-07-06T23:21:54.443613636Z" level=info msg="ImageCreate event name:\"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:21:54.446718 containerd[1520]: time="2025-07-06T23:21:54.446476006Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:21:54.447633 containerd[1520]: time="2025-07-06T23:21:54.447593185Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26863435\" in 1.645166506s" Jul 6 23:21:54.447633 containerd[1520]: time="2025-07-06T23:21:54.447631528Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:b3d1319ea6da12d4a1dd21a923f6a71f942a7b6e2c4763b8a3cca0725fb8aadf\"" Jul 6 23:21:54.449755 containerd[1520]: time="2025-07-06T23:21:54.449705649Z" level=info msg="CreateContainer within sandbox \"4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Jul 6 23:21:54.456375 containerd[1520]: time="2025-07-06T23:21:54.456034026Z" level=info msg="Container 3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae: CDI devices from CRI Config.CDIDevices: []" Jul 6 23:21:54.465160 containerd[1520]: time="2025-07-06T23:21:54.465119599Z" level=info msg="CreateContainer within sandbox \"4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae\"" Jul 6 23:21:54.466131 containerd[1520]: time="2025-07-06T23:21:54.466071754Z" level=info msg="StartContainer for \"3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae\"" Jul 6 23:21:54.466980 containerd[1520]: time="2025-07-06T23:21:54.466951971Z" level=info msg="connecting to shim 3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae" address="unix:///run/containerd/s/70b235577317bdb239120f0926d8a8749ec3f4f9631151d4529d4c0d3124ae53" protocol=ttrpc version=3 Jul 6 23:21:54.491071 systemd[1]: Started cri-containerd-3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae.scope - libcontainer container 3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae. Jul 6 23:21:54.521856 systemd[1]: cri-containerd-3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae.scope: Deactivated successfully. Jul 6 23:21:54.522471 containerd[1520]: time="2025-07-06T23:21:54.522440307Z" level=info msg="TaskExit event in podsandbox handler container_id:\"3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae\" id:\"3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae\" pid:2216 exited_at:{seconds:1751844114 nanos:522041714}" Jul 6 23:21:54.532120 containerd[1520]: time="2025-07-06T23:21:54.532075332Z" level=info msg="received exit event container_id:\"3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae\" id:\"3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae\" pid:2216 exited_at:{seconds:1751844114 nanos:522041714}" Jul 6 23:21:54.533376 containerd[1520]: time="2025-07-06T23:21:54.533349993Z" level=info msg="StartContainer for \"3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae\" returns successfully" Jul 6 23:21:54.548597 kubelet[1812]: I0706 23:21:54.548568 1812 kubelet_node_status.go:501] "Fast updating node status as it just became ready" Jul 6 23:21:54.645803 kubelet[1812]: E0706 23:21:54.645746 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:54.810045 containerd[1520]: time="2025-07-06T23:21:54.809554198Z" level=info msg="CreateContainer within sandbox \"4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Jul 6 23:21:54.890220 containerd[1520]: time="2025-07-06T23:21:54.890130052Z" level=info msg="Container 286afd14fc623e63c784a154ee72c0b2cf4cf0cfed2fbf3a28471f1b53ac3dd7: CDI devices from CRI Config.CDIDevices: []" Jul 6 23:21:54.896052 containerd[1520]: time="2025-07-06T23:21:54.895993842Z" level=info msg="CreateContainer within sandbox \"4cdeb13b08f34a1c1c50a952080e5963da0f0512283e397b9f56e1dcc7d721d5\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"286afd14fc623e63c784a154ee72c0b2cf4cf0cfed2fbf3a28471f1b53ac3dd7\"" Jul 6 23:21:54.896488 containerd[1520]: time="2025-07-06T23:21:54.896456434Z" level=info msg="StartContainer for \"286afd14fc623e63c784a154ee72c0b2cf4cf0cfed2fbf3a28471f1b53ac3dd7\"" Jul 6 23:21:54.897327 containerd[1520]: time="2025-07-06T23:21:54.897295794Z" level=info msg="connecting to shim 286afd14fc623e63c784a154ee72c0b2cf4cf0cfed2fbf3a28471f1b53ac3dd7" address="unix:///run/containerd/s/70b235577317bdb239120f0926d8a8749ec3f4f9631151d4529d4c0d3124ae53" protocol=ttrpc version=3 Jul 6 23:21:54.916058 systemd[1]: Started cri-containerd-286afd14fc623e63c784a154ee72c0b2cf4cf0cfed2fbf3a28471f1b53ac3dd7.scope - libcontainer container 286afd14fc623e63c784a154ee72c0b2cf4cf0cfed2fbf3a28471f1b53ac3dd7. Jul 6 23:21:54.940806 containerd[1520]: time="2025-07-06T23:21:54.940753395Z" level=info msg="StartContainer for \"286afd14fc623e63c784a154ee72c0b2cf4cf0cfed2fbf3a28471f1b53ac3dd7\" returns successfully" Jul 6 23:21:54.969570 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3ff82e52c9f31a1dce0e612d5eb16780f0739ae478de812f888dff881b4f85ae-rootfs.mount: Deactivated successfully. Jul 6 23:21:55.646725 kubelet[1812]: E0706 23:21:55.646660 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:56.026774 systemd-networkd[1436]: flannel.1: Link UP Jul 6 23:21:56.026780 systemd-networkd[1436]: flannel.1: Gained carrier Jul 6 23:21:56.647790 kubelet[1812]: E0706 23:21:56.647711 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:57.648232 kubelet[1812]: E0706 23:21:57.648157 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:57.878208 systemd-networkd[1436]: flannel.1: Gained IPv6LL Jul 6 23:21:58.648781 kubelet[1812]: E0706 23:21:58.648725 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:21:58.872228 kubelet[1812]: I0706 23:21:58.872165 1812 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-4cgwl" podStartSLOduration=7.010487747 podStartE2EDuration="11.872145388s" podCreationTimestamp="2025-07-06 23:21:47 +0000 UTC" firstStartedPulling="2025-07-06 23:21:49.586869505 +0000 UTC m=+2.659764851" lastFinishedPulling="2025-07-06 23:21:54.448527146 +0000 UTC m=+7.521422492" observedRunningTime="2025-07-06 23:21:55.827192728 +0000 UTC m=+8.900088074" watchObservedRunningTime="2025-07-06 23:21:58.872145388 +0000 UTC m=+11.945040694" Jul 6 23:21:58.879159 systemd[1]: Created slice kubepods-besteffort-pod61eae346_c628_492f_a5d6_85e7c02f7638.slice - libcontainer container kubepods-besteffort-pod61eae346_c628_492f_a5d6_85e7c02f7638.slice. Jul 6 23:21:58.928763 kubelet[1812]: I0706 23:21:58.928629 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gvq9j\" (UniqueName: \"kubernetes.io/projected/61eae346-c628-492f-a5d6-85e7c02f7638-kube-api-access-gvq9j\") pod \"nginx-deployment-7fcdb87857-nwnq5\" (UID: \"61eae346-c628-492f-a5d6-85e7c02f7638\") " pod="default/nginx-deployment-7fcdb87857-nwnq5" Jul 6 23:21:59.182740 containerd[1520]: time="2025-07-06T23:21:59.182642793Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-nwnq5,Uid:61eae346-c628-492f-a5d6-85e7c02f7638,Namespace:default,Attempt:0,}" Jul 6 23:21:59.217409 systemd-networkd[1436]: cni0: Link UP Jul 6 23:21:59.217420 systemd-networkd[1436]: cni0: Gained carrier Jul 6 23:21:59.219605 systemd-networkd[1436]: cni0: Lost carrier Jul 6 23:21:59.224300 systemd-networkd[1436]: veth305b53f1: Link UP Jul 6 23:21:59.226984 kernel: cni0: port 1(veth305b53f1) entered blocking state Jul 6 23:21:59.227049 kernel: cni0: port 1(veth305b53f1) entered disabled state Jul 6 23:21:59.228133 kernel: veth305b53f1: entered allmulticast mode Jul 6 23:21:59.228171 kernel: veth305b53f1: entered promiscuous mode Jul 6 23:21:59.244121 kernel: cni0: port 1(veth305b53f1) entered blocking state Jul 6 23:21:59.244265 kernel: cni0: port 1(veth305b53f1) entered forwarding state Jul 6 23:21:59.244272 systemd-networkd[1436]: veth305b53f1: Gained carrier Jul 6 23:21:59.244644 systemd-networkd[1436]: cni0: Gained carrier Jul 6 23:21:59.247809 containerd[1520]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Jul 6 23:21:59.247809 containerd[1520]: delegateAdd: netconf sent to delegate plugin: Jul 6 23:21:59.293196 containerd[1520]: {"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-07-06T23:21:59.293091570Z" level=info msg="connecting to shim ebbc8ca0e7c0798c8d6eda3c12055e5a48613e8ee8b7019e025bac4d262e470f" address="unix:///run/containerd/s/43b1e6222f5df8c282554c609907f7a23d517e47177dcb9d73c9daba06b85328" namespace=k8s.io protocol=ttrpc version=3 Jul 6 23:21:59.319079 systemd[1]: Started cri-containerd-ebbc8ca0e7c0798c8d6eda3c12055e5a48613e8ee8b7019e025bac4d262e470f.scope - libcontainer container ebbc8ca0e7c0798c8d6eda3c12055e5a48613e8ee8b7019e025bac4d262e470f. Jul 6 23:21:59.329985 systemd-resolved[1351]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 6 23:21:59.352512 containerd[1520]: time="2025-07-06T23:21:59.352460956Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-nwnq5,Uid:61eae346-c628-492f-a5d6-85e7c02f7638,Namespace:default,Attempt:0,} returns sandbox id \"ebbc8ca0e7c0798c8d6eda3c12055e5a48613e8ee8b7019e025bac4d262e470f\"" Jul 6 23:21:59.354150 containerd[1520]: time="2025-07-06T23:21:59.354113178Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 6 23:21:59.649235 kubelet[1812]: E0706 23:21:59.649106 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:00.310050 systemd-networkd[1436]: veth305b53f1: Gained IPv6LL Jul 6 23:22:00.650487 kubelet[1812]: E0706 23:22:00.650336 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:01.078158 systemd-networkd[1436]: cni0: Gained IPv6LL Jul 6 23:22:01.536226 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1003966201.mount: Deactivated successfully. Jul 6 23:22:01.651223 kubelet[1812]: E0706 23:22:01.651123 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:02.263913 containerd[1520]: time="2025-07-06T23:22:02.263440445Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:22:02.264297 containerd[1520]: time="2025-07-06T23:22:02.263939253Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=69950600" Jul 6 23:22:02.264841 containerd[1520]: time="2025-07-06T23:22:02.264809299Z" level=info msg="ImageCreate event name:\"sha256:e55a872cbf1b1d996b1d5333796fbe6ec0b825868f3ad30b387fc65697ed40dd\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:22:02.268085 containerd[1520]: time="2025-07-06T23:22:02.268015841Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:22:02.268698 containerd[1520]: time="2025-07-06T23:22:02.268553535Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e55a872cbf1b1d996b1d5333796fbe6ec0b825868f3ad30b387fc65697ed40dd\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\", size \"69950478\" in 2.914398686s" Jul 6 23:22:02.268698 containerd[1520]: time="2025-07-06T23:22:02.268601613Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e55a872cbf1b1d996b1d5333796fbe6ec0b825868f3ad30b387fc65697ed40dd\"" Jul 6 23:22:02.272818 containerd[1520]: time="2025-07-06T23:22:02.272753296Z" level=info msg="CreateContainer within sandbox \"ebbc8ca0e7c0798c8d6eda3c12055e5a48613e8ee8b7019e025bac4d262e470f\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Jul 6 23:22:02.285110 containerd[1520]: time="2025-07-06T23:22:02.285065229Z" level=info msg="Container 321cf807d2a8527819fd7b0454b110974a5239cb1e40b4c0aeb5dcb2deaa39de: CDI devices from CRI Config.CDIDevices: []" Jul 6 23:22:02.285652 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3488654709.mount: Deactivated successfully. Jul 6 23:22:02.291227 containerd[1520]: time="2025-07-06T23:22:02.291166943Z" level=info msg="CreateContainer within sandbox \"ebbc8ca0e7c0798c8d6eda3c12055e5a48613e8ee8b7019e025bac4d262e470f\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"321cf807d2a8527819fd7b0454b110974a5239cb1e40b4c0aeb5dcb2deaa39de\"" Jul 6 23:22:02.292543 containerd[1520]: time="2025-07-06T23:22:02.291680538Z" level=info msg="StartContainer for \"321cf807d2a8527819fd7b0454b110974a5239cb1e40b4c0aeb5dcb2deaa39de\"" Jul 6 23:22:02.292543 containerd[1520]: time="2025-07-06T23:22:02.292531760Z" level=info msg="connecting to shim 321cf807d2a8527819fd7b0454b110974a5239cb1e40b4c0aeb5dcb2deaa39de" address="unix:///run/containerd/s/43b1e6222f5df8c282554c609907f7a23d517e47177dcb9d73c9daba06b85328" protocol=ttrpc version=3 Jul 6 23:22:02.320103 systemd[1]: Started cri-containerd-321cf807d2a8527819fd7b0454b110974a5239cb1e40b4c0aeb5dcb2deaa39de.scope - libcontainer container 321cf807d2a8527819fd7b0454b110974a5239cb1e40b4c0aeb5dcb2deaa39de. Jul 6 23:22:02.349809 containerd[1520]: time="2025-07-06T23:22:02.349715976Z" level=info msg="StartContainer for \"321cf807d2a8527819fd7b0454b110974a5239cb1e40b4c0aeb5dcb2deaa39de\" returns successfully" Jul 6 23:22:02.651419 kubelet[1812]: E0706 23:22:02.651289 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:03.652709 kubelet[1812]: E0706 23:22:03.652658 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:04.653695 kubelet[1812]: E0706 23:22:04.653657 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:05.514377 kubelet[1812]: I0706 23:22:05.513853 1812 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-nwnq5" podStartSLOduration=4.597335619 podStartE2EDuration="7.513834153s" podCreationTimestamp="2025-07-06 23:21:58 +0000 UTC" firstStartedPulling="2025-07-06 23:21:59.353459624 +0000 UTC m=+12.426354930" lastFinishedPulling="2025-07-06 23:22:02.269958118 +0000 UTC m=+15.342853464" observedRunningTime="2025-07-06 23:22:02.854497514 +0000 UTC m=+15.927392860" watchObservedRunningTime="2025-07-06 23:22:05.513834153 +0000 UTC m=+18.586729499" Jul 6 23:22:05.522281 systemd[1]: Created slice kubepods-besteffort-podeb401cc6_e9f0_439a_bd87_3b42229b3e71.slice - libcontainer container kubepods-besteffort-podeb401cc6_e9f0_439a_bd87_3b42229b3e71.slice. Jul 6 23:22:05.564901 kubelet[1812]: I0706 23:22:05.564810 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/eb401cc6-e9f0-439a-bd87-3b42229b3e71-data\") pod \"nfs-server-provisioner-0\" (UID: \"eb401cc6-e9f0-439a-bd87-3b42229b3e71\") " pod="default/nfs-server-provisioner-0" Jul 6 23:22:05.564901 kubelet[1812]: I0706 23:22:05.564863 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-qpsgm\" (UniqueName: \"kubernetes.io/projected/eb401cc6-e9f0-439a-bd87-3b42229b3e71-kube-api-access-qpsgm\") pod \"nfs-server-provisioner-0\" (UID: \"eb401cc6-e9f0-439a-bd87-3b42229b3e71\") " pod="default/nfs-server-provisioner-0" Jul 6 23:22:05.654492 kubelet[1812]: E0706 23:22:05.654447 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:05.826329 containerd[1520]: time="2025-07-06T23:22:05.826213014Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:eb401cc6-e9f0-439a-bd87-3b42229b3e71,Namespace:default,Attempt:0,}" Jul 6 23:22:05.847613 systemd-networkd[1436]: vethe68e2599: Link UP Jul 6 23:22:05.850207 kernel: cni0: port 2(vethe68e2599) entered blocking state Jul 6 23:22:05.850304 kernel: cni0: port 2(vethe68e2599) entered disabled state Jul 6 23:22:05.850320 kernel: vethe68e2599: entered allmulticast mode Jul 6 23:22:05.850963 kernel: vethe68e2599: entered promiscuous mode Jul 6 23:22:05.858928 kernel: cni0: port 2(vethe68e2599) entered blocking state Jul 6 23:22:05.859033 kernel: cni0: port 2(vethe68e2599) entered forwarding state Jul 6 23:22:05.859107 systemd-networkd[1436]: vethe68e2599: Gained carrier Jul 6 23:22:05.866580 containerd[1520]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Jul 6 23:22:05.866580 containerd[1520]: delegateAdd: netconf sent to delegate plugin: Jul 6 23:22:05.901806 containerd[1520]: {"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-07-06T23:22:05.901759018Z" level=info msg="connecting to shim 39b2e4bd1c26680a3673c4f03e8639163d2df4bbcede92380a77bc15265f63a1" address="unix:///run/containerd/s/710b7bfe04e332d092ed99410e8cb5c275567a8c02ad118d9c72b6bdb53b7e35" namespace=k8s.io protocol=ttrpc version=3 Jul 6 23:22:05.937146 systemd[1]: Started cri-containerd-39b2e4bd1c26680a3673c4f03e8639163d2df4bbcede92380a77bc15265f63a1.scope - libcontainer container 39b2e4bd1c26680a3673c4f03e8639163d2df4bbcede92380a77bc15265f63a1. Jul 6 23:22:05.952152 systemd-resolved[1351]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 6 23:22:05.974814 containerd[1520]: time="2025-07-06T23:22:05.974674468Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:eb401cc6-e9f0-439a-bd87-3b42229b3e71,Namespace:default,Attempt:0,} returns sandbox id \"39b2e4bd1c26680a3673c4f03e8639163d2df4bbcede92380a77bc15265f63a1\"" Jul 6 23:22:05.976699 containerd[1520]: time="2025-07-06T23:22:05.976621179Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Jul 6 23:22:06.655528 kubelet[1812]: E0706 23:22:06.655490 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:07.606073 systemd-networkd[1436]: vethe68e2599: Gained IPv6LL Jul 6 23:22:07.639904 kubelet[1812]: E0706 23:22:07.639825 1812 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:07.656303 kubelet[1812]: E0706 23:22:07.656264 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:07.757903 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2424211688.mount: Deactivated successfully. Jul 6 23:22:08.656452 kubelet[1812]: E0706 23:22:08.656382 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:09.127923 containerd[1520]: time="2025-07-06T23:22:09.127759195Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:22:09.128554 containerd[1520]: time="2025-07-06T23:22:09.128509859Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=87373625" Jul 6 23:22:09.129379 containerd[1520]: time="2025-07-06T23:22:09.129337018Z" level=info msg="ImageCreate event name:\"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:22:09.131577 containerd[1520]: time="2025-07-06T23:22:09.131543548Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:22:09.132518 containerd[1520]: time="2025-07-06T23:22:09.132483508Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"87371201\" in 3.155622146s" Jul 6 23:22:09.132717 containerd[1520]: time="2025-07-06T23:22:09.132602627Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:5a42a519e0a8cf95c3c5f18f767c58c8c8b072aaea0a26e5e47a6f206c7df685\"" Jul 6 23:22:09.135168 containerd[1520]: time="2025-07-06T23:22:09.135128888Z" level=info msg="CreateContainer within sandbox \"39b2e4bd1c26680a3673c4f03e8639163d2df4bbcede92380a77bc15265f63a1\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Jul 6 23:22:09.143110 containerd[1520]: time="2025-07-06T23:22:09.143054753Z" level=info msg="Container 9bbb3df9c200190ac8f656e6f5b447ada5584d1388f4ea8e1686d54074c9c1c9: CDI devices from CRI Config.CDIDevices: []" Jul 6 23:22:09.154303 containerd[1520]: time="2025-07-06T23:22:09.154177211Z" level=info msg="CreateContainer within sandbox \"39b2e4bd1c26680a3673c4f03e8639163d2df4bbcede92380a77bc15265f63a1\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"9bbb3df9c200190ac8f656e6f5b447ada5584d1388f4ea8e1686d54074c9c1c9\"" Jul 6 23:22:09.155431 containerd[1520]: time="2025-07-06T23:22:09.155319663Z" level=info msg="StartContainer for \"9bbb3df9c200190ac8f656e6f5b447ada5584d1388f4ea8e1686d54074c9c1c9\"" Jul 6 23:22:09.156738 containerd[1520]: time="2025-07-06T23:22:09.156705991Z" level=info msg="connecting to shim 9bbb3df9c200190ac8f656e6f5b447ada5584d1388f4ea8e1686d54074c9c1c9" address="unix:///run/containerd/s/710b7bfe04e332d092ed99410e8cb5c275567a8c02ad118d9c72b6bdb53b7e35" protocol=ttrpc version=3 Jul 6 23:22:09.177122 systemd[1]: Started cri-containerd-9bbb3df9c200190ac8f656e6f5b447ada5584d1388f4ea8e1686d54074c9c1c9.scope - libcontainer container 9bbb3df9c200190ac8f656e6f5b447ada5584d1388f4ea8e1686d54074c9c1c9. Jul 6 23:22:09.206343 containerd[1520]: time="2025-07-06T23:22:09.206296848Z" level=info msg="StartContainer for \"9bbb3df9c200190ac8f656e6f5b447ada5584d1388f4ea8e1686d54074c9c1c9\" returns successfully" Jul 6 23:22:09.656870 kubelet[1812]: E0706 23:22:09.656823 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:10.657372 kubelet[1812]: E0706 23:22:10.657312 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:11.658537 kubelet[1812]: E0706 23:22:11.658467 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:12.659452 kubelet[1812]: E0706 23:22:12.659406 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:13.660537 kubelet[1812]: E0706 23:22:13.660477 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:14.661660 kubelet[1812]: E0706 23:22:14.661604 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:15.662543 kubelet[1812]: E0706 23:22:15.662494 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:16.663517 kubelet[1812]: E0706 23:22:16.663455 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:17.663856 kubelet[1812]: E0706 23:22:17.663785 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:18.664275 kubelet[1812]: E0706 23:22:18.664224 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:19.133646 kubelet[1812]: I0706 23:22:19.133588 1812 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=10.976506736 podStartE2EDuration="14.133568945s" podCreationTimestamp="2025-07-06 23:22:05 +0000 UTC" firstStartedPulling="2025-07-06 23:22:05.976318714 +0000 UTC m=+19.049214060" lastFinishedPulling="2025-07-06 23:22:09.133380963 +0000 UTC m=+22.206276269" observedRunningTime="2025-07-06 23:22:09.856146865 +0000 UTC m=+22.929042211" watchObservedRunningTime="2025-07-06 23:22:19.133568945 +0000 UTC m=+32.206464251" Jul 6 23:22:19.140291 systemd[1]: Created slice kubepods-besteffort-pod37271138_a7f2_4ee5_bccd_e10ac88679ba.slice - libcontainer container kubepods-besteffort-pod37271138_a7f2_4ee5_bccd_e10ac88679ba.slice. Jul 6 23:22:19.250254 kubelet[1812]: I0706 23:22:19.250176 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-9d227e21-11b0-4169-926b-6c09067e8361\" (UniqueName: \"kubernetes.io/nfs/37271138-a7f2-4ee5-bccd-e10ac88679ba-pvc-9d227e21-11b0-4169-926b-6c09067e8361\") pod \"test-pod-1\" (UID: \"37271138-a7f2-4ee5-bccd-e10ac88679ba\") " pod="default/test-pod-1" Jul 6 23:22:19.250254 kubelet[1812]: I0706 23:22:19.250228 1812 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fkrzg\" (UniqueName: \"kubernetes.io/projected/37271138-a7f2-4ee5-bccd-e10ac88679ba-kube-api-access-fkrzg\") pod \"test-pod-1\" (UID: \"37271138-a7f2-4ee5-bccd-e10ac88679ba\") " pod="default/test-pod-1" Jul 6 23:22:19.417928 kernel: netfs: FS-Cache loaded Jul 6 23:22:19.468310 kernel: RPC: Registered named UNIX socket transport module. Jul 6 23:22:19.468425 kernel: RPC: Registered udp transport module. Jul 6 23:22:19.468442 kernel: RPC: Registered tcp transport module. Jul 6 23:22:19.468992 kernel: RPC: Registered tcp-with-tls transport module. Jul 6 23:22:19.469939 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jul 6 23:22:19.661936 kernel: NFS: Registering the id_resolver key type Jul 6 23:22:19.662048 kernel: Key type id_resolver registered Jul 6 23:22:19.662092 kernel: Key type id_legacy registered Jul 6 23:22:19.664390 kubelet[1812]: E0706 23:22:19.664348 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:19.694513 nfsidmap[2823]: 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 Jul 6 23:22:19.701263 nfsidmap[2823]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 6 23:22:19.705126 nfsidmap[2824]: 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 Jul 6 23:22:19.705299 nfsidmap[2824]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Jul 6 23:22:19.713814 nfsrahead[2827]: setting /var/lib/kubelet/pods/37271138-a7f2-4ee5-bccd-e10ac88679ba/volumes/kubernetes.io~nfs/pvc-9d227e21-11b0-4169-926b-6c09067e8361 readahead to 128 Jul 6 23:22:19.755306 containerd[1520]: time="2025-07-06T23:22:19.755203949Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:37271138-a7f2-4ee5-bccd-e10ac88679ba,Namespace:default,Attempt:0,}" Jul 6 23:22:19.765674 systemd-networkd[1436]: vethc6e63d3e: Link UP Jul 6 23:22:19.767169 kernel: cni0: port 3(vethc6e63d3e) entered blocking state Jul 6 23:22:19.767285 kernel: cni0: port 3(vethc6e63d3e) entered disabled state Jul 6 23:22:19.767306 kernel: vethc6e63d3e: entered allmulticast mode Jul 6 23:22:19.768157 kernel: vethc6e63d3e: entered promiscuous mode Jul 6 23:22:19.774923 kernel: cni0: port 3(vethc6e63d3e) entered blocking state Jul 6 23:22:19.775036 kernel: cni0: port 3(vethc6e63d3e) entered forwarding state Jul 6 23:22:19.774908 systemd-networkd[1436]: vethc6e63d3e: Gained carrier Jul 6 23:22:19.776548 containerd[1520]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0x40000a68e8), "name":"cbr0", "type":"bridge"} Jul 6 23:22:19.776548 containerd[1520]: delegateAdd: netconf sent to delegate plugin: Jul 6 23:22:19.802967 containerd[1520]: {"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-07-06T23:22:19.802921403Z" level=info msg="connecting to shim 85298aa6f1e6acdeec5a7942a3c7ed965e30ff708554e40322c780e4c421197e" address="unix:///run/containerd/s/8025414c69cfa0c65d8561c3585e69207d7fbda8a1cac913dca3a1d55f4ae639" namespace=k8s.io protocol=ttrpc version=3 Jul 6 23:22:19.826079 systemd[1]: Started cri-containerd-85298aa6f1e6acdeec5a7942a3c7ed965e30ff708554e40322c780e4c421197e.scope - libcontainer container 85298aa6f1e6acdeec5a7942a3c7ed965e30ff708554e40322c780e4c421197e. Jul 6 23:22:19.838762 systemd-resolved[1351]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 6 23:22:19.858443 containerd[1520]: time="2025-07-06T23:22:19.858398458Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:37271138-a7f2-4ee5-bccd-e10ac88679ba,Namespace:default,Attempt:0,} returns sandbox id \"85298aa6f1e6acdeec5a7942a3c7ed965e30ff708554e40322c780e4c421197e\"" Jul 6 23:22:19.859822 containerd[1520]: time="2025-07-06T23:22:19.859793909Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Jul 6 23:22:20.093435 containerd[1520]: time="2025-07-06T23:22:20.093291866Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Jul 6 23:22:20.094449 containerd[1520]: time="2025-07-06T23:22:20.094403644Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Jul 6 23:22:20.097184 containerd[1520]: time="2025-07-06T23:22:20.097127870Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:e55a872cbf1b1d996b1d5333796fbe6ec0b825868f3ad30b387fc65697ed40dd\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:9a18b8b1845bf88a3388cde4ec626461965a717ac641198120979e75438b9693\", size \"69950478\" in 237.297203ms" Jul 6 23:22:20.097184 containerd[1520]: time="2025-07-06T23:22:20.097174669Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:e55a872cbf1b1d996b1d5333796fbe6ec0b825868f3ad30b387fc65697ed40dd\"" Jul 6 23:22:20.100998 containerd[1520]: time="2025-07-06T23:22:20.100877837Z" level=info msg="CreateContainer within sandbox \"85298aa6f1e6acdeec5a7942a3c7ed965e30ff708554e40322c780e4c421197e\" for container &ContainerMetadata{Name:test,Attempt:0,}" Jul 6 23:22:20.108153 containerd[1520]: time="2025-07-06T23:22:20.108120535Z" level=info msg="Container 9ace93f28e6f09f41a76a9027b815ae4409fdc403cead62e13b8485fd5a7a932: CDI devices from CRI Config.CDIDevices: []" Jul 6 23:22:20.114637 containerd[1520]: time="2025-07-06T23:22:20.114589128Z" level=info msg="CreateContainer within sandbox \"85298aa6f1e6acdeec5a7942a3c7ed965e30ff708554e40322c780e4c421197e\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"9ace93f28e6f09f41a76a9027b815ae4409fdc403cead62e13b8485fd5a7a932\"" Jul 6 23:22:20.115916 containerd[1520]: time="2025-07-06T23:22:20.115170037Z" level=info msg="StartContainer for \"9ace93f28e6f09f41a76a9027b815ae4409fdc403cead62e13b8485fd5a7a932\"" Jul 6 23:22:20.116222 containerd[1520]: time="2025-07-06T23:22:20.116192057Z" level=info msg="connecting to shim 9ace93f28e6f09f41a76a9027b815ae4409fdc403cead62e13b8485fd5a7a932" address="unix:///run/containerd/s/8025414c69cfa0c65d8561c3585e69207d7fbda8a1cac913dca3a1d55f4ae639" protocol=ttrpc version=3 Jul 6 23:22:20.135096 systemd[1]: Started cri-containerd-9ace93f28e6f09f41a76a9027b815ae4409fdc403cead62e13b8485fd5a7a932.scope - libcontainer container 9ace93f28e6f09f41a76a9027b815ae4409fdc403cead62e13b8485fd5a7a932. Jul 6 23:22:20.165934 containerd[1520]: time="2025-07-06T23:22:20.165864644Z" level=info msg="StartContainer for \"9ace93f28e6f09f41a76a9027b815ae4409fdc403cead62e13b8485fd5a7a932\" returns successfully" Jul 6 23:22:20.665004 kubelet[1812]: E0706 23:22:20.664959 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:21.430047 systemd-networkd[1436]: vethc6e63d3e: Gained IPv6LL Jul 6 23:22:21.665860 kubelet[1812]: E0706 23:22:21.665474 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Jul 6 23:22:22.666075 kubelet[1812]: E0706 23:22:22.666017 1812 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"