Sep 12 17:45:11.879639 kernel: Linux version 6.12.47-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Fri Sep 12 15:34:39 -00 2025 Sep 12 17:45:11.879666 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 verity.usrhash=271a44cc8ea1639cfb6fdf777202a5f025fda0b3ce9b293cc4e0e7047aecb858 Sep 12 17:45:11.879677 kernel: BIOS-provided physical RAM map: Sep 12 17:45:11.879684 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000002ffff] usable Sep 12 17:45:11.879690 kernel: BIOS-e820: [mem 0x0000000000030000-0x000000000004ffff] reserved Sep 12 17:45:11.879697 kernel: BIOS-e820: [mem 0x0000000000050000-0x000000000009efff] usable Sep 12 17:45:11.879704 kernel: BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] reserved Sep 12 17:45:11.879711 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009b8ecfff] usable Sep 12 17:45:11.879717 kernel: BIOS-e820: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Sep 12 17:45:11.879724 kernel: BIOS-e820: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Sep 12 17:45:11.879731 kernel: BIOS-e820: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Sep 12 17:45:11.879739 kernel: BIOS-e820: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Sep 12 17:45:11.879745 kernel: BIOS-e820: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Sep 12 17:45:11.879752 kernel: BIOS-e820: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Sep 12 17:45:11.879760 kernel: BIOS-e820: [mem 0x000000009bfb7000-0x000000009bffffff] usable Sep 12 17:45:11.879767 kernel: BIOS-e820: [mem 0x000000009c000000-0x000000009cffffff] reserved Sep 12 17:45:11.879776 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 12 17:45:11.879783 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 12 17:45:11.879790 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 12 17:45:11.879797 kernel: NX (Execute Disable) protection: active Sep 12 17:45:11.879804 kernel: APIC: Static calls initialized Sep 12 17:45:11.879811 kernel: e820: update [mem 0x9a13f018-0x9a148c57] usable ==> usable Sep 12 17:45:11.879818 kernel: e820: update [mem 0x9a102018-0x9a13ee57] usable ==> usable Sep 12 17:45:11.879825 kernel: extended physical RAM map: Sep 12 17:45:11.879832 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000002ffff] usable Sep 12 17:45:11.879839 kernel: reserve setup_data: [mem 0x0000000000030000-0x000000000004ffff] reserved Sep 12 17:45:11.879846 kernel: reserve setup_data: [mem 0x0000000000050000-0x000000000009efff] usable Sep 12 17:45:11.879856 kernel: reserve setup_data: [mem 0x000000000009f000-0x000000000009ffff] reserved Sep 12 17:45:11.879863 kernel: reserve setup_data: [mem 0x0000000000100000-0x000000009a102017] usable Sep 12 17:45:11.879870 kernel: reserve setup_data: [mem 0x000000009a102018-0x000000009a13ee57] usable Sep 12 17:45:11.879876 kernel: reserve setup_data: [mem 0x000000009a13ee58-0x000000009a13f017] usable Sep 12 17:45:11.879883 kernel: reserve setup_data: [mem 0x000000009a13f018-0x000000009a148c57] usable Sep 12 17:45:11.879890 kernel: reserve setup_data: [mem 0x000000009a148c58-0x000000009b8ecfff] usable Sep 12 17:45:11.879897 kernel: reserve setup_data: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Sep 12 17:45:11.879904 kernel: reserve setup_data: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Sep 12 17:45:11.879911 kernel: reserve setup_data: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Sep 12 17:45:11.879918 kernel: reserve setup_data: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Sep 12 17:45:11.879925 kernel: reserve setup_data: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Sep 12 17:45:11.879934 kernel: reserve setup_data: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Sep 12 17:45:11.879941 kernel: reserve setup_data: [mem 0x000000009bfb7000-0x000000009bffffff] usable Sep 12 17:45:11.879951 kernel: reserve setup_data: [mem 0x000000009c000000-0x000000009cffffff] reserved Sep 12 17:45:11.879958 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Sep 12 17:45:11.879979 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Sep 12 17:45:11.879987 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Sep 12 17:45:11.879997 kernel: efi: EFI v2.7 by EDK II Sep 12 17:45:11.880004 kernel: efi: SMBIOS=0x9b9d5000 ACPI=0x9bb7e000 ACPI 2.0=0x9bb7e014 MEMATTR=0x9a1af018 RNG=0x9bb73018 Sep 12 17:45:11.880011 kernel: random: crng init done Sep 12 17:45:11.880019 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Sep 12 17:45:11.880026 kernel: secureboot: Secure boot enabled Sep 12 17:45:11.880033 kernel: SMBIOS 2.8 present. Sep 12 17:45:11.880041 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Sep 12 17:45:11.880048 kernel: DMI: Memory slots populated: 1/1 Sep 12 17:45:11.880055 kernel: Hypervisor detected: KVM Sep 12 17:45:11.880063 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 12 17:45:11.880070 kernel: kvm-clock: using sched offset of 5371698854 cycles Sep 12 17:45:11.880081 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 12 17:45:11.880091 kernel: tsc: Detected 2794.750 MHz processor Sep 12 17:45:11.880113 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 12 17:45:11.880122 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 12 17:45:11.880150 kernel: last_pfn = 0x9c000 max_arch_pfn = 0x400000000 Sep 12 17:45:11.880161 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Sep 12 17:45:11.880171 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 12 17:45:11.880180 kernel: Using GB pages for direct mapping Sep 12 17:45:11.880189 kernel: ACPI: Early table checksum verification disabled Sep 12 17:45:11.880202 kernel: ACPI: RSDP 0x000000009BB7E014 000024 (v02 BOCHS ) Sep 12 17:45:11.880211 kernel: ACPI: XSDT 0x000000009BB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Sep 12 17:45:11.880221 kernel: ACPI: FACP 0x000000009BB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:45:11.880229 kernel: ACPI: DSDT 0x000000009BB7A000 002237 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:45:11.880237 kernel: ACPI: FACS 0x000000009BBDD000 000040 Sep 12 17:45:11.880244 kernel: ACPI: APIC 0x000000009BB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:45:11.880251 kernel: ACPI: HPET 0x000000009BB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:45:11.880259 kernel: ACPI: MCFG 0x000000009BB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:45:11.880270 kernel: ACPI: WAET 0x000000009BB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Sep 12 17:45:11.880281 kernel: ACPI: BGRT 0x000000009BB74000 000038 (v01 INTEL EDK2 00000002 01000013) Sep 12 17:45:11.880288 kernel: ACPI: Reserving FACP table memory at [mem 0x9bb79000-0x9bb790f3] Sep 12 17:45:11.880296 kernel: ACPI: Reserving DSDT table memory at [mem 0x9bb7a000-0x9bb7c236] Sep 12 17:45:11.880303 kernel: ACPI: Reserving FACS table memory at [mem 0x9bbdd000-0x9bbdd03f] Sep 12 17:45:11.880310 kernel: ACPI: Reserving APIC table memory at [mem 0x9bb78000-0x9bb7808f] Sep 12 17:45:11.880318 kernel: ACPI: Reserving HPET table memory at [mem 0x9bb77000-0x9bb77037] Sep 12 17:45:11.880325 kernel: ACPI: Reserving MCFG table memory at [mem 0x9bb76000-0x9bb7603b] Sep 12 17:45:11.880332 kernel: ACPI: Reserving WAET table memory at [mem 0x9bb75000-0x9bb75027] Sep 12 17:45:11.880340 kernel: ACPI: Reserving BGRT table memory at [mem 0x9bb74000-0x9bb74037] Sep 12 17:45:11.880349 kernel: No NUMA configuration found Sep 12 17:45:11.880356 kernel: Faking a node at [mem 0x0000000000000000-0x000000009bffffff] Sep 12 17:45:11.880364 kernel: NODE_DATA(0) allocated [mem 0x9bf57dc0-0x9bf5efff] Sep 12 17:45:11.880371 kernel: Zone ranges: Sep 12 17:45:11.880379 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 12 17:45:11.880386 kernel: DMA32 [mem 0x0000000001000000-0x000000009bffffff] Sep 12 17:45:11.880393 kernel: Normal empty Sep 12 17:45:11.880400 kernel: Device empty Sep 12 17:45:11.880408 kernel: Movable zone start for each node Sep 12 17:45:11.880418 kernel: Early memory node ranges Sep 12 17:45:11.880425 kernel: node 0: [mem 0x0000000000001000-0x000000000002ffff] Sep 12 17:45:11.880433 kernel: node 0: [mem 0x0000000000050000-0x000000000009efff] Sep 12 17:45:11.880440 kernel: node 0: [mem 0x0000000000100000-0x000000009b8ecfff] Sep 12 17:45:11.880447 kernel: node 0: [mem 0x000000009bbff000-0x000000009bfb0fff] Sep 12 17:45:11.880455 kernel: node 0: [mem 0x000000009bfb7000-0x000000009bffffff] Sep 12 17:45:11.880462 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009bffffff] Sep 12 17:45:11.880469 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 12 17:45:11.880477 kernel: On node 0, zone DMA: 32 pages in unavailable ranges Sep 12 17:45:11.880484 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Sep 12 17:45:11.880494 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Sep 12 17:45:11.880501 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Sep 12 17:45:11.880509 kernel: On node 0, zone DMA32: 16384 pages in unavailable ranges Sep 12 17:45:11.880516 kernel: ACPI: PM-Timer IO Port: 0x608 Sep 12 17:45:11.880523 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 12 17:45:11.880531 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 12 17:45:11.880538 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Sep 12 17:45:11.880545 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 12 17:45:11.880561 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 12 17:45:11.880571 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 12 17:45:11.880578 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 12 17:45:11.880585 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 12 17:45:11.880594 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Sep 12 17:45:11.880601 kernel: TSC deadline timer available Sep 12 17:45:11.880608 kernel: CPU topo: Max. logical packages: 1 Sep 12 17:45:11.880615 kernel: CPU topo: Max. logical dies: 1 Sep 12 17:45:11.880623 kernel: CPU topo: Max. dies per package: 1 Sep 12 17:45:11.880639 kernel: CPU topo: Max. threads per core: 1 Sep 12 17:45:11.880646 kernel: CPU topo: Num. cores per package: 4 Sep 12 17:45:11.880654 kernel: CPU topo: Num. threads per package: 4 Sep 12 17:45:11.880661 kernel: CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs Sep 12 17:45:11.880671 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Sep 12 17:45:11.880678 kernel: kvm-guest: KVM setup pv remote TLB flush Sep 12 17:45:11.880686 kernel: kvm-guest: setup PV sched yield Sep 12 17:45:11.880694 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Sep 12 17:45:11.880701 kernel: Booting paravirtualized kernel on KVM Sep 12 17:45:11.880711 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 12 17:45:11.880719 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Sep 12 17:45:11.880727 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u524288 Sep 12 17:45:11.880734 kernel: pcpu-alloc: s207832 r8192 d29736 u524288 alloc=1*2097152 Sep 12 17:45:11.880742 kernel: pcpu-alloc: [0] 0 1 2 3 Sep 12 17:45:11.880749 kernel: kvm-guest: PV spinlocks enabled Sep 12 17:45:11.880757 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 12 17:45:11.880766 kernel: Kernel command line: rootflags=rw mount.usrflags=ro 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 verity.usrhash=271a44cc8ea1639cfb6fdf777202a5f025fda0b3ce9b293cc4e0e7047aecb858 Sep 12 17:45:11.880776 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 12 17:45:11.880784 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 12 17:45:11.880791 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 12 17:45:11.880799 kernel: Fallback order for Node 0: 0 Sep 12 17:45:11.880807 kernel: Built 1 zonelists, mobility grouping on. Total pages: 638054 Sep 12 17:45:11.880814 kernel: Policy zone: DMA32 Sep 12 17:45:11.880822 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 12 17:45:11.880829 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Sep 12 17:45:11.880837 kernel: ftrace: allocating 40125 entries in 157 pages Sep 12 17:45:11.880846 kernel: ftrace: allocated 157 pages with 5 groups Sep 12 17:45:11.880854 kernel: Dynamic Preempt: voluntary Sep 12 17:45:11.880862 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 12 17:45:11.880870 kernel: rcu: RCU event tracing is enabled. Sep 12 17:45:11.880878 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Sep 12 17:45:11.880886 kernel: Trampoline variant of Tasks RCU enabled. Sep 12 17:45:11.880893 kernel: Rude variant of Tasks RCU enabled. Sep 12 17:45:11.880901 kernel: Tracing variant of Tasks RCU enabled. Sep 12 17:45:11.880909 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 12 17:45:11.880919 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Sep 12 17:45:11.880926 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 17:45:11.880934 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 17:45:11.880942 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Sep 12 17:45:11.880950 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Sep 12 17:45:11.880957 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 12 17:45:11.880978 kernel: Console: colour dummy device 80x25 Sep 12 17:45:11.880985 kernel: printk: legacy console [ttyS0] enabled Sep 12 17:45:11.880993 kernel: ACPI: Core revision 20240827 Sep 12 17:45:11.881004 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Sep 12 17:45:11.881012 kernel: APIC: Switch to symmetric I/O mode setup Sep 12 17:45:11.881019 kernel: x2apic enabled Sep 12 17:45:11.881027 kernel: APIC: Switched APIC routing to: physical x2apic Sep 12 17:45:11.881035 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Sep 12 17:45:11.881043 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Sep 12 17:45:11.881050 kernel: kvm-guest: setup PV IPIs Sep 12 17:45:11.881058 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Sep 12 17:45:11.881073 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 12 17:45:11.881086 kernel: Calibrating delay loop (skipped) preset value.. 5589.50 BogoMIPS (lpj=2794750) Sep 12 17:45:11.881102 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Sep 12 17:45:11.881110 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Sep 12 17:45:11.881118 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Sep 12 17:45:11.881126 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 12 17:45:11.881133 kernel: Spectre V2 : Mitigation: Retpolines Sep 12 17:45:11.881141 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 12 17:45:11.881149 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Sep 12 17:45:11.881161 kernel: active return thunk: retbleed_return_thunk Sep 12 17:45:11.881171 kernel: RETBleed: Mitigation: untrained return thunk Sep 12 17:45:11.881179 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 12 17:45:11.881186 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 12 17:45:11.881194 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Sep 12 17:45:11.881203 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Sep 12 17:45:11.881210 kernel: active return thunk: srso_return_thunk Sep 12 17:45:11.881218 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Sep 12 17:45:11.881226 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 12 17:45:11.881236 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 12 17:45:11.881244 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 12 17:45:11.881251 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 12 17:45:11.881259 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Sep 12 17:45:11.881268 kernel: Freeing SMP alternatives memory: 32K Sep 12 17:45:11.881277 kernel: pid_max: default: 32768 minimum: 301 Sep 12 17:45:11.881285 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 12 17:45:11.881293 kernel: landlock: Up and running. Sep 12 17:45:11.881302 kernel: SELinux: Initializing. Sep 12 17:45:11.881313 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 12 17:45:11.881323 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 12 17:45:11.881331 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Sep 12 17:45:11.881338 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Sep 12 17:45:11.881346 kernel: ... version: 0 Sep 12 17:45:11.881354 kernel: ... bit width: 48 Sep 12 17:45:11.881361 kernel: ... generic registers: 6 Sep 12 17:45:11.881369 kernel: ... value mask: 0000ffffffffffff Sep 12 17:45:11.881377 kernel: ... max period: 00007fffffffffff Sep 12 17:45:11.881386 kernel: ... fixed-purpose events: 0 Sep 12 17:45:11.881394 kernel: ... event mask: 000000000000003f Sep 12 17:45:11.881401 kernel: signal: max sigframe size: 1776 Sep 12 17:45:11.881409 kernel: rcu: Hierarchical SRCU implementation. Sep 12 17:45:11.881417 kernel: rcu: Max phase no-delay instances is 400. Sep 12 17:45:11.881425 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 12 17:45:11.881432 kernel: smp: Bringing up secondary CPUs ... Sep 12 17:45:11.881440 kernel: smpboot: x86: Booting SMP configuration: Sep 12 17:45:11.881448 kernel: .... node #0, CPUs: #1 #2 #3 Sep 12 17:45:11.881457 kernel: smp: Brought up 1 node, 4 CPUs Sep 12 17:45:11.881465 kernel: smpboot: Total of 4 processors activated (22358.00 BogoMIPS) Sep 12 17:45:11.881473 kernel: Memory: 2409220K/2552216K available (14336K kernel code, 2432K rwdata, 9960K rodata, 54040K init, 2924K bss, 137068K reserved, 0K cma-reserved) Sep 12 17:45:11.881481 kernel: devtmpfs: initialized Sep 12 17:45:11.881488 kernel: x86/mm: Memory block size: 128MB Sep 12 17:45:11.881496 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bb7f000-0x9bbfefff] (524288 bytes) Sep 12 17:45:11.881504 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bfb5000-0x9bfb6fff] (8192 bytes) Sep 12 17:45:11.881512 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 12 17:45:11.881520 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Sep 12 17:45:11.881529 kernel: pinctrl core: initialized pinctrl subsystem Sep 12 17:45:11.881537 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 12 17:45:11.881545 kernel: audit: initializing netlink subsys (disabled) Sep 12 17:45:11.881561 kernel: audit: type=2000 audit(1757699108.871:1): state=initialized audit_enabled=0 res=1 Sep 12 17:45:11.881569 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 12 17:45:11.881576 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 12 17:45:11.881584 kernel: cpuidle: using governor menu Sep 12 17:45:11.881592 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 12 17:45:11.881600 kernel: dca service started, version 1.12.1 Sep 12 17:45:11.881609 kernel: PCI: ECAM [mem 0xe0000000-0xefffffff] (base 0xe0000000) for domain 0000 [bus 00-ff] Sep 12 17:45:11.881617 kernel: PCI: Using configuration type 1 for base access Sep 12 17:45:11.881625 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 12 17:45:11.881633 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 12 17:45:11.881641 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 12 17:45:11.881648 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 12 17:45:11.881656 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 12 17:45:11.881663 kernel: ACPI: Added _OSI(Module Device) Sep 12 17:45:11.881671 kernel: ACPI: Added _OSI(Processor Device) Sep 12 17:45:11.881681 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 12 17:45:11.881689 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 12 17:45:11.881696 kernel: ACPI: Interpreter enabled Sep 12 17:45:11.881704 kernel: ACPI: PM: (supports S0 S5) Sep 12 17:45:11.881711 kernel: ACPI: Using IOAPIC for interrupt routing Sep 12 17:45:11.881719 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 12 17:45:11.881727 kernel: PCI: Using E820 reservations for host bridge windows Sep 12 17:45:11.881734 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Sep 12 17:45:11.881742 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 12 17:45:11.881940 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Sep 12 17:45:11.882080 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Sep 12 17:45:11.882198 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Sep 12 17:45:11.882209 kernel: PCI host bridge to bus 0000:00 Sep 12 17:45:11.882339 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 12 17:45:11.882447 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 12 17:45:11.882567 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 12 17:45:11.882674 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Sep 12 17:45:11.882779 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Sep 12 17:45:11.882885 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Sep 12 17:45:11.883009 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 12 17:45:11.883224 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 conventional PCI endpoint Sep 12 17:45:11.883358 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint Sep 12 17:45:11.883480 kernel: pci 0000:00:01.0: BAR 0 [mem 0xc0000000-0xc0ffffff pref] Sep 12 17:45:11.883606 kernel: pci 0000:00:01.0: BAR 2 [mem 0xc1044000-0xc1044fff] Sep 12 17:45:11.883722 kernel: pci 0000:00:01.0: ROM [mem 0xffff0000-0xffffffff pref] Sep 12 17:45:11.883838 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Sep 12 17:45:11.883983 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 12 17:45:11.884112 kernel: pci 0000:00:02.0: BAR 0 [io 0x6100-0x611f] Sep 12 17:45:11.884258 kernel: pci 0000:00:02.0: BAR 1 [mem 0xc1043000-0xc1043fff] Sep 12 17:45:11.884373 kernel: pci 0000:00:02.0: BAR 4 [mem 0x380000000000-0x380000003fff 64bit pref] Sep 12 17:45:11.884525 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 conventional PCI endpoint Sep 12 17:45:11.884653 kernel: pci 0000:00:03.0: BAR 0 [io 0x6000-0x607f] Sep 12 17:45:11.884770 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc1042000-0xc1042fff] Sep 12 17:45:11.884887 kernel: pci 0000:00:03.0: BAR 4 [mem 0x380000004000-0x380000007fff 64bit pref] Sep 12 17:45:11.885043 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 12 17:45:11.885187 kernel: pci 0000:00:04.0: BAR 0 [io 0x60e0-0x60ff] Sep 12 17:45:11.885332 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc1041000-0xc1041fff] Sep 12 17:45:11.885461 kernel: pci 0000:00:04.0: BAR 4 [mem 0x380000008000-0x38000000bfff 64bit pref] Sep 12 17:45:11.885587 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref] Sep 12 17:45:11.885719 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 conventional PCI endpoint Sep 12 17:45:11.885837 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Sep 12 17:45:11.885978 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 conventional PCI endpoint Sep 12 17:45:11.886106 kernel: pci 0000:00:1f.2: BAR 4 [io 0x60c0-0x60df] Sep 12 17:45:11.886248 kernel: pci 0000:00:1f.2: BAR 5 [mem 0xc1040000-0xc1040fff] Sep 12 17:45:11.886375 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 conventional PCI endpoint Sep 12 17:45:11.886491 kernel: pci 0000:00:1f.3: BAR 4 [io 0x6080-0x60bf] Sep 12 17:45:11.886501 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 12 17:45:11.886509 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 12 17:45:11.886517 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 12 17:45:11.886528 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 12 17:45:11.886536 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Sep 12 17:45:11.886543 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Sep 12 17:45:11.886560 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Sep 12 17:45:11.886568 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Sep 12 17:45:11.886575 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Sep 12 17:45:11.886584 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Sep 12 17:45:11.886592 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Sep 12 17:45:11.886599 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Sep 12 17:45:11.886609 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Sep 12 17:45:11.886617 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Sep 12 17:45:11.886624 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Sep 12 17:45:11.886632 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Sep 12 17:45:11.886640 kernel: iommu: Default domain type: Translated Sep 12 17:45:11.886648 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 12 17:45:11.886656 kernel: efivars: Registered efivars operations Sep 12 17:45:11.886664 kernel: PCI: Using ACPI for IRQ routing Sep 12 17:45:11.886671 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 12 17:45:11.886681 kernel: e820: reserve RAM buffer [mem 0x0009f000-0x0009ffff] Sep 12 17:45:11.886689 kernel: e820: reserve RAM buffer [mem 0x9a102018-0x9bffffff] Sep 12 17:45:11.886696 kernel: e820: reserve RAM buffer [mem 0x9a13f018-0x9bffffff] Sep 12 17:45:11.886704 kernel: e820: reserve RAM buffer [mem 0x9b8ed000-0x9bffffff] Sep 12 17:45:11.886712 kernel: e820: reserve RAM buffer [mem 0x9bfb1000-0x9bffffff] Sep 12 17:45:11.886829 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Sep 12 17:45:11.886944 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Sep 12 17:45:11.887079 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Sep 12 17:45:11.887090 kernel: vgaarb: loaded Sep 12 17:45:11.887101 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Sep 12 17:45:11.887109 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Sep 12 17:45:11.887117 kernel: clocksource: Switched to clocksource kvm-clock Sep 12 17:45:11.887125 kernel: VFS: Disk quotas dquot_6.6.0 Sep 12 17:45:11.887133 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 12 17:45:11.887140 kernel: pnp: PnP ACPI init Sep 12 17:45:11.887289 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Sep 12 17:45:11.887303 kernel: pnp: PnP ACPI: found 6 devices Sep 12 17:45:11.887317 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 12 17:45:11.887327 kernel: NET: Registered PF_INET protocol family Sep 12 17:45:11.887337 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 12 17:45:11.887347 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 12 17:45:11.887357 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 12 17:45:11.887366 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 12 17:45:11.887376 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 12 17:45:11.887386 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 12 17:45:11.887396 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 12 17:45:11.887406 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 12 17:45:11.887414 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 12 17:45:11.887422 kernel: NET: Registered PF_XDP protocol family Sep 12 17:45:11.887541 kernel: pci 0000:00:04.0: ROM [mem 0xfffc0000-0xffffffff pref]: can't claim; no compatible bridge window Sep 12 17:45:11.887669 kernel: pci 0000:00:04.0: ROM [mem 0x9d000000-0x9d03ffff pref]: assigned Sep 12 17:45:11.887778 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 12 17:45:11.887884 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 12 17:45:11.888007 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 12 17:45:11.888125 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Sep 12 17:45:11.888247 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Sep 12 17:45:11.888355 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Sep 12 17:45:11.888365 kernel: PCI: CLS 0 bytes, default 64 Sep 12 17:45:11.888373 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2848e100549, max_idle_ns: 440795215505 ns Sep 12 17:45:11.888381 kernel: Initialise system trusted keyrings Sep 12 17:45:11.888389 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 12 17:45:11.888397 kernel: Key type asymmetric registered Sep 12 17:45:11.888408 kernel: Asymmetric key parser 'x509' registered Sep 12 17:45:11.888431 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 12 17:45:11.888441 kernel: io scheduler mq-deadline registered Sep 12 17:45:11.888450 kernel: io scheduler kyber registered Sep 12 17:45:11.888458 kernel: io scheduler bfq registered Sep 12 17:45:11.888466 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 12 17:45:11.888474 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Sep 12 17:45:11.888482 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Sep 12 17:45:11.888490 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Sep 12 17:45:11.888501 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 12 17:45:11.888509 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 12 17:45:11.888517 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 12 17:45:11.888526 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 12 17:45:11.888533 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 12 17:45:11.888665 kernel: rtc_cmos 00:04: RTC can wake from S4 Sep 12 17:45:11.888677 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 12 17:45:11.888785 kernel: rtc_cmos 00:04: registered as rtc0 Sep 12 17:45:11.888898 kernel: rtc_cmos 00:04: setting system clock to 2025-09-12T17:45:11 UTC (1757699111) Sep 12 17:45:11.889023 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Sep 12 17:45:11.889035 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Sep 12 17:45:11.889043 kernel: efifb: probing for efifb Sep 12 17:45:11.889051 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Sep 12 17:45:11.889059 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Sep 12 17:45:11.889067 kernel: efifb: scrolling: redraw Sep 12 17:45:11.889074 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 12 17:45:11.889082 kernel: Console: switching to colour frame buffer device 160x50 Sep 12 17:45:11.889093 kernel: fb0: EFI VGA frame buffer device Sep 12 17:45:11.889104 kernel: pstore: Using crash dump compression: deflate Sep 12 17:45:11.889112 kernel: pstore: Registered efi_pstore as persistent store backend Sep 12 17:45:11.889120 kernel: NET: Registered PF_INET6 protocol family Sep 12 17:45:11.889128 kernel: Segment Routing with IPv6 Sep 12 17:45:11.889136 kernel: In-situ OAM (IOAM) with IPv6 Sep 12 17:45:11.889146 kernel: NET: Registered PF_PACKET protocol family Sep 12 17:45:11.889154 kernel: Key type dns_resolver registered Sep 12 17:45:11.889162 kernel: IPI shorthand broadcast: enabled Sep 12 17:45:11.889170 kernel: sched_clock: Marking stable (4199002699, 138552556)->(4357256615, -19701360) Sep 12 17:45:11.889178 kernel: registered taskstats version 1 Sep 12 17:45:11.889186 kernel: Loading compiled-in X.509 certificates Sep 12 17:45:11.889194 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.47-flatcar: f1ae8d6e9bfae84d90f4136cf098b0465b2a5bd7' Sep 12 17:45:11.889202 kernel: Demotion targets for Node 0: null Sep 12 17:45:11.889210 kernel: Key type .fscrypt registered Sep 12 17:45:11.889220 kernel: Key type fscrypt-provisioning registered Sep 12 17:45:11.889228 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 12 17:45:11.889236 kernel: ima: Allocated hash algorithm: sha1 Sep 12 17:45:11.889244 kernel: ima: No architecture policies found Sep 12 17:45:11.889252 kernel: clk: Disabling unused clocks Sep 12 17:45:11.889260 kernel: Warning: unable to open an initial console. Sep 12 17:45:11.889269 kernel: Freeing unused kernel image (initmem) memory: 54040K Sep 12 17:45:11.889277 kernel: Write protecting the kernel read-only data: 24576k Sep 12 17:45:11.889287 kernel: Freeing unused kernel image (rodata/data gap) memory: 280K Sep 12 17:45:11.889298 kernel: Run /init as init process Sep 12 17:45:11.889306 kernel: with arguments: Sep 12 17:45:11.889316 kernel: /init Sep 12 17:45:11.889324 kernel: with environment: Sep 12 17:45:11.889334 kernel: HOME=/ Sep 12 17:45:11.889342 kernel: TERM=linux Sep 12 17:45:11.889350 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 12 17:45:11.889363 systemd[1]: Successfully made /usr/ read-only. Sep 12 17:45:11.889377 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 12 17:45:11.889386 systemd[1]: Detected virtualization kvm. Sep 12 17:45:11.889394 systemd[1]: Detected architecture x86-64. Sep 12 17:45:11.889403 systemd[1]: Running in initrd. Sep 12 17:45:11.889411 systemd[1]: No hostname configured, using default hostname. Sep 12 17:45:11.889420 systemd[1]: Hostname set to . Sep 12 17:45:11.889429 systemd[1]: Initializing machine ID from VM UUID. Sep 12 17:45:11.889440 systemd[1]: Queued start job for default target initrd.target. Sep 12 17:45:11.889448 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:45:11.889457 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:45:11.889467 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 12 17:45:11.889503 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:45:11.889512 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 12 17:45:11.889522 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 12 17:45:11.889534 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 12 17:45:11.889543 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 12 17:45:11.889561 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:45:11.889570 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:45:11.889579 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:45:11.889588 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:45:11.889597 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:45:11.889605 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:45:11.889616 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:45:11.889624 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:45:11.889633 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 12 17:45:11.889642 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 12 17:45:11.889651 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:45:11.889660 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:45:11.889668 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:45:11.889677 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:45:11.889686 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 12 17:45:11.889697 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:45:11.889705 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 12 17:45:11.889714 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 12 17:45:11.889723 systemd[1]: Starting systemd-fsck-usr.service... Sep 12 17:45:11.889732 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:45:11.889740 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:45:11.889749 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:45:11.889758 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 12 17:45:11.889769 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:45:11.889778 systemd[1]: Finished systemd-fsck-usr.service. Sep 12 17:45:11.889787 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 17:45:11.889834 systemd-journald[219]: Collecting audit messages is disabled. Sep 12 17:45:11.889857 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:45:11.889867 systemd-journald[219]: Journal started Sep 12 17:45:11.889888 systemd-journald[219]: Runtime Journal (/run/log/journal/8eb4e84ae91d4a5f93a9fa4858180e46) is 6M, max 48.2M, 42.2M free. Sep 12 17:45:11.878930 systemd-modules-load[221]: Inserted module 'overlay' Sep 12 17:45:11.894423 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:45:11.894871 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:45:11.903110 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 12 17:45:11.906813 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:45:11.910383 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:45:11.914690 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 12 17:45:11.917111 systemd-modules-load[221]: Inserted module 'br_netfilter' Sep 12 17:45:11.918164 kernel: Bridge firewalling registered Sep 12 17:45:11.919792 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:45:11.921158 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:45:11.929502 systemd-tmpfiles[240]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 12 17:45:11.931131 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:45:11.935446 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:45:11.936151 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:45:11.938745 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:45:11.952138 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:45:11.956000 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 12 17:45:11.986082 dracut-cmdline[262]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro 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 verity.usrhash=271a44cc8ea1639cfb6fdf777202a5f025fda0b3ce9b293cc4e0e7047aecb858 Sep 12 17:45:11.991910 systemd-resolved[254]: Positive Trust Anchors: Sep 12 17:45:11.991937 systemd-resolved[254]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:45:11.991988 systemd-resolved[254]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 12 17:45:11.995169 systemd-resolved[254]: Defaulting to hostname 'linux'. Sep 12 17:45:11.996530 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:45:12.001724 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:45:12.116008 kernel: SCSI subsystem initialized Sep 12 17:45:12.124993 kernel: Loading iSCSI transport class v2.0-870. Sep 12 17:45:12.134994 kernel: iscsi: registered transport (tcp) Sep 12 17:45:12.159116 kernel: iscsi: registered transport (qla4xxx) Sep 12 17:45:12.159185 kernel: QLogic iSCSI HBA Driver Sep 12 17:45:12.184586 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 17:45:12.210239 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:45:12.211727 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 17:45:12.274861 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 12 17:45:12.276670 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 12 17:45:12.331016 kernel: raid6: avx2x4 gen() 29852 MB/s Sep 12 17:45:12.347993 kernel: raid6: avx2x2 gen() 30408 MB/s Sep 12 17:45:12.365000 kernel: raid6: avx2x1 gen() 25262 MB/s Sep 12 17:45:12.365036 kernel: raid6: using algorithm avx2x2 gen() 30408 MB/s Sep 12 17:45:12.382996 kernel: raid6: .... xor() 19489 MB/s, rmw enabled Sep 12 17:45:12.383054 kernel: raid6: using avx2x2 recovery algorithm Sep 12 17:45:12.403997 kernel: xor: automatically using best checksumming function avx Sep 12 17:45:12.571012 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 12 17:45:12.579386 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:45:12.581609 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:45:12.610387 systemd-udevd[471]: Using default interface naming scheme 'v255'. Sep 12 17:45:12.617085 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:45:12.618406 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 12 17:45:12.646349 dracut-pre-trigger[473]: rd.md=0: removing MD RAID activation Sep 12 17:45:12.681748 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:45:12.685745 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:45:12.773696 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:45:12.775859 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 12 17:45:12.817006 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Sep 12 17:45:12.820000 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Sep 12 17:45:12.823673 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 12 17:45:12.823695 kernel: GPT:9289727 != 19775487 Sep 12 17:45:12.823706 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 12 17:45:12.823717 kernel: GPT:9289727 != 19775487 Sep 12 17:45:12.824053 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 12 17:45:12.825166 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:45:12.945011 kernel: cryptd: max_cpu_qlen set to 1000 Sep 12 17:45:12.952991 kernel: AES CTR mode by8 optimization enabled Sep 12 17:45:12.956506 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:45:12.957485 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:45:12.963918 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:45:12.971763 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:45:12.975995 kernel: libata version 3.00 loaded. Sep 12 17:45:12.975064 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:45:12.991477 kernel: ahci 0000:00:1f.2: version 3.0 Sep 12 17:45:12.998459 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Sep 12 17:45:12.998482 kernel: ahci 0000:00:1f.2: AHCI vers 0001.0000, 32 command slots, 1.5 Gbps, SATA mode Sep 12 17:45:12.998681 kernel: ahci 0000:00:1f.2: 6/6 ports implemented (port mask 0x3f) Sep 12 17:45:12.998868 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Sep 12 17:45:13.000007 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 Sep 12 17:45:13.007001 kernel: scsi host0: ahci Sep 12 17:45:13.009601 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Sep 12 17:45:13.010991 kernel: scsi host1: ahci Sep 12 17:45:13.014002 kernel: scsi host2: ahci Sep 12 17:45:13.014210 kernel: scsi host3: ahci Sep 12 17:45:13.021994 kernel: scsi host4: ahci Sep 12 17:45:13.022205 kernel: scsi host5: ahci Sep 12 17:45:13.022388 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 lpm-pol 1 Sep 12 17:45:13.022401 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 lpm-pol 1 Sep 12 17:45:13.022411 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 lpm-pol 1 Sep 12 17:45:13.022422 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 lpm-pol 1 Sep 12 17:45:13.024208 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 lpm-pol 1 Sep 12 17:45:13.024225 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 lpm-pol 1 Sep 12 17:45:13.050083 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Sep 12 17:45:13.058293 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Sep 12 17:45:13.058366 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Sep 12 17:45:13.070292 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 12 17:45:13.071586 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 12 17:45:13.102814 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:45:13.107154 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:45:13.109782 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:45:13.110893 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:45:13.119789 disk-uuid[632]: Primary Header is updated. Sep 12 17:45:13.119789 disk-uuid[632]: Secondary Entries is updated. Sep 12 17:45:13.119789 disk-uuid[632]: Secondary Header is updated. Sep 12 17:45:13.123140 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:45:13.127994 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:45:13.145186 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:45:13.328006 kernel: ata1: SATA link down (SStatus 0 SControl 300) Sep 12 17:45:13.336058 kernel: ata4: SATA link down (SStatus 0 SControl 300) Sep 12 17:45:13.336117 kernel: ata5: SATA link down (SStatus 0 SControl 300) Sep 12 17:45:13.336989 kernel: ata2: SATA link down (SStatus 0 SControl 300) Sep 12 17:45:13.337013 kernel: ata6: SATA link down (SStatus 0 SControl 300) Sep 12 17:45:13.337997 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Sep 12 17:45:13.338992 kernel: ata3.00: LPM support broken, forcing max_power Sep 12 17:45:13.339007 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Sep 12 17:45:13.340096 kernel: ata3.00: applying bridge limits Sep 12 17:45:13.341235 kernel: ata3.00: LPM support broken, forcing max_power Sep 12 17:45:13.341246 kernel: ata3.00: configured for UDMA/100 Sep 12 17:45:13.341995 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Sep 12 17:45:13.396006 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Sep 12 17:45:13.396233 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 12 17:45:13.422155 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Sep 12 17:45:13.825321 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 12 17:45:13.826115 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:45:13.827804 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:45:13.828281 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:45:13.829521 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 12 17:45:13.864095 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:45:14.129950 disk-uuid[633]: The operation has completed successfully. Sep 12 17:45:14.131254 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Sep 12 17:45:14.159296 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 12 17:45:14.159413 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 12 17:45:14.190714 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 12 17:45:14.215649 sh[667]: Success Sep 12 17:45:14.233377 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 12 17:45:14.233447 kernel: device-mapper: uevent: version 1.0.3 Sep 12 17:45:14.234447 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 12 17:45:14.243001 kernel: device-mapper: verity: sha256 using shash "sha256-ni" Sep 12 17:45:14.274904 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 12 17:45:14.277980 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 12 17:45:14.293557 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 12 17:45:14.300603 kernel: BTRFS: device fsid 74707491-1b86-4926-8bdb-c533ce2a0c32 devid 1 transid 38 /dev/mapper/usr (253:0) scanned by mount (679) Sep 12 17:45:14.300633 kernel: BTRFS info (device dm-0): first mount of filesystem 74707491-1b86-4926-8bdb-c533ce2a0c32 Sep 12 17:45:14.300645 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:45:14.306119 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 12 17:45:14.306143 kernel: BTRFS info (device dm-0): enabling free space tree Sep 12 17:45:14.307362 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 12 17:45:14.308252 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 12 17:45:14.309478 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 12 17:45:14.310319 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 12 17:45:14.312087 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 12 17:45:14.336990 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (712) Sep 12 17:45:14.340203 kernel: BTRFS info (device vda6): first mount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:45:14.340233 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:45:14.343261 kernel: BTRFS info (device vda6): turning on async discard Sep 12 17:45:14.343304 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 17:45:14.348987 kernel: BTRFS info (device vda6): last unmount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:45:14.349378 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 12 17:45:14.352770 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 12 17:45:14.539876 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:45:14.543676 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:45:14.603350 ignition[756]: Ignition 2.21.0 Sep 12 17:45:14.603891 ignition[756]: Stage: fetch-offline Sep 12 17:45:14.603954 ignition[756]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:45:14.603978 ignition[756]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:45:14.605697 systemd-networkd[854]: lo: Link UP Sep 12 17:45:14.604091 ignition[756]: parsed url from cmdline: "" Sep 12 17:45:14.605701 systemd-networkd[854]: lo: Gained carrier Sep 12 17:45:14.604094 ignition[756]: no config URL provided Sep 12 17:45:14.607418 systemd-networkd[854]: Enumeration completed Sep 12 17:45:14.604099 ignition[756]: reading system config file "/usr/lib/ignition/user.ign" Sep 12 17:45:14.607505 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:45:14.604107 ignition[756]: no config at "/usr/lib/ignition/user.ign" Sep 12 17:45:14.608681 systemd-networkd[854]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:45:14.604135 ignition[756]: op(1): [started] loading QEMU firmware config module Sep 12 17:45:14.608685 systemd-networkd[854]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:45:14.604140 ignition[756]: op(1): executing: "modprobe" "qemu_fw_cfg" Sep 12 17:45:14.609934 systemd[1]: Reached target network.target - Network. Sep 12 17:45:14.613195 ignition[756]: op(1): [finished] loading QEMU firmware config module Sep 12 17:45:14.610810 systemd-networkd[854]: eth0: Link UP Sep 12 17:45:14.614414 ignition[756]: parsing config with SHA512: a4c5009c9381f9f0649ed28e3992f6753b9ee53eb71d7270ead5dd148fcc02e7814336534fc2864190ff8f2111366ac9a67c47576d14f3c425057cbc5f54267e Sep 12 17:45:14.611337 systemd-networkd[854]: eth0: Gained carrier Sep 12 17:45:14.617591 ignition[756]: fetch-offline: fetch-offline passed Sep 12 17:45:14.611346 systemd-networkd[854]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:45:14.617641 ignition[756]: Ignition finished successfully Sep 12 17:45:14.617340 unknown[756]: fetched base config from "system" Sep 12 17:45:14.617349 unknown[756]: fetched user config from "qemu" Sep 12 17:45:14.620703 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:45:14.623150 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Sep 12 17:45:14.624028 systemd-networkd[854]: eth0: DHCPv4 address 10.0.0.86/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 12 17:45:14.624257 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 12 17:45:14.656615 ignition[862]: Ignition 2.21.0 Sep 12 17:45:14.657493 ignition[862]: Stage: kargs Sep 12 17:45:14.658168 ignition[862]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:45:14.658184 ignition[862]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:45:14.658904 ignition[862]: kargs: kargs passed Sep 12 17:45:14.658950 ignition[862]: Ignition finished successfully Sep 12 17:45:14.663810 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 12 17:45:14.666879 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 12 17:45:14.710903 ignition[871]: Ignition 2.21.0 Sep 12 17:45:14.712014 ignition[871]: Stage: disks Sep 12 17:45:14.712339 ignition[871]: no configs at "/usr/lib/ignition/base.d" Sep 12 17:45:14.712351 ignition[871]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:45:14.713268 ignition[871]: disks: disks passed Sep 12 17:45:14.713313 ignition[871]: Ignition finished successfully Sep 12 17:45:14.716443 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 12 17:45:14.716934 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 12 17:45:14.718729 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 12 17:45:14.719205 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:45:14.719532 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:45:14.719851 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:45:14.721288 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 12 17:45:14.756639 systemd-fsck[881]: ROOT: clean, 15/553520 files, 52789/553472 blocks Sep 12 17:45:14.764742 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 12 17:45:14.766174 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 12 17:45:14.910020 kernel: EXT4-fs (vda9): mounted filesystem 26739aba-b0be-4ce3-bfbd-ca4dbcbe2426 r/w with ordered data mode. Quota mode: none. Sep 12 17:45:14.911030 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 12 17:45:14.911950 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 12 17:45:14.914731 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:45:14.916826 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 12 17:45:14.918719 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 12 17:45:14.918764 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 12 17:45:14.918786 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:45:14.932909 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 12 17:45:14.935367 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 12 17:45:14.939421 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (889) Sep 12 17:45:14.939443 kernel: BTRFS info (device vda6): first mount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:45:14.939454 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:45:14.942998 kernel: BTRFS info (device vda6): turning on async discard Sep 12 17:45:14.943028 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 17:45:14.944848 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:45:14.971376 initrd-setup-root[913]: cut: /sysroot/etc/passwd: No such file or directory Sep 12 17:45:14.977541 initrd-setup-root[920]: cut: /sysroot/etc/group: No such file or directory Sep 12 17:45:14.984227 initrd-setup-root[927]: cut: /sysroot/etc/shadow: No such file or directory Sep 12 17:45:14.991651 initrd-setup-root[934]: cut: /sysroot/etc/gshadow: No such file or directory Sep 12 17:45:15.101170 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 12 17:45:15.103702 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 12 17:45:15.104615 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 12 17:45:15.122991 kernel: BTRFS info (device vda6): last unmount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:45:15.139113 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 12 17:45:15.158508 ignition[1003]: INFO : Ignition 2.21.0 Sep 12 17:45:15.159623 ignition[1003]: INFO : Stage: mount Sep 12 17:45:15.160301 ignition[1003]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:45:15.160301 ignition[1003]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:45:15.163819 ignition[1003]: INFO : mount: mount passed Sep 12 17:45:15.164583 ignition[1003]: INFO : Ignition finished successfully Sep 12 17:45:15.168156 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 12 17:45:15.170138 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 12 17:45:15.299406 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 12 17:45:15.301054 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 12 17:45:15.335007 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 (254:6) scanned by mount (1015) Sep 12 17:45:15.337298 kernel: BTRFS info (device vda6): first mount of filesystem 5410dae6-8d31-4ea4-a4b4-868064445761 Sep 12 17:45:15.337321 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Sep 12 17:45:15.340996 kernel: BTRFS info (device vda6): turning on async discard Sep 12 17:45:15.341018 kernel: BTRFS info (device vda6): enabling free space tree Sep 12 17:45:15.342431 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 12 17:45:15.414396 ignition[1032]: INFO : Ignition 2.21.0 Sep 12 17:45:15.414396 ignition[1032]: INFO : Stage: files Sep 12 17:45:15.417758 ignition[1032]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:45:15.417758 ignition[1032]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:45:15.420412 ignition[1032]: DEBUG : files: compiled without relabeling support, skipping Sep 12 17:45:15.422377 ignition[1032]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 12 17:45:15.422377 ignition[1032]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 12 17:45:15.425193 ignition[1032]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 12 17:45:15.425193 ignition[1032]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 12 17:45:15.425193 ignition[1032]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 12 17:45:15.425172 unknown[1032]: wrote ssh authorized keys file for user: core Sep 12 17:45:15.430678 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Sep 12 17:45:15.430678 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Sep 12 17:45:15.430678 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:45:15.430678 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 12 17:45:15.430678 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Sep 12 17:45:15.440260 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Sep 12 17:45:15.440260 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Sep 12 17:45:15.440260 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.31.8-x86-64.raw: attempt #1 Sep 12 17:45:16.481206 systemd-networkd[854]: eth0: Gained IPv6LL Sep 12 17:45:17.755582 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Sep 12 17:45:18.142747 ignition[1032]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.8-x86-64.raw" Sep 12 17:45:18.142747 ignition[1032]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Sep 12 17:45:18.146635 ignition[1032]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 12 17:45:18.149373 ignition[1032]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Sep 12 17:45:18.149373 ignition[1032]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Sep 12 17:45:18.149373 ignition[1032]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Sep 12 17:45:18.168614 ignition[1032]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Sep 12 17:45:18.172592 ignition[1032]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Sep 12 17:45:18.174158 ignition[1032]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Sep 12 17:45:18.174158 ignition[1032]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:45:18.174158 ignition[1032]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 12 17:45:18.174158 ignition[1032]: INFO : files: files passed Sep 12 17:45:18.174158 ignition[1032]: INFO : Ignition finished successfully Sep 12 17:45:18.176456 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 12 17:45:18.179628 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 12 17:45:18.181766 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 12 17:45:18.204682 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 12 17:45:18.204841 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 12 17:45:18.209203 initrd-setup-root-after-ignition[1061]: grep: /sysroot/oem/oem-release: No such file or directory Sep 12 17:45:18.213792 initrd-setup-root-after-ignition[1063]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:45:18.213792 initrd-setup-root-after-ignition[1063]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:45:18.217181 initrd-setup-root-after-ignition[1067]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 12 17:45:18.220428 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:45:18.221051 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 12 17:45:18.222258 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 12 17:45:18.292105 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 12 17:45:18.293194 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 12 17:45:18.296210 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 12 17:45:18.298215 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 12 17:45:18.300225 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 12 17:45:18.302570 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 12 17:45:18.329025 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:45:18.332873 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 12 17:45:18.354942 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:45:18.355306 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:45:18.357482 systemd[1]: Stopped target timers.target - Timer Units. Sep 12 17:45:18.359677 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 12 17:45:18.359792 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 12 17:45:18.363142 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 12 17:45:18.365175 systemd[1]: Stopped target basic.target - Basic System. Sep 12 17:45:18.365703 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 12 17:45:18.366035 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 12 17:45:18.366508 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 12 17:45:18.366824 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 12 17:45:18.367313 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 12 17:45:18.367641 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 12 17:45:18.367985 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 12 17:45:18.368464 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 12 17:45:18.368773 systemd[1]: Stopped target swap.target - Swaps. Sep 12 17:45:18.369232 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 12 17:45:18.369332 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 12 17:45:18.385616 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:45:18.385941 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:45:18.386404 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 12 17:45:18.391601 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:45:18.392021 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 12 17:45:18.392125 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 12 17:45:18.392854 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 12 17:45:18.392953 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 12 17:45:18.393478 systemd[1]: Stopped target paths.target - Path Units. Sep 12 17:45:18.393776 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 12 17:45:18.406066 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:45:18.406674 systemd[1]: Stopped target slices.target - Slice Units. Sep 12 17:45:18.409085 systemd[1]: Stopped target sockets.target - Socket Units. Sep 12 17:45:18.410916 systemd[1]: iscsid.socket: Deactivated successfully. Sep 12 17:45:18.411050 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 12 17:45:18.412502 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 12 17:45:18.412597 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 12 17:45:18.414425 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 12 17:45:18.414565 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 12 17:45:18.416409 systemd[1]: ignition-files.service: Deactivated successfully. Sep 12 17:45:18.416528 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 12 17:45:18.419480 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 12 17:45:18.425451 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 12 17:45:18.426363 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 12 17:45:18.426486 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:45:18.428537 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 12 17:45:18.428637 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 12 17:45:18.435020 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 12 17:45:18.435132 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 12 17:45:18.450850 ignition[1087]: INFO : Ignition 2.21.0 Sep 12 17:45:18.450850 ignition[1087]: INFO : Stage: umount Sep 12 17:45:18.452594 ignition[1087]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 12 17:45:18.452594 ignition[1087]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Sep 12 17:45:18.452594 ignition[1087]: INFO : umount: umount passed Sep 12 17:45:18.455647 ignition[1087]: INFO : Ignition finished successfully Sep 12 17:45:18.454998 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 12 17:45:18.455133 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 12 17:45:18.457916 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 12 17:45:18.458374 systemd[1]: Stopped target network.target - Network. Sep 12 17:45:18.459211 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 12 17:45:18.459260 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 12 17:45:18.459584 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 12 17:45:18.459635 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 12 17:45:18.462868 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 12 17:45:18.462917 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 12 17:45:18.463345 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 12 17:45:18.463393 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 12 17:45:18.466342 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 12 17:45:18.467015 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 12 17:45:18.474085 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 12 17:45:18.474217 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 12 17:45:18.478062 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 12 17:45:18.478418 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 12 17:45:18.478464 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:45:18.481540 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 12 17:45:18.481759 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 12 17:45:18.481874 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 12 17:45:18.487221 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 12 17:45:18.487799 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 12 17:45:18.488516 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 12 17:45:18.488570 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:45:18.489779 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 12 17:45:18.493596 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 12 17:45:18.493649 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 12 17:45:18.493963 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 12 17:45:18.494018 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:45:18.497278 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 12 17:45:18.497326 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 12 17:45:18.499191 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:45:18.500595 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 12 17:45:18.515218 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 12 17:45:18.515347 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 12 17:45:18.522317 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 12 17:45:18.522501 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:45:18.523222 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 12 17:45:18.523268 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 12 17:45:18.523646 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 12 17:45:18.523680 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:45:18.523948 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 12 17:45:18.524048 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 12 17:45:18.531278 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 12 17:45:18.531327 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 12 17:45:18.532167 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 12 17:45:18.532213 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 12 17:45:18.533544 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 12 17:45:18.537891 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 12 17:45:18.537943 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:45:18.542037 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 12 17:45:18.542086 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:45:18.545219 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 12 17:45:18.545264 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:45:18.548624 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 12 17:45:18.548669 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:45:18.549332 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 12 17:45:18.549372 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:45:18.561505 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 12 17:45:18.561614 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 12 17:45:18.659350 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 12 17:45:18.659482 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 12 17:45:18.661534 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 12 17:45:18.661855 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 12 17:45:18.661905 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 12 17:45:18.663115 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 12 17:45:18.683372 systemd[1]: Switching root. Sep 12 17:45:18.714295 systemd-journald[219]: Journal stopped Sep 12 17:45:19.862126 systemd-journald[219]: Received SIGTERM from PID 1 (systemd). Sep 12 17:45:19.862206 kernel: SELinux: policy capability network_peer_controls=1 Sep 12 17:45:19.862229 kernel: SELinux: policy capability open_perms=1 Sep 12 17:45:19.862240 kernel: SELinux: policy capability extended_socket_class=1 Sep 12 17:45:19.862251 kernel: SELinux: policy capability always_check_network=0 Sep 12 17:45:19.862263 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 12 17:45:19.862274 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 12 17:45:19.862286 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 12 17:45:19.862297 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 12 17:45:19.862309 kernel: SELinux: policy capability userspace_initial_context=0 Sep 12 17:45:19.862327 kernel: audit: type=1403 audit(1757699119.090:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 12 17:45:19.862347 systemd[1]: Successfully loaded SELinux policy in 63.044ms. Sep 12 17:45:19.862380 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.263ms. Sep 12 17:45:19.862394 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 12 17:45:19.862407 systemd[1]: Detected virtualization kvm. Sep 12 17:45:19.862419 systemd[1]: Detected architecture x86-64. Sep 12 17:45:19.862431 systemd[1]: Detected first boot. Sep 12 17:45:19.862443 systemd[1]: Initializing machine ID from VM UUID. Sep 12 17:45:19.862456 zram_generator::config[1132]: No configuration found. Sep 12 17:45:19.862471 kernel: Guest personality initialized and is inactive Sep 12 17:45:19.862483 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 12 17:45:19.862494 kernel: Initialized host personality Sep 12 17:45:19.862505 kernel: NET: Registered PF_VSOCK protocol family Sep 12 17:45:19.862517 systemd[1]: Populated /etc with preset unit settings. Sep 12 17:45:19.862530 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 12 17:45:19.862542 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 12 17:45:19.862554 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 12 17:45:19.862567 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 12 17:45:19.862582 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 12 17:45:19.862594 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 12 17:45:19.862605 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 12 17:45:19.862617 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 12 17:45:19.862629 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 12 17:45:19.862641 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 12 17:45:19.862653 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 12 17:45:19.862665 systemd[1]: Created slice user.slice - User and Session Slice. Sep 12 17:45:19.862679 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 12 17:45:19.862692 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 12 17:45:19.862703 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 12 17:45:19.862715 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 12 17:45:19.862728 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 12 17:45:19.862740 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 12 17:45:19.862751 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 12 17:45:19.862764 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 12 17:45:19.862778 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 12 17:45:19.862790 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 12 17:45:19.862801 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 12 17:45:19.862813 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 12 17:45:19.862826 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 12 17:45:19.862838 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 12 17:45:19.862850 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 12 17:45:19.862862 systemd[1]: Reached target slices.target - Slice Units. Sep 12 17:45:19.862874 systemd[1]: Reached target swap.target - Swaps. Sep 12 17:45:19.862887 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 12 17:45:19.862899 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 12 17:45:19.862911 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 12 17:45:19.862923 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 12 17:45:19.862935 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 12 17:45:19.862947 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 12 17:45:19.862959 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 12 17:45:19.862991 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 12 17:45:19.863003 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 12 17:45:19.863018 systemd[1]: Mounting media.mount - External Media Directory... Sep 12 17:45:19.863031 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:45:19.863042 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 12 17:45:19.863055 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 12 17:45:19.863066 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 12 17:45:19.863078 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Sep 12 17:45:19.863091 systemd[1]: Reached target machines.target - Containers. Sep 12 17:45:19.863103 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 12 17:45:19.863121 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:45:19.863141 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 12 17:45:19.863153 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 12 17:45:19.863165 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:45:19.863177 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:45:19.863189 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:45:19.863201 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 12 17:45:19.863213 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:45:19.863226 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 12 17:45:19.863239 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 12 17:45:19.863252 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 12 17:45:19.863267 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 12 17:45:19.863279 systemd[1]: Stopped systemd-fsck-usr.service. Sep 12 17:45:19.863294 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:45:19.863308 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 12 17:45:19.863320 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 12 17:45:19.863333 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 12 17:45:19.863345 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 12 17:45:19.863366 kernel: loop: module loaded Sep 12 17:45:19.863378 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 12 17:45:19.863391 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 12 17:45:19.863404 kernel: ACPI: bus type drm_connector registered Sep 12 17:45:19.863416 systemd[1]: verity-setup.service: Deactivated successfully. Sep 12 17:45:19.863430 systemd[1]: Stopped verity-setup.service. Sep 12 17:45:19.863444 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:45:19.863458 kernel: fuse: init (API version 7.41) Sep 12 17:45:19.863471 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 12 17:45:19.863483 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 12 17:45:19.863498 systemd[1]: Mounted media.mount - External Media Directory. Sep 12 17:45:19.863510 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 12 17:45:19.863523 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 12 17:45:19.863536 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 12 17:45:19.863569 systemd-journald[1203]: Collecting audit messages is disabled. Sep 12 17:45:19.863592 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 12 17:45:19.863604 systemd-journald[1203]: Journal started Sep 12 17:45:19.863630 systemd-journald[1203]: Runtime Journal (/run/log/journal/8eb4e84ae91d4a5f93a9fa4858180e46) is 6M, max 48.2M, 42.2M free. Sep 12 17:45:19.618140 systemd[1]: Queued start job for default target multi-user.target. Sep 12 17:45:19.629011 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Sep 12 17:45:19.629505 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 12 17:45:19.867480 systemd[1]: Started systemd-journald.service - Journal Service. Sep 12 17:45:19.868703 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 12 17:45:19.870378 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 12 17:45:19.870620 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 12 17:45:19.872182 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:45:19.872492 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:45:19.874030 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:45:19.874286 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:45:19.875739 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:45:19.875952 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:45:19.877943 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 12 17:45:19.878191 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 12 17:45:19.879903 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:45:19.880194 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:45:19.881864 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 12 17:45:19.883634 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 12 17:45:19.885543 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 12 17:45:19.887496 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 12 17:45:19.902929 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 12 17:45:19.905564 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 12 17:45:19.907726 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 12 17:45:19.908843 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 12 17:45:19.908929 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 12 17:45:19.910874 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 12 17:45:19.923105 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 12 17:45:19.924387 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:45:19.926085 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 12 17:45:19.930187 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 12 17:45:19.932112 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 17:45:19.933582 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 12 17:45:19.935068 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:45:19.936375 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 12 17:45:19.947079 systemd-journald[1203]: Time spent on flushing to /var/log/journal/8eb4e84ae91d4a5f93a9fa4858180e46 is 82.178ms for 1025 entries. Sep 12 17:45:19.947079 systemd-journald[1203]: System Journal (/var/log/journal/8eb4e84ae91d4a5f93a9fa4858180e46) is 8M, max 195.6M, 187.6M free. Sep 12 17:45:20.037269 systemd-journald[1203]: Received client request to flush runtime journal. Sep 12 17:45:20.037314 kernel: loop0: detected capacity change from 0 to 111000 Sep 12 17:45:19.938822 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 12 17:45:19.942736 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 12 17:45:19.945891 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 12 17:45:19.948281 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 12 17:45:19.950054 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 12 17:45:19.979052 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 12 17:45:19.980653 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 12 17:45:20.032276 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 12 17:45:20.033963 systemd-tmpfiles[1252]: ACLs are not supported, ignoring. Sep 12 17:45:20.033991 systemd-tmpfiles[1252]: ACLs are not supported, ignoring. Sep 12 17:45:20.034554 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 12 17:45:20.043919 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 12 17:45:20.045816 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 12 17:45:20.053199 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 12 17:45:20.058053 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 12 17:45:20.069762 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 12 17:45:20.080280 kernel: loop1: detected capacity change from 0 to 128016 Sep 12 17:45:20.092760 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 12 17:45:20.095594 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 12 17:45:20.118007 kernel: loop2: detected capacity change from 0 to 221472 Sep 12 17:45:20.127872 systemd-tmpfiles[1274]: ACLs are not supported, ignoring. Sep 12 17:45:20.127895 systemd-tmpfiles[1274]: ACLs are not supported, ignoring. Sep 12 17:45:20.132334 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 12 17:45:20.148296 kernel: loop3: detected capacity change from 0 to 111000 Sep 12 17:45:20.161004 kernel: loop4: detected capacity change from 0 to 128016 Sep 12 17:45:20.168991 kernel: loop5: detected capacity change from 0 to 221472 Sep 12 17:45:20.178288 (sd-merge)[1278]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Sep 12 17:45:20.178840 (sd-merge)[1278]: Merged extensions into '/usr'. Sep 12 17:45:20.185635 systemd[1]: Reload requested from client PID 1251 ('systemd-sysext') (unit systemd-sysext.service)... Sep 12 17:45:20.185653 systemd[1]: Reloading... Sep 12 17:45:20.299001 zram_generator::config[1302]: No configuration found. Sep 12 17:45:20.475009 ldconfig[1246]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 12 17:45:20.533101 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 12 17:45:20.533272 systemd[1]: Reloading finished in 347 ms. Sep 12 17:45:20.605313 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 12 17:45:20.607062 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 12 17:45:20.621249 systemd[1]: Starting ensure-sysext.service... Sep 12 17:45:20.623510 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 12 17:45:20.737525 systemd[1]: Reload requested from client PID 1341 ('systemctl') (unit ensure-sysext.service)... Sep 12 17:45:20.737708 systemd[1]: Reloading... Sep 12 17:45:20.765346 systemd-tmpfiles[1342]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 12 17:45:20.765400 systemd-tmpfiles[1342]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 12 17:45:20.765797 systemd-tmpfiles[1342]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 12 17:45:20.766152 systemd-tmpfiles[1342]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 12 17:45:20.767276 systemd-tmpfiles[1342]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 12 17:45:20.767648 systemd-tmpfiles[1342]: ACLs are not supported, ignoring. Sep 12 17:45:20.767734 systemd-tmpfiles[1342]: ACLs are not supported, ignoring. Sep 12 17:45:20.774796 systemd-tmpfiles[1342]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:45:20.775025 systemd-tmpfiles[1342]: Skipping /boot Sep 12 17:45:20.788448 systemd-tmpfiles[1342]: Detected autofs mount point /boot during canonicalization of boot. Sep 12 17:45:20.788464 systemd-tmpfiles[1342]: Skipping /boot Sep 12 17:45:20.804004 zram_generator::config[1372]: No configuration found. Sep 12 17:45:20.979548 systemd[1]: Reloading finished in 241 ms. Sep 12 17:45:21.005655 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 12 17:45:21.030697 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 12 17:45:21.040603 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 17:45:21.043113 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 12 17:45:21.045452 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 12 17:45:21.051429 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 12 17:45:21.055415 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 12 17:45:21.058402 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 12 17:45:21.063717 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:45:21.063887 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:45:21.064952 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:45:21.068417 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:45:21.070854 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:45:21.073095 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:45:21.073202 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:45:21.075150 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 12 17:45:21.076341 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:45:21.084105 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 12 17:45:21.092526 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:45:21.092809 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:45:21.094662 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:45:21.095003 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:45:21.096918 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:45:21.098458 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:45:21.099207 systemd-udevd[1412]: Using default interface naming scheme 'v255'. Sep 12 17:45:21.108338 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 12 17:45:21.112082 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:45:21.112263 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:45:21.113957 augenrules[1441]: No rules Sep 12 17:45:21.116137 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:45:21.118461 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 12 17:45:21.122608 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:45:21.123837 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:45:21.124032 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:45:21.127585 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 12 17:45:21.127858 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:45:21.128851 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 12 17:45:21.130002 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 17:45:21.130566 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 17:45:21.138181 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 12 17:45:21.139955 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:45:21.140184 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:45:21.142305 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:45:21.142567 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:45:21.152142 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 12 17:45:21.155083 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 12 17:45:21.156818 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 12 17:45:21.162793 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 12 17:45:21.164241 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 12 17:45:21.172516 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:45:21.176947 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 12 17:45:21.179207 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 12 17:45:21.180532 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 12 17:45:21.225235 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 12 17:45:21.229223 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 12 17:45:21.230450 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 12 17:45:21.230498 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 12 17:45:21.230546 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 12 17:45:21.230590 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Sep 12 17:45:21.230619 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 12 17:45:21.231198 systemd[1]: Finished ensure-sysext.service. Sep 12 17:45:21.255909 augenrules[1491]: /sbin/augenrules: No change Sep 12 17:45:21.263060 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 12 17:45:21.263344 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 12 17:45:21.265399 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 12 17:45:21.265640 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 12 17:45:21.267769 augenrules[1517]: No rules Sep 12 17:45:21.267500 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 12 17:45:21.267710 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 12 17:45:21.279204 systemd[1]: audit-rules.service: Deactivated successfully. Sep 12 17:45:21.279508 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 12 17:45:21.304155 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 12 17:45:21.307725 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Sep 12 17:45:21.335995 kernel: mousedev: PS/2 mouse device common for all mice Sep 12 17:45:21.345675 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 12 17:45:21.363986 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Sep 12 17:45:21.369002 kernel: ACPI: button: Power Button [PWRF] Sep 12 17:45:21.372577 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Sep 12 17:45:21.376370 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 12 17:45:21.400766 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 12 17:45:21.411160 systemd-networkd[1467]: lo: Link UP Sep 12 17:45:21.411171 systemd-networkd[1467]: lo: Gained carrier Sep 12 17:45:21.412939 systemd-networkd[1467]: Enumeration completed Sep 12 17:45:21.413041 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 12 17:45:21.416672 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 12 17:45:21.418852 systemd-networkd[1467]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:45:21.419611 systemd-networkd[1467]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 12 17:45:21.419755 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 12 17:45:21.421738 systemd-networkd[1467]: eth0: Link UP Sep 12 17:45:21.421939 systemd-networkd[1467]: eth0: Gained carrier Sep 12 17:45:21.422028 systemd-networkd[1467]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 12 17:45:21.519007 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Sep 12 17:45:21.519348 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Sep 12 17:45:21.520668 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Sep 12 17:45:21.523621 systemd-networkd[1467]: eth0: DHCPv4 address 10.0.0.86/16, gateway 10.0.0.1 acquired from 10.0.0.1 Sep 12 17:45:21.524784 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 12 17:45:21.536508 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 12 17:45:21.555956 systemd-resolved[1411]: Positive Trust Anchors: Sep 12 17:45:21.557337 systemd-resolved[1411]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 12 17:45:21.557429 systemd-resolved[1411]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Sep 12 17:45:21.569768 systemd-resolved[1411]: Defaulting to hostname 'linux'. Sep 12 17:45:21.572787 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 12 17:45:21.575975 systemd[1]: Reached target network.target - Network. Sep 12 17:45:21.576936 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 12 17:45:21.579149 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Sep 12 17:45:21.580440 systemd[1]: Reached target time-set.target - System Time Set. Sep 12 17:45:23.163379 systemd-timesyncd[1532]: Contacted time server 10.0.0.1:123 (10.0.0.1). Sep 12 17:45:23.163450 systemd-timesyncd[1532]: Initial clock synchronization to Fri 2025-09-12 17:45:23.163243 UTC. Sep 12 17:45:23.163505 systemd-resolved[1411]: Clock change detected. Flushing caches. Sep 12 17:45:23.222015 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 12 17:45:23.224487 systemd[1]: Reached target sysinit.target - System Initialization. Sep 12 17:45:23.225881 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 12 17:45:23.227317 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 12 17:45:23.228827 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 12 17:45:23.230190 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 12 17:45:23.231736 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 12 17:45:23.233159 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 12 17:45:23.234572 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 12 17:45:23.234672 systemd[1]: Reached target paths.target - Path Units. Sep 12 17:45:23.235718 systemd[1]: Reached target timers.target - Timer Units. Sep 12 17:45:23.238571 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 12 17:45:23.247467 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 12 17:45:23.253576 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 12 17:45:23.255596 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 12 17:45:23.257772 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 12 17:45:23.273794 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 12 17:45:23.275449 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 12 17:45:23.277526 kernel: kvm_amd: TSC scaling supported Sep 12 17:45:23.277555 kernel: kvm_amd: Nested Virtualization enabled Sep 12 17:45:23.277577 kernel: kvm_amd: Nested Paging enabled Sep 12 17:45:23.277594 kernel: kvm_amd: LBR virtualization supported Sep 12 17:45:23.278241 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Sep 12 17:45:23.279665 kernel: kvm_amd: Virtual GIF supported Sep 12 17:45:23.282604 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 12 17:45:23.285280 systemd[1]: Reached target sockets.target - Socket Units. Sep 12 17:45:23.286321 systemd[1]: Reached target basic.target - Basic System. Sep 12 17:45:23.287442 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:45:23.287489 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 12 17:45:23.289513 systemd[1]: Starting containerd.service - containerd container runtime... Sep 12 17:45:23.292656 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 12 17:45:23.295431 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 12 17:45:23.298776 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 12 17:45:23.305572 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 12 17:45:23.306702 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Sep 12 17:45:23.308832 jq[1569]: false Sep 12 17:45:23.309275 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 12 17:45:23.312277 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 12 17:45:23.315282 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 12 17:45:23.317840 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 12 17:45:23.327936 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Refreshing passwd entry cache Sep 12 17:45:23.327945 oslogin_cache_refresh[1571]: Refreshing passwd entry cache Sep 12 17:45:23.328292 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 12 17:45:23.331336 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 12 17:45:23.331908 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 12 17:45:23.333356 systemd[1]: Starting update-engine.service - Update Engine... Sep 12 17:45:23.334427 kernel: EDAC MC: Ver: 3.0.0 Sep 12 17:45:23.336702 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 12 17:45:23.341115 extend-filesystems[1570]: Found /dev/vda6 Sep 12 17:45:23.342278 extend-filesystems[1570]: Found /dev/vda9 Sep 12 17:45:23.342225 oslogin_cache_refresh[1571]: Failure getting users, quitting Sep 12 17:45:23.343219 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Failure getting users, quitting Sep 12 17:45:23.343219 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 12 17:45:23.343219 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Refreshing group entry cache Sep 12 17:45:23.342246 oslogin_cache_refresh[1571]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 12 17:45:23.342298 oslogin_cache_refresh[1571]: Refreshing group entry cache Sep 12 17:45:23.343942 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 12 17:45:23.345635 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 12 17:45:23.346147 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 12 17:45:23.346508 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 12 17:45:23.347195 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 12 17:45:23.350839 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Failure getting groups, quitting Sep 12 17:45:23.350839 google_oslogin_nss_cache[1571]: oslogin_cache_refresh[1571]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 12 17:45:23.350290 oslogin_cache_refresh[1571]: Failure getting groups, quitting Sep 12 17:45:23.350300 oslogin_cache_refresh[1571]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 12 17:45:23.353400 extend-filesystems[1570]: Checking size of /dev/vda9 Sep 12 17:45:23.355805 jq[1582]: true Sep 12 17:45:23.356773 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 12 17:45:23.357523 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 12 17:45:23.359567 systemd[1]: motdgen.service: Deactivated successfully. Sep 12 17:45:23.361208 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 12 17:45:23.365550 update_engine[1580]: I20250912 17:45:23.365154 1580 main.cc:92] Flatcar Update Engine starting Sep 12 17:45:23.368459 extend-filesystems[1570]: Resized partition /dev/vda9 Sep 12 17:45:23.373417 (ntainerd)[1595]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 12 17:45:23.384116 jq[1598]: true Sep 12 17:45:23.384322 extend-filesystems[1604]: resize2fs 1.47.2 (1-Jan-2025) Sep 12 17:45:23.393889 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Sep 12 17:45:23.407392 dbus-daemon[1567]: [system] SELinux support is enabled Sep 12 17:45:23.407545 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 12 17:45:23.412439 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Sep 12 17:45:23.413160 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 12 17:45:23.414448 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Sep 12 17:45:23.414462 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 12 17:45:23.421657 systemd[1]: Started update-engine.service - Update Engine. Sep 12 17:45:23.424374 update_engine[1580]: I20250912 17:45:23.423249 1580 update_check_scheduler.cc:74] Next update check in 10m5s Sep 12 17:45:23.425313 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 12 17:45:23.425319 systemd-logind[1575]: Watching system buttons on /dev/input/event2 (Power Button) Sep 12 17:45:23.425339 systemd-logind[1575]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 12 17:45:23.425638 systemd-logind[1575]: New seat seat0. Sep 12 17:45:23.431189 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Sep 12 17:45:23.434361 systemd[1]: Started systemd-logind.service - User Login Management. Sep 12 17:45:23.459447 extend-filesystems[1604]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Sep 12 17:45:23.459447 extend-filesystems[1604]: old_desc_blocks = 1, new_desc_blocks = 1 Sep 12 17:45:23.459447 extend-filesystems[1604]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Sep 12 17:45:23.485274 extend-filesystems[1570]: Resized filesystem in /dev/vda9 Sep 12 17:45:23.487304 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 12 17:45:23.487743 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 12 17:45:23.525483 sshd_keygen[1594]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 12 17:45:23.572492 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 12 17:45:23.575941 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 12 17:45:23.577382 bash[1633]: Updated "/home/core/.ssh/authorized_keys" Sep 12 17:45:23.580455 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 12 17:45:23.583047 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 12 17:45:23.584023 locksmithd[1614]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 12 17:45:23.605591 systemd[1]: issuegen.service: Deactivated successfully. Sep 12 17:45:23.605863 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 12 17:45:23.608591 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 12 17:45:23.644545 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 12 17:45:23.647458 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 12 17:45:23.649554 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 12 17:45:23.651166 systemd[1]: Reached target getty.target - Login Prompts. Sep 12 17:45:23.712300 containerd[1595]: time="2025-09-12T17:45:23Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 12 17:45:23.713501 containerd[1595]: time="2025-09-12T17:45:23.713442934Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 12 17:45:23.725946 containerd[1595]: time="2025-09-12T17:45:23.725884331Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.649µs" Sep 12 17:45:23.725946 containerd[1595]: time="2025-09-12T17:45:23.725914778Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 12 17:45:23.725946 containerd[1595]: time="2025-09-12T17:45:23.725932462Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 12 17:45:23.726309 containerd[1595]: time="2025-09-12T17:45:23.726278480Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 12 17:45:23.726309 containerd[1595]: time="2025-09-12T17:45:23.726299560Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 12 17:45:23.726353 containerd[1595]: time="2025-09-12T17:45:23.726322633Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:45:23.726412 containerd[1595]: time="2025-09-12T17:45:23.726390360Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 12 17:45:23.726412 containerd[1595]: time="2025-09-12T17:45:23.726406009Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:45:23.726738 containerd[1595]: time="2025-09-12T17:45:23.726706733Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 12 17:45:23.726738 containerd[1595]: time="2025-09-12T17:45:23.726724927Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:45:23.726738 containerd[1595]: time="2025-09-12T17:45:23.726735056Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 12 17:45:23.726803 containerd[1595]: time="2025-09-12T17:45:23.726743502Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 12 17:45:23.726863 containerd[1595]: time="2025-09-12T17:45:23.726843329Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 12 17:45:23.727117 containerd[1595]: time="2025-09-12T17:45:23.727081536Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:45:23.727161 containerd[1595]: time="2025-09-12T17:45:23.727140176Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 12 17:45:23.727161 containerd[1595]: time="2025-09-12T17:45:23.727155154Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 12 17:45:23.727201 containerd[1595]: time="2025-09-12T17:45:23.727191212Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 12 17:45:23.727451 containerd[1595]: time="2025-09-12T17:45:23.727409511Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 12 17:45:23.727523 containerd[1595]: time="2025-09-12T17:45:23.727494440Z" level=info msg="metadata content store policy set" policy=shared Sep 12 17:45:23.733470 containerd[1595]: time="2025-09-12T17:45:23.733433947Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 12 17:45:23.733470 containerd[1595]: time="2025-09-12T17:45:23.733470235Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 12 17:45:23.733536 containerd[1595]: time="2025-09-12T17:45:23.733484522Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 12 17:45:23.733536 containerd[1595]: time="2025-09-12T17:45:23.733496965Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 12 17:45:23.733536 containerd[1595]: time="2025-09-12T17:45:23.733508907Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 12 17:45:23.733536 containerd[1595]: time="2025-09-12T17:45:23.733533744Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 12 17:45:23.733620 containerd[1595]: time="2025-09-12T17:45:23.733550806Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 12 17:45:23.733620 containerd[1595]: time="2025-09-12T17:45:23.733562668Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 12 17:45:23.733620 containerd[1595]: time="2025-09-12T17:45:23.733572206Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 12 17:45:23.733620 containerd[1595]: time="2025-09-12T17:45:23.733584389Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 12 17:45:23.733620 containerd[1595]: time="2025-09-12T17:45:23.733592935Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 12 17:45:23.733620 containerd[1595]: time="2025-09-12T17:45:23.733603565Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 12 17:45:23.733738 containerd[1595]: time="2025-09-12T17:45:23.733711958Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 12 17:45:23.733738 containerd[1595]: time="2025-09-12T17:45:23.733729842Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 12 17:45:23.733782 containerd[1595]: time="2025-09-12T17:45:23.733743748Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 12 17:45:23.733782 containerd[1595]: time="2025-09-12T17:45:23.733755650Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 12 17:45:23.733782 containerd[1595]: time="2025-09-12T17:45:23.733765909Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 12 17:45:23.733782 containerd[1595]: time="2025-09-12T17:45:23.733776199Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 12 17:45:23.733862 containerd[1595]: time="2025-09-12T17:45:23.733788722Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 12 17:45:23.733862 containerd[1595]: time="2025-09-12T17:45:23.733800384Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 12 17:45:23.733862 containerd[1595]: time="2025-09-12T17:45:23.733812567Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 12 17:45:23.733862 containerd[1595]: time="2025-09-12T17:45:23.733822024Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 12 17:45:23.733862 containerd[1595]: time="2025-09-12T17:45:23.733831582Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 12 17:45:23.733957 containerd[1595]: time="2025-09-12T17:45:23.733894049Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 12 17:45:23.733957 containerd[1595]: time="2025-09-12T17:45:23.733906643Z" level=info msg="Start snapshots syncer" Sep 12 17:45:23.733957 containerd[1595]: time="2025-09-12T17:45:23.733931269Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 12 17:45:23.734260 containerd[1595]: time="2025-09-12T17:45:23.734214380Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Sep 12 17:45:23.734468 containerd[1595]: time="2025-09-12T17:45:23.734267259Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 12 17:45:23.737950 containerd[1595]: time="2025-09-12T17:45:23.737915009Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 12 17:45:23.738067 containerd[1595]: time="2025-09-12T17:45:23.738036396Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 12 17:45:23.738067 containerd[1595]: time="2025-09-12T17:45:23.738063106Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 12 17:45:23.738141 containerd[1595]: time="2025-09-12T17:45:23.738073716Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 12 17:45:23.738141 containerd[1595]: time="2025-09-12T17:45:23.738109714Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 12 17:45:23.738141 containerd[1595]: time="2025-09-12T17:45:23.738123319Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 12 17:45:23.738141 containerd[1595]: time="2025-09-12T17:45:23.738133649Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 12 17:45:23.738212 containerd[1595]: time="2025-09-12T17:45:23.738143317Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 12 17:45:23.738212 containerd[1595]: time="2025-09-12T17:45:23.738165108Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 12 17:45:23.738212 containerd[1595]: time="2025-09-12T17:45:23.738175758Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 12 17:45:23.738212 containerd[1595]: time="2025-09-12T17:45:23.738185266Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 12 17:45:23.738292 containerd[1595]: time="2025-09-12T17:45:23.738217766Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:45:23.738292 containerd[1595]: time="2025-09-12T17:45:23.738230671Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 12 17:45:23.738292 containerd[1595]: time="2025-09-12T17:45:23.738238746Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:45:23.738292 containerd[1595]: time="2025-09-12T17:45:23.738247833Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 12 17:45:23.738292 containerd[1595]: time="2025-09-12T17:45:23.738255327Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 12 17:45:23.738292 containerd[1595]: time="2025-09-12T17:45:23.738264143Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 12 17:45:23.738292 containerd[1595]: time="2025-09-12T17:45:23.738274563Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 12 17:45:23.738419 containerd[1595]: time="2025-09-12T17:45:23.738308767Z" level=info msg="runtime interface created" Sep 12 17:45:23.738419 containerd[1595]: time="2025-09-12T17:45:23.738315450Z" level=info msg="created NRI interface" Sep 12 17:45:23.738419 containerd[1595]: time="2025-09-12T17:45:23.738336038Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 12 17:45:23.738419 containerd[1595]: time="2025-09-12T17:45:23.738348872Z" level=info msg="Connect containerd service" Sep 12 17:45:23.738419 containerd[1595]: time="2025-09-12T17:45:23.738372176Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 12 17:45:23.739228 containerd[1595]: time="2025-09-12T17:45:23.739192875Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Sep 12 17:45:23.950222 containerd[1595]: time="2025-09-12T17:45:23.950041837Z" level=info msg="Start subscribing containerd event" Sep 12 17:45:23.950342 containerd[1595]: time="2025-09-12T17:45:23.950206585Z" level=info msg="Start recovering state" Sep 12 17:45:23.950392 containerd[1595]: time="2025-09-12T17:45:23.950350525Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 12 17:45:23.950472 containerd[1595]: time="2025-09-12T17:45:23.950444742Z" level=info msg="Start event monitor" Sep 12 17:45:23.950497 containerd[1595]: time="2025-09-12T17:45:23.950490428Z" level=info msg="Start cni network conf syncer for default" Sep 12 17:45:23.950518 containerd[1595]: time="2025-09-12T17:45:23.950501478Z" level=info msg="Start streaming server" Sep 12 17:45:23.950518 containerd[1595]: time="2025-09-12T17:45:23.950514523Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 12 17:45:23.950566 containerd[1595]: time="2025-09-12T17:45:23.950527818Z" level=info msg="runtime interface starting up..." Sep 12 17:45:23.950566 containerd[1595]: time="2025-09-12T17:45:23.950537847Z" level=info msg="starting plugins..." Sep 12 17:45:23.950566 containerd[1595]: time="2025-09-12T17:45:23.950557023Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 12 17:45:23.950628 containerd[1595]: time="2025-09-12T17:45:23.950446195Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 12 17:45:23.952453 containerd[1595]: time="2025-09-12T17:45:23.950781564Z" level=info msg="containerd successfully booted in 0.238964s" Sep 12 17:45:23.950902 systemd[1]: Started containerd.service - containerd container runtime. Sep 12 17:45:24.846425 systemd-networkd[1467]: eth0: Gained IPv6LL Sep 12 17:45:24.849618 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 12 17:45:24.851485 systemd[1]: Reached target network-online.target - Network is Online. Sep 12 17:45:24.854161 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 12 17:45:24.856796 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 12 17:45:24.859333 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 12 17:45:24.881997 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 12 17:45:24.882389 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 12 17:45:24.884072 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 12 17:45:24.890662 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 12 17:45:25.675045 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 12 17:45:25.676638 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 12 17:45:25.678051 systemd[1]: Startup finished in 4.274s (kernel) + 7.432s (initrd) + 5.067s (userspace) = 16.773s. Sep 12 17:45:25.680787 (kubelet)[1695]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Sep 12 17:45:26.408861 kubelet[1695]: E0912 17:45:26.408746 1695 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" Sep 12 17:45:26.413009 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Sep 12 17:45:26.413262 systemd[1]: kubelet.service: Failed with result 'exit-code'. Sep 12 17:45:26.413703 systemd[1]: kubelet.service: Consumed 1.389s CPU time, 265.5M memory peak. Sep 12 17:45:26.657379 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 12 17:45:26.658608 systemd[1]: Started sshd@0-10.0.0.86:22-10.0.0.1:51634.service - OpenSSH per-connection server daemon (10.0.0.1:51634). Sep 12 17:45:26.737116 sshd[1709]: Accepted publickey for core from 10.0.0.1 port 51634 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:45:26.739199 sshd-session[1709]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:45:26.746436 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 12 17:45:26.747631 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 12 17:45:26.754506 systemd-logind[1575]: New session 1 of user core. Sep 12 17:45:26.771915 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 12 17:45:26.775019 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 12 17:45:26.796715 (systemd)[1714]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 12 17:45:26.799357 systemd-logind[1575]: New session c1 of user core. Sep 12 17:45:26.954455 systemd[1714]: Queued start job for default target default.target. Sep 12 17:45:26.972524 systemd[1714]: Created slice app.slice - User Application Slice. Sep 12 17:45:26.972572 systemd[1714]: Reached target paths.target - Paths. Sep 12 17:45:26.972632 systemd[1714]: Reached target timers.target - Timers. Sep 12 17:45:26.976175 systemd[1714]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 12 17:45:26.989110 systemd[1714]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 12 17:45:26.989250 systemd[1714]: Reached target sockets.target - Sockets. Sep 12 17:45:26.989288 systemd[1714]: Reached target basic.target - Basic System. Sep 12 17:45:26.989328 systemd[1714]: Reached target default.target - Main User Target. Sep 12 17:45:26.989372 systemd[1714]: Startup finished in 182ms. Sep 12 17:45:26.989601 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 12 17:45:26.991247 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 12 17:45:27.059633 systemd[1]: Started sshd@1-10.0.0.86:22-10.0.0.1:51640.service - OpenSSH per-connection server daemon (10.0.0.1:51640). Sep 12 17:45:27.119594 sshd[1725]: Accepted publickey for core from 10.0.0.1 port 51640 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:45:27.121661 sshd-session[1725]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:45:27.128264 systemd-logind[1575]: New session 2 of user core. Sep 12 17:45:27.138250 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 12 17:45:27.190964 sshd[1728]: Connection closed by 10.0.0.1 port 51640 Sep 12 17:45:27.191371 sshd-session[1725]: pam_unix(sshd:session): session closed for user core Sep 12 17:45:27.209664 systemd[1]: sshd@1-10.0.0.86:22-10.0.0.1:51640.service: Deactivated successfully. Sep 12 17:45:27.211493 systemd[1]: session-2.scope: Deactivated successfully. Sep 12 17:45:27.212154 systemd-logind[1575]: Session 2 logged out. Waiting for processes to exit. Sep 12 17:45:27.214291 systemd-logind[1575]: Removed session 2. Sep 12 17:45:27.216160 systemd[1]: Started sshd@2-10.0.0.86:22-10.0.0.1:51644.service - OpenSSH per-connection server daemon (10.0.0.1:51644). Sep 12 17:45:27.266703 sshd[1734]: Accepted publickey for core from 10.0.0.1 port 51644 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:45:27.267900 sshd-session[1734]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:45:27.272146 systemd-logind[1575]: New session 3 of user core. Sep 12 17:45:27.282234 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 12 17:45:27.331817 sshd[1737]: Connection closed by 10.0.0.1 port 51644 Sep 12 17:45:27.332211 sshd-session[1734]: pam_unix(sshd:session): session closed for user core Sep 12 17:45:27.355629 systemd[1]: sshd@2-10.0.0.86:22-10.0.0.1:51644.service: Deactivated successfully. Sep 12 17:45:27.357345 systemd[1]: session-3.scope: Deactivated successfully. Sep 12 17:45:27.357990 systemd-logind[1575]: Session 3 logged out. Waiting for processes to exit. Sep 12 17:45:27.360555 systemd[1]: Started sshd@3-10.0.0.86:22-10.0.0.1:51650.service - OpenSSH per-connection server daemon (10.0.0.1:51650). Sep 12 17:45:27.361043 systemd-logind[1575]: Removed session 3. Sep 12 17:45:27.417978 sshd[1743]: Accepted publickey for core from 10.0.0.1 port 51650 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:45:27.419445 sshd-session[1743]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:45:27.423647 systemd-logind[1575]: New session 4 of user core. Sep 12 17:45:27.433277 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 12 17:45:27.486289 sshd[1746]: Connection closed by 10.0.0.1 port 51650 Sep 12 17:45:27.486751 sshd-session[1743]: pam_unix(sshd:session): session closed for user core Sep 12 17:45:27.495408 systemd[1]: sshd@3-10.0.0.86:22-10.0.0.1:51650.service: Deactivated successfully. Sep 12 17:45:27.496915 systemd[1]: session-4.scope: Deactivated successfully. Sep 12 17:45:27.497620 systemd-logind[1575]: Session 4 logged out. Waiting for processes to exit. Sep 12 17:45:27.499850 systemd[1]: Started sshd@4-10.0.0.86:22-10.0.0.1:51656.service - OpenSSH per-connection server daemon (10.0.0.1:51656). Sep 12 17:45:27.500478 systemd-logind[1575]: Removed session 4. Sep 12 17:45:27.539877 sshd[1752]: Accepted publickey for core from 10.0.0.1 port 51656 ssh2: RSA SHA256:fiC/i3IODFTUvy597QlN9UclswHBzEHPUbvMhtWvcQE Sep 12 17:45:27.541120 sshd-session[1752]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 12 17:45:27.545181 systemd-logind[1575]: New session 5 of user core. Sep 12 17:45:27.559219 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 12 17:45:27.615745 sudo[1756]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Sep 12 17:45:27.616062 sudo[1756]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 12 17:45:27.628819 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Sep 12 17:45:27.679870 systemd[1]: coreos-metadata.service: Deactivated successfully. Sep 12 17:45:27.680256 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Sep 12 17:45:28.380884 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 12 17:45:28.381047 systemd[1]: kubelet.service: Consumed 1.389s CPU time, 265.5M memory peak. Sep 12 17:45:28.383384 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 12 17:45:28.412633 systemd[1]: Reload requested from client PID 1798 ('systemctl') (unit session-5.scope)... Sep 12 17:45:28.412647 systemd[1]: Reloading... Sep 12 17:45:28.511136 zram_generator::config[1839]: No configuration found. Sep 12 17:45:28.854427 systemd[1]: Reloading finished in 441 ms. Sep 12 17:45:28.919857 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Sep 12 17:45:28.920004 systemd[1]: kubelet.service: Failed with result 'signal'. Sep 12 17:45:28.920420 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Sep 12 17:45:28.921261 systemd[1]: kubelet.service: Consumed 164ms CPU time, 98.2M memory peak. Sep 12 17:45:28.923239 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Sep 12 17:45:29.097961 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Sep 12 17:45:29.107261 (kubelet)[1887]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Sep 12 17:45:29.240648 kubelet[1887]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 12 17:45:29.240648 kubelet[1887]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Sep 12 17:45:29.240648 kubelet[1887]: Flag --volume-plugin-dir has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Sep 12 17:45:29.241017 kubelet[1887]: I0912 17:45:29.240693 1887 server.go:211] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Sep 12 17:45:29.467112 kubelet[1887]: I0912 17:45:29.466996 1887 server.go:491] "Kubelet version" kubeletVersion="v1.31.8" Sep 12 17:45:29.467112 kubelet[1887]: I0912 17:45:29.467025 1887 server.go:493] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Sep 12 17:45:29.467345 kubelet[1887]: I0912 17:45:29.467322 1887 server.go:934] "Client rotation is on, will bootstrap in background" Sep 12 17:45:29.488521 kubelet[1887]: I0912 17:45:29.488477 1887 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Sep 12 17:45:29.495470 kubelet[1887]: I0912 17:45:29.495434 1887 server.go:1431] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Sep 12 17:45:29.505455 kubelet[1887]: I0912 17:45:29.505418 1887 server.go:749] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Sep 12 17:45:29.506382 kubelet[1887]: I0912 17:45:29.506350 1887 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" Sep 12 17:45:29.506611 kubelet[1887]: I0912 17:45:29.506578 1887 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Sep 12 17:45:29.506898 kubelet[1887]: I0912 17:45:29.506604 1887 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.86","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} Sep 12 17:45:29.507010 kubelet[1887]: I0912 17:45:29.506911 1887 topology_manager.go:138] "Creating topology manager with none policy" Sep 12 17:45:29.507010 kubelet[1887]: I0912 17:45:29.506923 1887 container_manager_linux.go:300] "Creating device plugin manager" Sep 12 17:45:29.507071 kubelet[1887]: I0912 17:45:29.507055 1887 state_mem.go:36] "Initialized new in-memory state store" Sep 12 17:45:29.509453 kubelet[1887]: I0912 17:45:29.509432 1887 kubelet.go:408] "Attempting to sync node with API server" Sep 12 17:45:29.509453 kubelet[1887]: I0912 17:45:29.509454 1887 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" Sep 12 17:45:29.509519 kubelet[1887]: I0912 17:45:29.509506 1887 kubelet.go:314] "Adding apiserver pod source" Sep 12 17:45:29.509543 kubelet[1887]: I0912 17:45:29.509526 1887 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Sep 12 17:45:29.509657 kubelet[1887]: E0912 17:45:29.509553 1887 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:29.509657 kubelet[1887]: E0912 17:45:29.509609 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:29.511802 kubelet[1887]: I0912 17:45:29.511778 1887 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v2.0.5" apiVersion="v1" Sep 12 17:45:29.512226 kubelet[1887]: I0912 17:45:29.512208 1887 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Sep 12 17:45:29.512294 kubelet[1887]: W0912 17:45:29.512271 1887 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Sep 12 17:45:29.513976 kubelet[1887]: I0912 17:45:29.513947 1887 server.go:1274] "Started kubelet" Sep 12 17:45:29.514085 kubelet[1887]: I0912 17:45:29.514049 1887 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Sep 12 17:45:29.516234 kubelet[1887]: I0912 17:45:29.515288 1887 server.go:449] "Adding debug handlers to kubelet server" Sep 12 17:45:29.516234 kubelet[1887]: I0912 17:45:29.515424 1887 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Sep 12 17:45:29.516496 kubelet[1887]: I0912 17:45:29.516461 1887 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Sep 12 17:45:29.516702 kubelet[1887]: I0912 17:45:29.516686 1887 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Sep 12 17:45:29.517193 kubelet[1887]: I0912 17:45:29.517151 1887 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Sep 12 17:45:29.521263 kubelet[1887]: E0912 17:45:29.520371 1887 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.86\" not found" Sep 12 17:45:29.521263 kubelet[1887]: I0912 17:45:29.520410 1887 volume_manager.go:289] "Starting Kubelet Volume Manager" Sep 12 17:45:29.521263 kubelet[1887]: I0912 17:45:29.520556 1887 desired_state_of_world_populator.go:147] "Desired state populator starts to run" Sep 12 17:45:29.521263 kubelet[1887]: I0912 17:45:29.520597 1887 reconciler.go:26] "Reconciler: start to sync state" Sep 12 17:45:29.522508 kubelet[1887]: I0912 17:45:29.522481 1887 factory.go:221] Registration of the systemd container factory successfully Sep 12 17:45:29.522588 kubelet[1887]: I0912 17:45:29.522560 1887 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Sep 12 17:45:29.525297 kubelet[1887]: E0912 17:45:29.525228 1887 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.86\" not found" node="10.0.0.86" Sep 12 17:45:29.525361 kubelet[1887]: I0912 17:45:29.525314 1887 factory.go:221] Registration of the containerd container factory successfully Sep 12 17:45:29.525414 kubelet[1887]: E0912 17:45:29.525273 1887 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Sep 12 17:45:29.539823 kubelet[1887]: I0912 17:45:29.539789 1887 cpu_manager.go:214] "Starting CPU manager" policy="none" Sep 12 17:45:29.539823 kubelet[1887]: I0912 17:45:29.539807 1887 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Sep 12 17:45:29.539823 kubelet[1887]: I0912 17:45:29.539823 1887 state_mem.go:36] "Initialized new in-memory state store" Sep 12 17:45:29.550193 kubelet[1887]: I0912 17:45:29.550165 1887 policy_none.go:49] "None policy: Start" Sep 12 17:45:29.551018 kubelet[1887]: I0912 17:45:29.550985 1887 memory_manager.go:170] "Starting memorymanager" policy="None" Sep 12 17:45:29.551132 kubelet[1887]: I0912 17:45:29.551121 1887 state_mem.go:35] "Initializing new in-memory state store" Sep 12 17:45:29.557579 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Sep 12 17:45:29.568168 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Sep 12 17:45:29.571667 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Sep 12 17:45:29.581114 kubelet[1887]: I0912 17:45:29.581051 1887 manager.go:513] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Sep 12 17:45:29.581325 kubelet[1887]: I0912 17:45:29.581303 1887 eviction_manager.go:189] "Eviction manager: starting control loop" Sep 12 17:45:29.581363 kubelet[1887]: I0912 17:45:29.581324 1887 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Sep 12 17:45:29.582534 kubelet[1887]: I0912 17:45:29.582510 1887 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Sep 12 17:45:29.583585 kubelet[1887]: E0912 17:45:29.583562 1887 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.86\" not found" Sep 12 17:45:29.585313 kubelet[1887]: I0912 17:45:29.585260 1887 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Sep 12 17:45:29.587223 kubelet[1887]: I0912 17:45:29.587188 1887 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Sep 12 17:45:29.587223 kubelet[1887]: I0912 17:45:29.587218 1887 status_manager.go:217] "Starting to sync pod status with apiserver" Sep 12 17:45:29.587373 kubelet[1887]: I0912 17:45:29.587246 1887 kubelet.go:2321] "Starting kubelet main sync loop" Sep 12 17:45:29.587373 kubelet[1887]: E0912 17:45:29.587298 1887 kubelet.go:2345] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Sep 12 17:45:29.682896 kubelet[1887]: I0912 17:45:29.682852 1887 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.86" Sep 12 17:45:29.688251 kubelet[1887]: I0912 17:45:29.688199 1887 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.86" Sep 12 17:45:29.695920 kubelet[1887]: I0912 17:45:29.695886 1887 kuberuntime_manager.go:1635] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Sep 12 17:45:29.696429 containerd[1595]: time="2025-09-12T17:45:29.696342679Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Sep 12 17:45:29.696843 kubelet[1887]: I0912 17:45:29.696623 1887 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Sep 12 17:45:30.123072 sudo[1756]: pam_unix(sudo:session): session closed for user root Sep 12 17:45:30.124870 sshd[1755]: Connection closed by 10.0.0.1 port 51656 Sep 12 17:45:30.125331 sshd-session[1752]: pam_unix(sshd:session): session closed for user core Sep 12 17:45:30.129439 systemd[1]: sshd@4-10.0.0.86:22-10.0.0.1:51656.service: Deactivated successfully. Sep 12 17:45:30.131831 systemd[1]: session-5.scope: Deactivated successfully. Sep 12 17:45:30.132066 systemd[1]: session-5.scope: Consumed 775ms CPU time, 75.7M memory peak. Sep 12 17:45:30.133298 systemd-logind[1575]: Session 5 logged out. Waiting for processes to exit. Sep 12 17:45:30.134593 systemd-logind[1575]: Removed session 5. Sep 12 17:45:30.469411 kubelet[1887]: I0912 17:45:30.469253 1887 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Sep 12 17:45:30.469840 kubelet[1887]: W0912 17:45:30.469496 1887 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 12 17:45:30.469840 kubelet[1887]: W0912 17:45:30.469548 1887 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 12 17:45:30.469840 kubelet[1887]: W0912 17:45:30.469576 1887 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Sep 12 17:45:30.510208 kubelet[1887]: I0912 17:45:30.510166 1887 apiserver.go:52] "Watching apiserver" Sep 12 17:45:30.510208 kubelet[1887]: E0912 17:45:30.510217 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:30.519917 systemd[1]: Created slice kubepods-besteffort-pode58adcc2_555e_486c_b92b_2c1bf2a442a9.slice - libcontainer container kubepods-besteffort-pode58adcc2_555e_486c_b92b_2c1bf2a442a9.slice. Sep 12 17:45:30.521108 kubelet[1887]: I0912 17:45:30.521073 1887 desired_state_of_world_populator.go:155] "Finished populating initial desired state of world" Sep 12 17:45:30.527000 kubelet[1887]: I0912 17:45:30.526971 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/706fd9d7-714d-418c-98ab-bd8d9af634f1-xtables-lock\") pod \"kube-flannel-ds-fjwpn\" (UID: \"706fd9d7-714d-418c-98ab-bd8d9af634f1\") " pod="kube-flannel/kube-flannel-ds-fjwpn" Sep 12 17:45:30.527076 kubelet[1887]: I0912 17:45:30.527003 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zvqpz\" (UniqueName: \"kubernetes.io/projected/706fd9d7-714d-418c-98ab-bd8d9af634f1-kube-api-access-zvqpz\") pod \"kube-flannel-ds-fjwpn\" (UID: \"706fd9d7-714d-418c-98ab-bd8d9af634f1\") " pod="kube-flannel/kube-flannel-ds-fjwpn" Sep 12 17:45:30.527076 kubelet[1887]: I0912 17:45:30.527025 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/e58adcc2-555e-486c-b92b-2c1bf2a442a9-kube-proxy\") pod \"kube-proxy-lzjsv\" (UID: \"e58adcc2-555e-486c-b92b-2c1bf2a442a9\") " pod="kube-system/kube-proxy-lzjsv" Sep 12 17:45:30.527076 kubelet[1887]: I0912 17:45:30.527061 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/e58adcc2-555e-486c-b92b-2c1bf2a442a9-xtables-lock\") pod \"kube-proxy-lzjsv\" (UID: \"e58adcc2-555e-486c-b92b-2c1bf2a442a9\") " pod="kube-system/kube-proxy-lzjsv" Sep 12 17:45:30.527076 kubelet[1887]: I0912 17:45:30.527077 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-649kx\" (UniqueName: \"kubernetes.io/projected/e58adcc2-555e-486c-b92b-2c1bf2a442a9-kube-api-access-649kx\") pod \"kube-proxy-lzjsv\" (UID: \"e58adcc2-555e-486c-b92b-2c1bf2a442a9\") " pod="kube-system/kube-proxy-lzjsv" Sep 12 17:45:30.527204 kubelet[1887]: I0912 17:45:30.527118 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/706fd9d7-714d-418c-98ab-bd8d9af634f1-cni-plugin\") pod \"kube-flannel-ds-fjwpn\" (UID: \"706fd9d7-714d-418c-98ab-bd8d9af634f1\") " pod="kube-flannel/kube-flannel-ds-fjwpn" Sep 12 17:45:30.527228 kubelet[1887]: I0912 17:45:30.527191 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/706fd9d7-714d-418c-98ab-bd8d9af634f1-cni\") pod \"kube-flannel-ds-fjwpn\" (UID: \"706fd9d7-714d-418c-98ab-bd8d9af634f1\") " pod="kube-flannel/kube-flannel-ds-fjwpn" Sep 12 17:45:30.527301 kubelet[1887]: I0912 17:45:30.527272 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/706fd9d7-714d-418c-98ab-bd8d9af634f1-flannel-cfg\") pod \"kube-flannel-ds-fjwpn\" (UID: \"706fd9d7-714d-418c-98ab-bd8d9af634f1\") " pod="kube-flannel/kube-flannel-ds-fjwpn" Sep 12 17:45:30.527332 kubelet[1887]: I0912 17:45:30.527306 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/e58adcc2-555e-486c-b92b-2c1bf2a442a9-lib-modules\") pod \"kube-proxy-lzjsv\" (UID: \"e58adcc2-555e-486c-b92b-2c1bf2a442a9\") " pod="kube-system/kube-proxy-lzjsv" Sep 12 17:45:30.527362 kubelet[1887]: I0912 17:45:30.527330 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/706fd9d7-714d-418c-98ab-bd8d9af634f1-run\") pod \"kube-flannel-ds-fjwpn\" (UID: \"706fd9d7-714d-418c-98ab-bd8d9af634f1\") " pod="kube-flannel/kube-flannel-ds-fjwpn" Sep 12 17:45:30.533547 systemd[1]: Created slice kubepods-burstable-pod706fd9d7_714d_418c_98ab_bd8d9af634f1.slice - libcontainer container kubepods-burstable-pod706fd9d7_714d_418c_98ab_bd8d9af634f1.slice. Sep 12 17:45:30.831539 kubelet[1887]: E0912 17:45:30.831487 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:30.832446 containerd[1595]: time="2025-09-12T17:45:30.832401409Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-lzjsv,Uid:e58adcc2-555e-486c-b92b-2c1bf2a442a9,Namespace:kube-system,Attempt:0,}" Sep 12 17:45:30.845422 kubelet[1887]: E0912 17:45:30.845383 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:30.845932 containerd[1595]: time="2025-09-12T17:45:30.845884580Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-fjwpn,Uid:706fd9d7-714d-418c-98ab-bd8d9af634f1,Namespace:kube-flannel,Attempt:0,}" Sep 12 17:45:31.394580 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1944107200.mount: Deactivated successfully. Sep 12 17:45:31.405452 containerd[1595]: time="2025-09-12T17:45:31.405400662Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 12 17:45:31.407085 containerd[1595]: time="2025-09-12T17:45:31.407063099Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321138" Sep 12 17:45:31.408230 containerd[1595]: time="2025-09-12T17:45:31.408193729Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.10\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 12 17:45:31.409122 containerd[1595]: time="2025-09-12T17:45:31.409067007Z" level=info msg="ImageCreate event name:\"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 12 17:45:31.409921 containerd[1595]: time="2025-09-12T17:45:31.409889309Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Sep 12 17:45:31.411617 containerd[1595]: time="2025-09-12T17:45:31.411587032Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Sep 12 17:45:31.412047 containerd[1595]: time="2025-09-12T17:45:31.411990007Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 526.643867ms" Sep 12 17:45:31.413402 containerd[1595]: time="2025-09-12T17:45:31.413373652Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.10\" with image id \"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\", repo tag \"registry.k8s.io/pause:3.10\", repo digest \"registry.k8s.io/pause@sha256:ee6521f290b2168b6e0935a181d4cff9be1ac3f505666ef0e3c98fae8199917a\", size \"320368\" in 571.671634ms" Sep 12 17:45:31.441896 containerd[1595]: time="2025-09-12T17:45:31.441836750Z" level=info msg="connecting to shim cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78" address="unix:///run/containerd/s/bfc57d8e05b2603ae402adfb0f06738cf418543050dfd6399c996a4e0345aca5" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:45:31.445314 containerd[1595]: time="2025-09-12T17:45:31.445211678Z" level=info msg="connecting to shim 0fb5aa9f2eeaf74604639dca801d8fe4f322d185f5dad4dba37bf4820b5c8ac4" address="unix:///run/containerd/s/65db96dc164fdb7e3c49b4ee06dd38299ea9dc6b97f91fb76e8e98d608c6ed17" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:45:31.510639 kubelet[1887]: E0912 17:45:31.510572 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:31.576275 systemd[1]: Started cri-containerd-cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78.scope - libcontainer container cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78. Sep 12 17:45:31.580554 systemd[1]: Started cri-containerd-0fb5aa9f2eeaf74604639dca801d8fe4f322d185f5dad4dba37bf4820b5c8ac4.scope - libcontainer container 0fb5aa9f2eeaf74604639dca801d8fe4f322d185f5dad4dba37bf4820b5c8ac4. Sep 12 17:45:31.677916 containerd[1595]: time="2025-09-12T17:45:31.677451788Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-lzjsv,Uid:e58adcc2-555e-486c-b92b-2c1bf2a442a9,Namespace:kube-system,Attempt:0,} returns sandbox id \"0fb5aa9f2eeaf74604639dca801d8fe4f322d185f5dad4dba37bf4820b5c8ac4\"" Sep 12 17:45:31.680024 kubelet[1887]: E0912 17:45:31.680001 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:31.681085 containerd[1595]: time="2025-09-12T17:45:31.681047630Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.13\"" Sep 12 17:45:31.683912 containerd[1595]: time="2025-09-12T17:45:31.683881424Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-fjwpn,Uid:706fd9d7-714d-418c-98ab-bd8d9af634f1,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78\"" Sep 12 17:45:31.684620 kubelet[1887]: E0912 17:45:31.684587 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:32.511143 kubelet[1887]: E0912 17:45:32.511070 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:33.114695 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount633140937.mount: Deactivated successfully. Sep 12 17:45:33.512278 kubelet[1887]: E0912 17:45:33.512051 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:33.794955 containerd[1595]: time="2025-09-12T17:45:33.794874846Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.13\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:33.795640 containerd[1595]: time="2025-09-12T17:45:33.795580469Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.13: active requests=0, bytes read=30410252" Sep 12 17:45:33.796791 containerd[1595]: time="2025-09-12T17:45:33.796743670Z" level=info msg="ImageCreate event name:\"sha256:21d97a49eeb0b08ecaba421a84a79ca44cf2bc57773c085bbfda537488790ad7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:33.799546 containerd[1595]: time="2025-09-12T17:45:33.799503214Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:a39637326e88d128d38da6ff2b2ceb4e856475887bfcb5f7a55734d4f63d9fae\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:33.800158 containerd[1595]: time="2025-09-12T17:45:33.800119950Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.13\" with image id \"sha256:21d97a49eeb0b08ecaba421a84a79ca44cf2bc57773c085bbfda537488790ad7\", repo tag \"registry.k8s.io/kube-proxy:v1.31.13\", repo digest \"registry.k8s.io/kube-proxy@sha256:a39637326e88d128d38da6ff2b2ceb4e856475887bfcb5f7a55734d4f63d9fae\", size \"30409271\" in 2.119015173s" Sep 12 17:45:33.800205 containerd[1595]: time="2025-09-12T17:45:33.800161118Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.13\" returns image reference \"sha256:21d97a49eeb0b08ecaba421a84a79ca44cf2bc57773c085bbfda537488790ad7\"" Sep 12 17:45:33.801474 containerd[1595]: time="2025-09-12T17:45:33.801441398Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Sep 12 17:45:33.803271 containerd[1595]: time="2025-09-12T17:45:33.803223650Z" level=info msg="CreateContainer within sandbox \"0fb5aa9f2eeaf74604639dca801d8fe4f322d185f5dad4dba37bf4820b5c8ac4\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Sep 12 17:45:33.814536 containerd[1595]: time="2025-09-12T17:45:33.814484985Z" level=info msg="Container e00e16607d5a809220599c29a61a368800805c2a89af5f3b0a6b9d3b7a87179e: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:45:33.824909 containerd[1595]: time="2025-09-12T17:45:33.824852714Z" level=info msg="CreateContainer within sandbox \"0fb5aa9f2eeaf74604639dca801d8fe4f322d185f5dad4dba37bf4820b5c8ac4\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"e00e16607d5a809220599c29a61a368800805c2a89af5f3b0a6b9d3b7a87179e\"" Sep 12 17:45:33.825620 containerd[1595]: time="2025-09-12T17:45:33.825582212Z" level=info msg="StartContainer for \"e00e16607d5a809220599c29a61a368800805c2a89af5f3b0a6b9d3b7a87179e\"" Sep 12 17:45:33.827232 containerd[1595]: time="2025-09-12T17:45:33.827199655Z" level=info msg="connecting to shim e00e16607d5a809220599c29a61a368800805c2a89af5f3b0a6b9d3b7a87179e" address="unix:///run/containerd/s/65db96dc164fdb7e3c49b4ee06dd38299ea9dc6b97f91fb76e8e98d608c6ed17" protocol=ttrpc version=3 Sep 12 17:45:33.864307 systemd[1]: Started cri-containerd-e00e16607d5a809220599c29a61a368800805c2a89af5f3b0a6b9d3b7a87179e.scope - libcontainer container e00e16607d5a809220599c29a61a368800805c2a89af5f3b0a6b9d3b7a87179e. Sep 12 17:45:33.950890 containerd[1595]: time="2025-09-12T17:45:33.950832807Z" level=info msg="StartContainer for \"e00e16607d5a809220599c29a61a368800805c2a89af5f3b0a6b9d3b7a87179e\" returns successfully" Sep 12 17:45:34.512270 kubelet[1887]: E0912 17:45:34.512230 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:34.599552 kubelet[1887]: E0912 17:45:34.599506 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:35.513318 kubelet[1887]: E0912 17:45:35.513283 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:35.600508 kubelet[1887]: E0912 17:45:35.600483 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:36.514304 kubelet[1887]: E0912 17:45:36.514230 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:37.201967 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount259690795.mount: Deactivated successfully. Sep 12 17:45:37.238357 containerd[1595]: time="2025-09-12T17:45:37.238311809Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:37.239069 containerd[1595]: time="2025-09-12T17:45:37.239035376Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Sep 12 17:45:37.240063 containerd[1595]: time="2025-09-12T17:45:37.240034159Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:37.241891 containerd[1595]: time="2025-09-12T17:45:37.241859471Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:37.242531 containerd[1595]: time="2025-09-12T17:45:37.242502717Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 3.441021955s" Sep 12 17:45:37.242581 containerd[1595]: time="2025-09-12T17:45:37.242534367Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Sep 12 17:45:37.244225 containerd[1595]: time="2025-09-12T17:45:37.244199949Z" level=info msg="CreateContainer within sandbox \"cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Sep 12 17:45:37.252403 containerd[1595]: time="2025-09-12T17:45:37.252370058Z" level=info msg="Container 3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:45:37.259468 containerd[1595]: time="2025-09-12T17:45:37.259427080Z" level=info msg="CreateContainer within sandbox \"cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e\"" Sep 12 17:45:37.259945 containerd[1595]: time="2025-09-12T17:45:37.259916758Z" level=info msg="StartContainer for \"3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e\"" Sep 12 17:45:37.260819 containerd[1595]: time="2025-09-12T17:45:37.260795376Z" level=info msg="connecting to shim 3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e" address="unix:///run/containerd/s/bfc57d8e05b2603ae402adfb0f06738cf418543050dfd6399c996a4e0345aca5" protocol=ttrpc version=3 Sep 12 17:45:37.283238 systemd[1]: Started cri-containerd-3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e.scope - libcontainer container 3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e. Sep 12 17:45:37.311181 systemd[1]: cri-containerd-3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e.scope: Deactivated successfully. Sep 12 17:45:37.314006 containerd[1595]: time="2025-09-12T17:45:37.313969818Z" level=info msg="StartContainer for \"3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e\" returns successfully" Sep 12 17:45:37.314463 containerd[1595]: time="2025-09-12T17:45:37.314440140Z" level=info msg="TaskExit event in podsandbox handler container_id:\"3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e\" id:\"3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e\" pid:2221 exited_at:{seconds:1757699137 nanos:313870872}" Sep 12 17:45:37.314589 containerd[1595]: time="2025-09-12T17:45:37.314536731Z" level=info msg="received exit event container_id:\"3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e\" id:\"3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e\" pid:2221 exited_at:{seconds:1757699137 nanos:313870872}" Sep 12 17:45:37.515152 kubelet[1887]: E0912 17:45:37.514974 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:37.605510 kubelet[1887]: E0912 17:45:37.605459 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:37.617734 kubelet[1887]: I0912 17:45:37.617612 1887 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-lzjsv" podStartSLOduration=6.497146625 podStartE2EDuration="8.617598762s" podCreationTimestamp="2025-09-12 17:45:29 +0000 UTC" firstStartedPulling="2025-09-12 17:45:31.680654894 +0000 UTC m=+2.479810475" lastFinishedPulling="2025-09-12 17:45:33.801107031 +0000 UTC m=+4.600262612" observedRunningTime="2025-09-12 17:45:34.610024481 +0000 UTC m=+5.409180062" watchObservedRunningTime="2025-09-12 17:45:37.617598762 +0000 UTC m=+8.416754343" Sep 12 17:45:38.136351 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3253d6c82b0abba97ba459e22f027922ea8aa110fe38cfee735281e3515beb7e-rootfs.mount: Deactivated successfully. Sep 12 17:45:38.515320 kubelet[1887]: E0912 17:45:38.515185 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:38.608327 kubelet[1887]: E0912 17:45:38.608277 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:38.609365 containerd[1595]: time="2025-09-12T17:45:38.609327914Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Sep 12 17:45:39.516286 kubelet[1887]: E0912 17:45:39.516239 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:40.428156 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3108876588.mount: Deactivated successfully. Sep 12 17:45:40.517345 kubelet[1887]: E0912 17:45:40.517289 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:41.518396 kubelet[1887]: E0912 17:45:41.518360 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:42.107804 containerd[1595]: time="2025-09-12T17:45:42.107749924Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:42.108564 containerd[1595]: time="2025-09-12T17:45:42.108496464Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Sep 12 17:45:42.109677 containerd[1595]: time="2025-09-12T17:45:42.109645348Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:42.112418 containerd[1595]: time="2025-09-12T17:45:42.112367031Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:42.113341 containerd[1595]: time="2025-09-12T17:45:42.113287107Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 3.503916933s" Sep 12 17:45:42.113341 containerd[1595]: time="2025-09-12T17:45:42.113339475Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Sep 12 17:45:42.115557 containerd[1595]: time="2025-09-12T17:45:42.115516657Z" level=info msg="CreateContainer within sandbox \"cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Sep 12 17:45:42.124983 containerd[1595]: time="2025-09-12T17:45:42.124926681Z" level=info msg="Container fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:45:42.135114 containerd[1595]: time="2025-09-12T17:45:42.134299154Z" level=info msg="CreateContainer within sandbox \"cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd\"" Sep 12 17:45:42.137012 containerd[1595]: time="2025-09-12T17:45:42.136979370Z" level=info msg="StartContainer for \"fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd\"" Sep 12 17:45:42.137945 containerd[1595]: time="2025-09-12T17:45:42.137906167Z" level=info msg="connecting to shim fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd" address="unix:///run/containerd/s/bfc57d8e05b2603ae402adfb0f06738cf418543050dfd6399c996a4e0345aca5" protocol=ttrpc version=3 Sep 12 17:45:42.192243 systemd[1]: Started cri-containerd-fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd.scope - libcontainer container fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd. Sep 12 17:45:42.224972 systemd[1]: cri-containerd-fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd.scope: Deactivated successfully. Sep 12 17:45:42.225783 containerd[1595]: time="2025-09-12T17:45:42.225419735Z" level=info msg="TaskExit event in podsandbox handler container_id:\"fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd\" id:\"fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd\" pid:2291 exited_at:{seconds:1757699142 nanos:225118711}" Sep 12 17:45:42.227771 containerd[1595]: time="2025-09-12T17:45:42.227713426Z" level=info msg="received exit event container_id:\"fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd\" id:\"fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd\" pid:2291 exited_at:{seconds:1757699142 nanos:225118711}" Sep 12 17:45:42.228824 containerd[1595]: time="2025-09-12T17:45:42.228794142Z" level=info msg="StartContainer for \"fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd\" returns successfully" Sep 12 17:45:42.247161 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-fa84f0a8d1e142f430106774b74bdc3f81d4975b3b707eae27ec9794a220b1cd-rootfs.mount: Deactivated successfully. Sep 12 17:45:42.319172 kubelet[1887]: I0912 17:45:42.319142 1887 kubelet_node_status.go:488] "Fast updating node status as it just became ready" Sep 12 17:45:42.518855 kubelet[1887]: E0912 17:45:42.518731 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:42.615610 kubelet[1887]: E0912 17:45:42.615566 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:42.617172 containerd[1595]: time="2025-09-12T17:45:42.617131024Z" level=info msg="CreateContainer within sandbox \"cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Sep 12 17:45:42.626678 containerd[1595]: time="2025-09-12T17:45:42.626635154Z" level=info msg="Container c2ccdf20fd211b59f24f6e7ee82c92a761c04733ab17c93364f3d811df0284dd: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:45:42.634894 containerd[1595]: time="2025-09-12T17:45:42.634854686Z" level=info msg="CreateContainer within sandbox \"cbf362e2c69a8f7ec9a02572dcadfab52a46465837140571df9ff26c84f6fc78\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"c2ccdf20fd211b59f24f6e7ee82c92a761c04733ab17c93364f3d811df0284dd\"" Sep 12 17:45:42.635371 containerd[1595]: time="2025-09-12T17:45:42.635339875Z" level=info msg="StartContainer for \"c2ccdf20fd211b59f24f6e7ee82c92a761c04733ab17c93364f3d811df0284dd\"" Sep 12 17:45:42.636276 containerd[1595]: time="2025-09-12T17:45:42.636250282Z" level=info msg="connecting to shim c2ccdf20fd211b59f24f6e7ee82c92a761c04733ab17c93364f3d811df0284dd" address="unix:///run/containerd/s/bfc57d8e05b2603ae402adfb0f06738cf418543050dfd6399c996a4e0345aca5" protocol=ttrpc version=3 Sep 12 17:45:42.665274 systemd[1]: Started cri-containerd-c2ccdf20fd211b59f24f6e7ee82c92a761c04733ab17c93364f3d811df0284dd.scope - libcontainer container c2ccdf20fd211b59f24f6e7ee82c92a761c04733ab17c93364f3d811df0284dd. Sep 12 17:45:42.710928 containerd[1595]: time="2025-09-12T17:45:42.710890964Z" level=info msg="StartContainer for \"c2ccdf20fd211b59f24f6e7ee82c92a761c04733ab17c93364f3d811df0284dd\" returns successfully" Sep 12 17:45:43.126979 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount634213575.mount: Deactivated successfully. Sep 12 17:45:43.519238 kubelet[1887]: E0912 17:45:43.519079 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:43.619354 kubelet[1887]: E0912 17:45:43.619286 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:43.628910 kubelet[1887]: I0912 17:45:43.628845 1887 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-fjwpn" podStartSLOduration=4.200124188 podStartE2EDuration="14.628819459s" podCreationTimestamp="2025-09-12 17:45:29 +0000 UTC" firstStartedPulling="2025-09-12 17:45:31.685390173 +0000 UTC m=+2.484545744" lastFinishedPulling="2025-09-12 17:45:42.114085433 +0000 UTC m=+12.913241015" observedRunningTime="2025-09-12 17:45:43.62847321 +0000 UTC m=+14.427628791" watchObservedRunningTime="2025-09-12 17:45:43.628819459 +0000 UTC m=+14.427975040" Sep 12 17:45:43.762158 systemd-networkd[1467]: flannel.1: Link UP Sep 12 17:45:43.762170 systemd-networkd[1467]: flannel.1: Gained carrier Sep 12 17:45:44.520082 kubelet[1887]: E0912 17:45:44.520009 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:44.621236 kubelet[1887]: E0912 17:45:44.621197 1887 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" Sep 12 17:45:45.520441 kubelet[1887]: E0912 17:45:45.520360 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:45.710379 systemd-networkd[1467]: flannel.1: Gained IPv6LL Sep 12 17:45:46.521656 kubelet[1887]: E0912 17:45:46.521548 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:47.262599 systemd[1]: Created slice kubepods-besteffort-pod8ba34b66_9075_49a3_85aa_3a783db033c8.slice - libcontainer container kubepods-besteffort-pod8ba34b66_9075_49a3_85aa_3a783db033c8.slice. Sep 12 17:45:47.455610 kubelet[1887]: I0912 17:45:47.455521 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-mdpzj\" (UniqueName: \"kubernetes.io/projected/8ba34b66-9075-49a3-85aa-3a783db033c8-kube-api-access-mdpzj\") pod \"nginx-deployment-8587fbcb89-79r4d\" (UID: \"8ba34b66-9075-49a3-85aa-3a783db033c8\") " pod="default/nginx-deployment-8587fbcb89-79r4d" Sep 12 17:45:47.522405 kubelet[1887]: E0912 17:45:47.522290 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:47.865411 containerd[1595]: time="2025-09-12T17:45:47.865355984Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-79r4d,Uid:8ba34b66-9075-49a3-85aa-3a783db033c8,Namespace:default,Attempt:0,}" Sep 12 17:45:47.875413 systemd-networkd[1467]: cni0: Link UP Sep 12 17:45:47.875424 systemd-networkd[1467]: cni0: Gained carrier Sep 12 17:45:47.880328 systemd-networkd[1467]: cni0: Lost carrier Sep 12 17:45:47.884586 systemd-networkd[1467]: veth1d9b5f01: Link UP Sep 12 17:45:47.886749 kernel: cni0: port 1(veth1d9b5f01) entered blocking state Sep 12 17:45:47.886810 kernel: cni0: port 1(veth1d9b5f01) entered disabled state Sep 12 17:45:47.886847 kernel: veth1d9b5f01: entered allmulticast mode Sep 12 17:45:47.889343 kernel: veth1d9b5f01: entered promiscuous mode Sep 12 17:45:47.897210 kernel: cni0: port 1(veth1d9b5f01) entered blocking state Sep 12 17:45:47.897347 kernel: cni0: port 1(veth1d9b5f01) entered forwarding state Sep 12 17:45:47.897239 systemd-networkd[1467]: veth1d9b5f01: Gained carrier Sep 12 17:45:47.897689 systemd-networkd[1467]: cni0: Gained carrier Sep 12 17:45:47.903419 containerd[1595]: 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)(0xc00001e938), "name":"cbr0", "type":"bridge"} Sep 12 17:45:47.903419 containerd[1595]: delegateAdd: netconf sent to delegate plugin: Sep 12 17:45:47.931849 containerd[1595]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-12T17:45:47.931780620Z" level=info msg="connecting to shim b389f0aa59fe58ea1b4cf40d5442f0e2066f6ecb77c85a2ad7f1accb97c71ce2" address="unix:///run/containerd/s/77afd4c511d2cd56c52f46c797e66f25a6c8d79d8779405dbce9b6a492e1ea6b" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:45:47.960227 systemd[1]: Started cri-containerd-b389f0aa59fe58ea1b4cf40d5442f0e2066f6ecb77c85a2ad7f1accb97c71ce2.scope - libcontainer container b389f0aa59fe58ea1b4cf40d5442f0e2066f6ecb77c85a2ad7f1accb97c71ce2. Sep 12 17:45:47.972292 systemd-resolved[1411]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 17:45:48.006485 containerd[1595]: time="2025-09-12T17:45:48.006413667Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-79r4d,Uid:8ba34b66-9075-49a3-85aa-3a783db033c8,Namespace:default,Attempt:0,} returns sandbox id \"b389f0aa59fe58ea1b4cf40d5442f0e2066f6ecb77c85a2ad7f1accb97c71ce2\"" Sep 12 17:45:48.007973 containerd[1595]: time="2025-09-12T17:45:48.007941051Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 12 17:45:48.523410 kubelet[1887]: E0912 17:45:48.523359 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:49.038239 systemd-networkd[1467]: cni0: Gained IPv6LL Sep 12 17:45:49.230258 systemd-networkd[1467]: veth1d9b5f01: Gained IPv6LL Sep 12 17:45:49.510487 kubelet[1887]: E0912 17:45:49.510356 1887 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:49.523823 kubelet[1887]: E0912 17:45:49.523774 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:50.524392 kubelet[1887]: E0912 17:45:50.524340 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:51.524662 kubelet[1887]: E0912 17:45:51.524608 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:52.504245 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3386208618.mount: Deactivated successfully. Sep 12 17:45:52.525626 kubelet[1887]: E0912 17:45:52.525574 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:53.526280 kubelet[1887]: E0912 17:45:53.526234 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:53.559223 containerd[1595]: time="2025-09-12T17:45:53.559169022Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:53.559903 containerd[1595]: time="2025-09-12T17:45:53.559836935Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73307810" Sep 12 17:45:53.560995 containerd[1595]: time="2025-09-12T17:45:53.560964839Z" level=info msg="ImageCreate event name:\"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:53.563204 containerd[1595]: time="2025-09-12T17:45:53.563171176Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:45:53.563912 containerd[1595]: time="2025-09-12T17:45:53.563879023Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"73307688\" in 5.555913126s" Sep 12 17:45:53.563948 containerd[1595]: time="2025-09-12T17:45:53.563913187Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\"" Sep 12 17:45:53.566856 containerd[1595]: time="2025-09-12T17:45:53.566796965Z" level=info msg="CreateContainer within sandbox \"b389f0aa59fe58ea1b4cf40d5442f0e2066f6ecb77c85a2ad7f1accb97c71ce2\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Sep 12 17:45:53.577991 containerd[1595]: time="2025-09-12T17:45:53.577919319Z" level=info msg="Container c62ad257198f1e4386026dfb549444d8dd8184bf9ab0650fe9e855d34b43f7ff: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:45:53.586702 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2460728605.mount: Deactivated successfully. Sep 12 17:45:53.588965 containerd[1595]: time="2025-09-12T17:45:53.588673703Z" level=info msg="CreateContainer within sandbox \"b389f0aa59fe58ea1b4cf40d5442f0e2066f6ecb77c85a2ad7f1accb97c71ce2\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"c62ad257198f1e4386026dfb549444d8dd8184bf9ab0650fe9e855d34b43f7ff\"" Sep 12 17:45:53.590671 containerd[1595]: time="2025-09-12T17:45:53.590631084Z" level=info msg="StartContainer for \"c62ad257198f1e4386026dfb549444d8dd8184bf9ab0650fe9e855d34b43f7ff\"" Sep 12 17:45:53.592226 containerd[1595]: time="2025-09-12T17:45:53.592147747Z" level=info msg="connecting to shim c62ad257198f1e4386026dfb549444d8dd8184bf9ab0650fe9e855d34b43f7ff" address="unix:///run/containerd/s/77afd4c511d2cd56c52f46c797e66f25a6c8d79d8779405dbce9b6a492e1ea6b" protocol=ttrpc version=3 Sep 12 17:45:53.674230 systemd[1]: Started cri-containerd-c62ad257198f1e4386026dfb549444d8dd8184bf9ab0650fe9e855d34b43f7ff.scope - libcontainer container c62ad257198f1e4386026dfb549444d8dd8184bf9ab0650fe9e855d34b43f7ff. Sep 12 17:45:53.715184 containerd[1595]: time="2025-09-12T17:45:53.715140008Z" level=info msg="StartContainer for \"c62ad257198f1e4386026dfb549444d8dd8184bf9ab0650fe9e855d34b43f7ff\" returns successfully" Sep 12 17:45:54.526650 kubelet[1887]: E0912 17:45:54.526562 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:54.646985 kubelet[1887]: I0912 17:45:54.646918 1887 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-79r4d" podStartSLOduration=3.089804876 podStartE2EDuration="8.646893345s" podCreationTimestamp="2025-09-12 17:45:46 +0000 UTC" firstStartedPulling="2025-09-12 17:45:48.007603268 +0000 UTC m=+18.806758849" lastFinishedPulling="2025-09-12 17:45:53.564691737 +0000 UTC m=+24.363847318" observedRunningTime="2025-09-12 17:45:54.646879639 +0000 UTC m=+25.446035210" watchObservedRunningTime="2025-09-12 17:45:54.646893345 +0000 UTC m=+25.446048926" Sep 12 17:45:55.527308 kubelet[1887]: E0912 17:45:55.527244 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:56.527559 kubelet[1887]: E0912 17:45:56.527502 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:57.528516 kubelet[1887]: E0912 17:45:57.528443 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:58.529317 kubelet[1887]: E0912 17:45:58.529256 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:45:58.676591 systemd[1]: Created slice kubepods-besteffort-podd306b21d_acc7_48fc_8ef0_33041c24e1ca.slice - libcontainer container kubepods-besteffort-podd306b21d_acc7_48fc_8ef0_33041c24e1ca.slice. Sep 12 17:45:58.824747 kubelet[1887]: I0912 17:45:58.824599 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/d306b21d-acc7-48fc-8ef0-33041c24e1ca-data\") pod \"nfs-server-provisioner-0\" (UID: \"d306b21d-acc7-48fc-8ef0-33041c24e1ca\") " pod="default/nfs-server-provisioner-0" Sep 12 17:45:58.824747 kubelet[1887]: I0912 17:45:58.824645 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9s2nf\" (UniqueName: \"kubernetes.io/projected/d306b21d-acc7-48fc-8ef0-33041c24e1ca-kube-api-access-9s2nf\") pod \"nfs-server-provisioner-0\" (UID: \"d306b21d-acc7-48fc-8ef0-33041c24e1ca\") " pod="default/nfs-server-provisioner-0" Sep 12 17:45:58.980940 containerd[1595]: time="2025-09-12T17:45:58.980879368Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d306b21d-acc7-48fc-8ef0-33041c24e1ca,Namespace:default,Attempt:0,}" Sep 12 17:45:59.114238 systemd-networkd[1467]: veth6af9e323: Link UP Sep 12 17:45:59.117150 kernel: cni0: port 2(veth6af9e323) entered blocking state Sep 12 17:45:59.117208 kernel: cni0: port 2(veth6af9e323) entered disabled state Sep 12 17:45:59.117241 kernel: veth6af9e323: entered allmulticast mode Sep 12 17:45:59.118420 kernel: veth6af9e323: entered promiscuous mode Sep 12 17:45:59.126259 kernel: cni0: port 2(veth6af9e323) entered blocking state Sep 12 17:45:59.126305 kernel: cni0: port 2(veth6af9e323) entered forwarding state Sep 12 17:45:59.126988 systemd-networkd[1467]: veth6af9e323: Gained carrier Sep 12 17:45:59.130041 containerd[1595]: 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)(0xc0000b68e8), "name":"cbr0", "type":"bridge"} Sep 12 17:45:59.130041 containerd[1595]: delegateAdd: netconf sent to delegate plugin: Sep 12 17:45:59.167243 containerd[1595]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-12T17:45:59.167178444Z" level=info msg="connecting to shim f38cf23236ff39e6ff079b0a68026f425f3dbfb6e75b611ae8251edeec6e9e63" address="unix:///run/containerd/s/a242ff1a097cb45c6f895c32fb8a4ea6a1ba1d9b2e1aa86e8b028ff781b4236f" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:45:59.200237 systemd[1]: Started cri-containerd-f38cf23236ff39e6ff079b0a68026f425f3dbfb6e75b611ae8251edeec6e9e63.scope - libcontainer container f38cf23236ff39e6ff079b0a68026f425f3dbfb6e75b611ae8251edeec6e9e63. Sep 12 17:45:59.214594 systemd-resolved[1411]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 17:45:59.249742 containerd[1595]: time="2025-09-12T17:45:59.249701104Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:d306b21d-acc7-48fc-8ef0-33041c24e1ca,Namespace:default,Attempt:0,} returns sandbox id \"f38cf23236ff39e6ff079b0a68026f425f3dbfb6e75b611ae8251edeec6e9e63\"" Sep 12 17:45:59.251318 containerd[1595]: time="2025-09-12T17:45:59.251272465Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Sep 12 17:45:59.529959 kubelet[1887]: E0912 17:45:59.529915 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:00.590169 kubelet[1887]: E0912 17:46:00.590070 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:00.738041 systemd-networkd[1467]: veth6af9e323: Gained IPv6LL Sep 12 17:46:01.591001 kubelet[1887]: E0912 17:46:01.590962 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:01.894424 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2797494744.mount: Deactivated successfully. Sep 12 17:46:02.591825 kubelet[1887]: E0912 17:46:02.591780 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:03.592046 kubelet[1887]: E0912 17:46:03.591986 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:04.080601 containerd[1595]: time="2025-09-12T17:46:04.080532735Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:46:04.081334 containerd[1595]: time="2025-09-12T17:46:04.081268086Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Sep 12 17:46:04.082524 containerd[1595]: time="2025-09-12T17:46:04.082481097Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:46:04.084940 containerd[1595]: time="2025-09-12T17:46:04.084891068Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:46:04.085789 containerd[1595]: time="2025-09-12T17:46:04.085756376Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 4.834438624s" Sep 12 17:46:04.085843 containerd[1595]: time="2025-09-12T17:46:04.085787074Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Sep 12 17:46:04.087946 containerd[1595]: time="2025-09-12T17:46:04.087916931Z" level=info msg="CreateContainer within sandbox \"f38cf23236ff39e6ff079b0a68026f425f3dbfb6e75b611ae8251edeec6e9e63\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Sep 12 17:46:04.094762 containerd[1595]: time="2025-09-12T17:46:04.094715902Z" level=info msg="Container 3af979b7b9413c4be4c320289dac9db73e22f7e94cb95a623cef0c03a0b7cdff: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:46:04.098117 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3348749958.mount: Deactivated successfully. Sep 12 17:46:04.104254 containerd[1595]: time="2025-09-12T17:46:04.104215267Z" level=info msg="CreateContainer within sandbox \"f38cf23236ff39e6ff079b0a68026f425f3dbfb6e75b611ae8251edeec6e9e63\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"3af979b7b9413c4be4c320289dac9db73e22f7e94cb95a623cef0c03a0b7cdff\"" Sep 12 17:46:04.104798 containerd[1595]: time="2025-09-12T17:46:04.104725539Z" level=info msg="StartContainer for \"3af979b7b9413c4be4c320289dac9db73e22f7e94cb95a623cef0c03a0b7cdff\"" Sep 12 17:46:04.105859 containerd[1595]: time="2025-09-12T17:46:04.105817909Z" level=info msg="connecting to shim 3af979b7b9413c4be4c320289dac9db73e22f7e94cb95a623cef0c03a0b7cdff" address="unix:///run/containerd/s/a242ff1a097cb45c6f895c32fb8a4ea6a1ba1d9b2e1aa86e8b028ff781b4236f" protocol=ttrpc version=3 Sep 12 17:46:04.126221 systemd[1]: Started cri-containerd-3af979b7b9413c4be4c320289dac9db73e22f7e94cb95a623cef0c03a0b7cdff.scope - libcontainer container 3af979b7b9413c4be4c320289dac9db73e22f7e94cb95a623cef0c03a0b7cdff. Sep 12 17:46:04.153425 containerd[1595]: time="2025-09-12T17:46:04.153374568Z" level=info msg="StartContainer for \"3af979b7b9413c4be4c320289dac9db73e22f7e94cb95a623cef0c03a0b7cdff\" returns successfully" Sep 12 17:46:04.592836 kubelet[1887]: E0912 17:46:04.592781 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:04.762243 kubelet[1887]: I0912 17:46:04.762165 1887 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.926582588 podStartE2EDuration="6.762131109s" podCreationTimestamp="2025-09-12 17:45:58 +0000 UTC" firstStartedPulling="2025-09-12 17:45:59.250974234 +0000 UTC m=+30.050129815" lastFinishedPulling="2025-09-12 17:46:04.086522755 +0000 UTC m=+34.885678336" observedRunningTime="2025-09-12 17:46:04.76174283 +0000 UTC m=+35.560898411" watchObservedRunningTime="2025-09-12 17:46:04.762131109 +0000 UTC m=+35.561286690" Sep 12 17:46:05.593163 kubelet[1887]: E0912 17:46:05.593114 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:06.594242 kubelet[1887]: E0912 17:46:06.594189 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:07.594861 kubelet[1887]: E0912 17:46:07.594808 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:08.596000 kubelet[1887]: E0912 17:46:08.595928 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:08.909191 update_engine[1580]: I20250912 17:46:08.908951 1580 update_attempter.cc:509] Updating boot flags... Sep 12 17:46:09.510299 kubelet[1887]: E0912 17:46:09.510236 1887 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:09.596221 kubelet[1887]: E0912 17:46:09.596168 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:10.596354 kubelet[1887]: E0912 17:46:10.596274 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:11.597463 kubelet[1887]: E0912 17:46:11.597414 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:12.597620 kubelet[1887]: E0912 17:46:12.597562 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:13.598494 kubelet[1887]: E0912 17:46:13.598442 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:14.019848 systemd[1]: Created slice kubepods-besteffort-pod8eb52e26_d05c_45cd_a4c5_42632f105e12.slice - libcontainer container kubepods-besteffort-pod8eb52e26_d05c_45cd_a4c5_42632f105e12.slice. Sep 12 17:46:14.196236 kubelet[1887]: I0912 17:46:14.196165 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-c5b65ff2-0dfd-4fd4-a3cc-9e062f32ec99\" (UniqueName: \"kubernetes.io/nfs/8eb52e26-d05c-45cd-a4c5-42632f105e12-pvc-c5b65ff2-0dfd-4fd4-a3cc-9e062f32ec99\") pod \"test-pod-1\" (UID: \"8eb52e26-d05c-45cd-a4c5-42632f105e12\") " pod="default/test-pod-1" Sep 12 17:46:14.196236 kubelet[1887]: I0912 17:46:14.196215 1887 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-zxltt\" (UniqueName: \"kubernetes.io/projected/8eb52e26-d05c-45cd-a4c5-42632f105e12-kube-api-access-zxltt\") pod \"test-pod-1\" (UID: \"8eb52e26-d05c-45cd-a4c5-42632f105e12\") " pod="default/test-pod-1" Sep 12 17:46:14.331137 kernel: netfs: FS-Cache loaded Sep 12 17:46:14.398550 kernel: RPC: Registered named UNIX socket transport module. Sep 12 17:46:14.398598 kernel: RPC: Registered udp transport module. Sep 12 17:46:14.398659 kernel: RPC: Registered tcp transport module. Sep 12 17:46:14.398675 kernel: RPC: Registered tcp-with-tls transport module. Sep 12 17:46:14.399152 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 12 17:46:14.598799 kubelet[1887]: E0912 17:46:14.598654 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:14.648391 kernel: NFS: Registering the id_resolver key type Sep 12 17:46:14.648532 kernel: Key type id_resolver registered Sep 12 17:46:14.648561 kernel: Key type id_legacy registered Sep 12 17:46:14.677204 nfsidmap[2961]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Sep 12 17:46:14.677699 nfsidmap[2961]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 12 17:46:14.681587 nfsidmap[2965]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf Sep 12 17:46:14.681758 nfsidmap[2965]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Sep 12 17:46:14.697230 nfsrahead[2969]: setting /var/lib/kubelet/pods/8eb52e26-d05c-45cd-a4c5-42632f105e12/volumes/kubernetes.io~nfs/pvc-c5b65ff2-0dfd-4fd4-a3cc-9e062f32ec99 readahead to 128 Sep 12 17:46:14.923723 containerd[1595]: time="2025-09-12T17:46:14.923580994Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8eb52e26-d05c-45cd-a4c5-42632f105e12,Namespace:default,Attempt:0,}" Sep 12 17:46:14.941512 systemd-networkd[1467]: veth44e59585: Link UP Sep 12 17:46:14.943132 kernel: cni0: port 3(veth44e59585) entered blocking state Sep 12 17:46:14.943233 kernel: cni0: port 3(veth44e59585) entered disabled state Sep 12 17:46:14.945117 kernel: veth44e59585: entered allmulticast mode Sep 12 17:46:14.945149 kernel: veth44e59585: entered promiscuous mode Sep 12 17:46:14.956399 kernel: cni0: port 3(veth44e59585) entered blocking state Sep 12 17:46:14.956482 kernel: cni0: port 3(veth44e59585) entered forwarding state Sep 12 17:46:14.956596 systemd-networkd[1467]: veth44e59585: Gained carrier Sep 12 17:46:14.972039 containerd[1595]: 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)(0xc00001e938), "name":"cbr0", "type":"bridge"} Sep 12 17:46:14.972039 containerd[1595]: delegateAdd: netconf sent to delegate plugin: Sep 12 17:46:15.008734 containerd[1595]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-09-12T17:46:15.008675863Z" level=info msg="connecting to shim 94bb79f04ab25815447841b9cfa51be05475560c2cd08a6922063e1676e2419a" address="unix:///run/containerd/s/d3b6dc80dc18ce00503019146fac5b0748019814db7d177c3f1384e89c10cc28" namespace=k8s.io protocol=ttrpc version=3 Sep 12 17:46:15.044238 systemd[1]: Started cri-containerd-94bb79f04ab25815447841b9cfa51be05475560c2cd08a6922063e1676e2419a.scope - libcontainer container 94bb79f04ab25815447841b9cfa51be05475560c2cd08a6922063e1676e2419a. Sep 12 17:46:15.056582 systemd-resolved[1411]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Sep 12 17:46:15.085005 containerd[1595]: time="2025-09-12T17:46:15.084953011Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:8eb52e26-d05c-45cd-a4c5-42632f105e12,Namespace:default,Attempt:0,} returns sandbox id \"94bb79f04ab25815447841b9cfa51be05475560c2cd08a6922063e1676e2419a\"" Sep 12 17:46:15.086607 containerd[1595]: time="2025-09-12T17:46:15.086567583Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Sep 12 17:46:15.484180 containerd[1595]: time="2025-09-12T17:46:15.484115918Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Sep 12 17:46:15.484925 containerd[1595]: time="2025-09-12T17:46:15.484857149Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Sep 12 17:46:15.487174 containerd[1595]: time="2025-09-12T17:46:15.487146284Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:883ca821a91fc20bcde818eeee4e1ed55ef63a020d6198ecd5a03af5a4eac530\", size \"73307688\" in 400.544446ms" Sep 12 17:46:15.487217 containerd[1595]: time="2025-09-12T17:46:15.487177483Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:4cbb30cb60f877a307c1f0bcdaca389dd24689ff60c6fb370f0cca7367185c48\"" Sep 12 17:46:15.489250 containerd[1595]: time="2025-09-12T17:46:15.489222768Z" level=info msg="CreateContainer within sandbox \"94bb79f04ab25815447841b9cfa51be05475560c2cd08a6922063e1676e2419a\" for container &ContainerMetadata{Name:test,Attempt:0,}" Sep 12 17:46:15.499046 containerd[1595]: time="2025-09-12T17:46:15.498983783Z" level=info msg="Container 78ef21f704ef4db45665b238be6aa0b223e62bfd37a9b10214c35054c28a7ee9: CDI devices from CRI Config.CDIDevices: []" Sep 12 17:46:15.507973 containerd[1595]: time="2025-09-12T17:46:15.507907957Z" level=info msg="CreateContainer within sandbox \"94bb79f04ab25815447841b9cfa51be05475560c2cd08a6922063e1676e2419a\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"78ef21f704ef4db45665b238be6aa0b223e62bfd37a9b10214c35054c28a7ee9\"" Sep 12 17:46:15.508536 containerd[1595]: time="2025-09-12T17:46:15.508457896Z" level=info msg="StartContainer for \"78ef21f704ef4db45665b238be6aa0b223e62bfd37a9b10214c35054c28a7ee9\"" Sep 12 17:46:15.509482 containerd[1595]: time="2025-09-12T17:46:15.509453617Z" level=info msg="connecting to shim 78ef21f704ef4db45665b238be6aa0b223e62bfd37a9b10214c35054c28a7ee9" address="unix:///run/containerd/s/d3b6dc80dc18ce00503019146fac5b0748019814db7d177c3f1384e89c10cc28" protocol=ttrpc version=3 Sep 12 17:46:15.539344 systemd[1]: Started cri-containerd-78ef21f704ef4db45665b238be6aa0b223e62bfd37a9b10214c35054c28a7ee9.scope - libcontainer container 78ef21f704ef4db45665b238be6aa0b223e62bfd37a9b10214c35054c28a7ee9. Sep 12 17:46:15.572427 containerd[1595]: time="2025-09-12T17:46:15.572368456Z" level=info msg="StartContainer for \"78ef21f704ef4db45665b238be6aa0b223e62bfd37a9b10214c35054c28a7ee9\" returns successfully" Sep 12 17:46:15.599991 kubelet[1887]: E0912 17:46:15.599176 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:15.781008 kubelet[1887]: I0912 17:46:15.780948 1887 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=17.379295682 podStartE2EDuration="17.780926982s" podCreationTimestamp="2025-09-12 17:45:58 +0000 UTC" firstStartedPulling="2025-09-12 17:46:15.086260472 +0000 UTC m=+45.885416053" lastFinishedPulling="2025-09-12 17:46:15.487891772 +0000 UTC m=+46.287047353" observedRunningTime="2025-09-12 17:46:15.780795023 +0000 UTC m=+46.579950604" watchObservedRunningTime="2025-09-12 17:46:15.780926982 +0000 UTC m=+46.580082553" Sep 12 17:46:16.238293 systemd-networkd[1467]: veth44e59585: Gained IPv6LL Sep 12 17:46:16.599597 kubelet[1887]: E0912 17:46:16.599553 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:17.600494 kubelet[1887]: E0912 17:46:17.600378 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:18.601398 kubelet[1887]: E0912 17:46:18.601340 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:19.602506 kubelet[1887]: E0912 17:46:19.602426 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:20.603666 kubelet[1887]: E0912 17:46:20.603582 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Sep 12 17:46:21.604442 kubelet[1887]: E0912 17:46:21.604380 1887 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"