Mar 21 12:42:46.897375 kernel: Linux version 6.6.83-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Fri Mar 21 10:52:59 -00 2025 Mar 21 12:42:46.897396 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=fb715041d083099c6a15c8aee7cc93fc3f3ca8764fc0aaaff245a06641d663d2 Mar 21 12:42:46.897406 kernel: BIOS-provided physical RAM map: Mar 21 12:42:46.897412 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000002ffff] usable Mar 21 12:42:46.897418 kernel: BIOS-e820: [mem 0x0000000000030000-0x000000000004ffff] reserved Mar 21 12:42:46.897427 kernel: BIOS-e820: [mem 0x0000000000050000-0x000000000009efff] usable Mar 21 12:42:46.897435 kernel: BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] reserved Mar 21 12:42:46.897441 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000009b8ecfff] usable Mar 21 12:42:46.897448 kernel: BIOS-e820: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Mar 21 12:42:46.897454 kernel: BIOS-e820: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Mar 21 12:42:46.897461 kernel: BIOS-e820: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Mar 21 12:42:46.897467 kernel: BIOS-e820: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Mar 21 12:42:46.897473 kernel: BIOS-e820: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Mar 21 12:42:46.897480 kernel: BIOS-e820: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Mar 21 12:42:46.897490 kernel: BIOS-e820: [mem 0x000000009bfb7000-0x000000009bffffff] usable Mar 21 12:42:46.897497 kernel: BIOS-e820: [mem 0x000000009c000000-0x000000009cffffff] reserved Mar 21 12:42:46.897504 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Mar 21 12:42:46.897511 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 21 12:42:46.897518 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 21 12:42:46.897527 kernel: NX (Execute Disable) protection: active Mar 21 12:42:46.897534 kernel: APIC: Static calls initialized Mar 21 12:42:46.897541 kernel: e820: update [mem 0x9a187018-0x9a190c57] usable ==> usable Mar 21 12:42:46.897548 kernel: e820: update [mem 0x9a187018-0x9a190c57] usable ==> usable Mar 21 12:42:46.897554 kernel: e820: update [mem 0x9a14a018-0x9a186e57] usable ==> usable Mar 21 12:42:46.897561 kernel: e820: update [mem 0x9a14a018-0x9a186e57] usable ==> usable Mar 21 12:42:46.897568 kernel: extended physical RAM map: Mar 21 12:42:46.897575 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000002ffff] usable Mar 21 12:42:46.897582 kernel: reserve setup_data: [mem 0x0000000000030000-0x000000000004ffff] reserved Mar 21 12:42:46.897588 kernel: reserve setup_data: [mem 0x0000000000050000-0x000000000009efff] usable Mar 21 12:42:46.897596 kernel: reserve setup_data: [mem 0x000000000009f000-0x000000000009ffff] reserved Mar 21 12:42:46.897605 kernel: reserve setup_data: [mem 0x0000000000100000-0x000000009a14a017] usable Mar 21 12:42:46.897611 kernel: reserve setup_data: [mem 0x000000009a14a018-0x000000009a186e57] usable Mar 21 12:42:46.897618 kernel: reserve setup_data: [mem 0x000000009a186e58-0x000000009a187017] usable Mar 21 12:42:46.897625 kernel: reserve setup_data: [mem 0x000000009a187018-0x000000009a190c57] usable Mar 21 12:42:46.897632 kernel: reserve setup_data: [mem 0x000000009a190c58-0x000000009b8ecfff] usable Mar 21 12:42:46.897639 kernel: reserve setup_data: [mem 0x000000009b8ed000-0x000000009bb6cfff] reserved Mar 21 12:42:46.897654 kernel: reserve setup_data: [mem 0x000000009bb6d000-0x000000009bb7efff] ACPI data Mar 21 12:42:46.897663 kernel: reserve setup_data: [mem 0x000000009bb7f000-0x000000009bbfefff] ACPI NVS Mar 21 12:42:46.897672 kernel: reserve setup_data: [mem 0x000000009bbff000-0x000000009bfb0fff] usable Mar 21 12:42:46.897682 kernel: reserve setup_data: [mem 0x000000009bfb1000-0x000000009bfb4fff] reserved Mar 21 12:42:46.897697 kernel: reserve setup_data: [mem 0x000000009bfb5000-0x000000009bfb6fff] ACPI NVS Mar 21 12:42:46.897707 kernel: reserve setup_data: [mem 0x000000009bfb7000-0x000000009bffffff] usable Mar 21 12:42:46.897716 kernel: reserve setup_data: [mem 0x000000009c000000-0x000000009cffffff] reserved Mar 21 12:42:46.897725 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Mar 21 12:42:46.897735 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 21 12:42:46.897744 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 21 12:42:46.897753 kernel: efi: EFI v2.7 by EDK II Mar 21 12:42:46.897760 kernel: efi: SMBIOS=0x9b9d5000 ACPI=0x9bb7e000 ACPI 2.0=0x9bb7e014 MEMATTR=0x9a1f7018 RNG=0x9bb73018 Mar 21 12:42:46.897767 kernel: random: crng init done Mar 21 12:42:46.897775 kernel: Kernel is locked down from EFI Secure Boot; see man kernel_lockdown.7 Mar 21 12:42:46.897782 kernel: secureboot: Secure boot enabled Mar 21 12:42:46.897789 kernel: SMBIOS 2.8 present. Mar 21 12:42:46.897796 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Mar 21 12:42:46.897803 kernel: Hypervisor detected: KVM Mar 21 12:42:46.897810 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Mar 21 12:42:46.897817 kernel: kvm-clock: using sched offset of 3864327287 cycles Mar 21 12:42:46.897825 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Mar 21 12:42:46.897835 kernel: tsc: Detected 2794.748 MHz processor Mar 21 12:42:46.897843 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 21 12:42:46.897850 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 21 12:42:46.897857 kernel: last_pfn = 0x9c000 max_arch_pfn = 0x400000000 Mar 21 12:42:46.897865 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Mar 21 12:42:46.897872 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 21 12:42:46.897879 kernel: Using GB pages for direct mapping Mar 21 12:42:46.897887 kernel: ACPI: Early table checksum verification disabled Mar 21 12:42:46.897894 kernel: ACPI: RSDP 0x000000009BB7E014 000024 (v02 BOCHS ) Mar 21 12:42:46.897904 kernel: ACPI: XSDT 0x000000009BB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Mar 21 12:42:46.897911 kernel: ACPI: FACP 0x000000009BB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:42:46.897918 kernel: ACPI: DSDT 0x000000009BB7A000 002225 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:42:46.897926 kernel: ACPI: FACS 0x000000009BBDD000 000040 Mar 21 12:42:46.897933 kernel: ACPI: APIC 0x000000009BB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:42:46.897941 kernel: ACPI: HPET 0x000000009BB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:42:46.897948 kernel: ACPI: MCFG 0x000000009BB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:42:46.897955 kernel: ACPI: WAET 0x000000009BB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 21 12:42:46.897963 kernel: ACPI: BGRT 0x000000009BB74000 000038 (v01 INTEL EDK2 00000002 01000013) Mar 21 12:42:46.897972 kernel: ACPI: Reserving FACP table memory at [mem 0x9bb79000-0x9bb790f3] Mar 21 12:42:46.897980 kernel: ACPI: Reserving DSDT table memory at [mem 0x9bb7a000-0x9bb7c224] Mar 21 12:42:46.897987 kernel: ACPI: Reserving FACS table memory at [mem 0x9bbdd000-0x9bbdd03f] Mar 21 12:42:46.897994 kernel: ACPI: Reserving APIC table memory at [mem 0x9bb78000-0x9bb7808f] Mar 21 12:42:46.898002 kernel: ACPI: Reserving HPET table memory at [mem 0x9bb77000-0x9bb77037] Mar 21 12:42:46.898009 kernel: ACPI: Reserving MCFG table memory at [mem 0x9bb76000-0x9bb7603b] Mar 21 12:42:46.898016 kernel: ACPI: Reserving WAET table memory at [mem 0x9bb75000-0x9bb75027] Mar 21 12:42:46.898023 kernel: ACPI: Reserving BGRT table memory at [mem 0x9bb74000-0x9bb74037] Mar 21 12:42:46.898031 kernel: No NUMA configuration found Mar 21 12:42:46.898041 kernel: Faking a node at [mem 0x0000000000000000-0x000000009bffffff] Mar 21 12:42:46.898048 kernel: NODE_DATA(0) allocated [mem 0x9bf59000-0x9bf5efff] Mar 21 12:42:46.898055 kernel: Zone ranges: Mar 21 12:42:46.898063 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 21 12:42:46.898070 kernel: DMA32 [mem 0x0000000001000000-0x000000009bffffff] Mar 21 12:42:46.898077 kernel: Normal empty Mar 21 12:42:46.898084 kernel: Movable zone start for each node Mar 21 12:42:46.898092 kernel: Early memory node ranges Mar 21 12:42:46.898099 kernel: node 0: [mem 0x0000000000001000-0x000000000002ffff] Mar 21 12:42:46.898106 kernel: node 0: [mem 0x0000000000050000-0x000000000009efff] Mar 21 12:42:46.898115 kernel: node 0: [mem 0x0000000000100000-0x000000009b8ecfff] Mar 21 12:42:46.898123 kernel: node 0: [mem 0x000000009bbff000-0x000000009bfb0fff] Mar 21 12:42:46.898130 kernel: node 0: [mem 0x000000009bfb7000-0x000000009bffffff] Mar 21 12:42:46.898137 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009bffffff] Mar 21 12:42:46.898144 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 21 12:42:46.898152 kernel: On node 0, zone DMA: 32 pages in unavailable ranges Mar 21 12:42:46.898159 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Mar 21 12:42:46.898166 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Mar 21 12:42:46.898174 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Mar 21 12:42:46.898184 kernel: On node 0, zone DMA32: 16384 pages in unavailable ranges Mar 21 12:42:46.898191 kernel: ACPI: PM-Timer IO Port: 0x608 Mar 21 12:42:46.898199 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Mar 21 12:42:46.898206 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Mar 21 12:42:46.898213 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Mar 21 12:42:46.898221 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Mar 21 12:42:46.898228 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 21 12:42:46.898235 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Mar 21 12:42:46.898243 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Mar 21 12:42:46.898252 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 21 12:42:46.898259 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Mar 21 12:42:46.898266 kernel: TSC deadline timer available Mar 21 12:42:46.898274 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Mar 21 12:42:46.898281 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Mar 21 12:42:46.898288 kernel: kvm-guest: KVM setup pv remote TLB flush Mar 21 12:42:46.898302 kernel: kvm-guest: setup PV sched yield Mar 21 12:42:46.898312 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Mar 21 12:42:46.898319 kernel: Booting paravirtualized kernel on KVM Mar 21 12:42:46.898327 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 21 12:42:46.898335 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Mar 21 12:42:46.898343 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288 Mar 21 12:42:46.898352 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152 Mar 21 12:42:46.898371 kernel: pcpu-alloc: [0] 0 1 2 3 Mar 21 12:42:46.898390 kernel: kvm-guest: PV spinlocks enabled Mar 21 12:42:46.898398 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 21 12:42:46.898407 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=fb715041d083099c6a15c8aee7cc93fc3f3ca8764fc0aaaff245a06641d663d2 Mar 21 12:42:46.898415 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 21 12:42:46.898423 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 21 12:42:46.898431 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 21 12:42:46.898441 kernel: Fallback order for Node 0: 0 Mar 21 12:42:46.898449 kernel: Built 1 zonelists, mobility grouping on. Total pages: 625927 Mar 21 12:42:46.898457 kernel: Policy zone: DMA32 Mar 21 12:42:46.898464 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 21 12:42:46.898472 kernel: Memory: 2368304K/2552216K available (14336K kernel code, 2304K rwdata, 25060K rodata, 43588K init, 1476K bss, 183656K reserved, 0K cma-reserved) Mar 21 12:42:46.898482 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 21 12:42:46.898490 kernel: ftrace: allocating 37985 entries in 149 pages Mar 21 12:42:46.898497 kernel: ftrace: allocated 149 pages with 4 groups Mar 21 12:42:46.898505 kernel: Dynamic Preempt: voluntary Mar 21 12:42:46.898512 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 21 12:42:46.898525 kernel: rcu: RCU event tracing is enabled. Mar 21 12:42:46.898533 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 21 12:42:46.898540 kernel: Trampoline variant of Tasks RCU enabled. Mar 21 12:42:46.898548 kernel: Rude variant of Tasks RCU enabled. Mar 21 12:42:46.898558 kernel: Tracing variant of Tasks RCU enabled. Mar 21 12:42:46.898565 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 21 12:42:46.898573 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 21 12:42:46.898581 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Mar 21 12:42:46.898588 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 21 12:42:46.898596 kernel: Console: colour dummy device 80x25 Mar 21 12:42:46.898603 kernel: printk: console [ttyS0] enabled Mar 21 12:42:46.898611 kernel: ACPI: Core revision 20230628 Mar 21 12:42:46.898619 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Mar 21 12:42:46.898629 kernel: APIC: Switch to symmetric I/O mode setup Mar 21 12:42:46.898636 kernel: x2apic enabled Mar 21 12:42:46.898651 kernel: APIC: Switched APIC routing to: physical x2apic Mar 21 12:42:46.898662 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Mar 21 12:42:46.898672 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Mar 21 12:42:46.898683 kernel: kvm-guest: setup PV IPIs Mar 21 12:42:46.898693 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Mar 21 12:42:46.898703 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Mar 21 12:42:46.898713 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Mar 21 12:42:46.898725 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Mar 21 12:42:46.898733 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Mar 21 12:42:46.898740 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Mar 21 12:42:46.898748 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 21 12:42:46.898756 kernel: Spectre V2 : Mitigation: Retpolines Mar 21 12:42:46.898763 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 21 12:42:46.898771 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 21 12:42:46.898779 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Mar 21 12:42:46.898786 kernel: RETBleed: Mitigation: untrained return thunk Mar 21 12:42:46.898797 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Mar 21 12:42:46.898805 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Mar 21 12:42:46.898813 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Mar 21 12:42:46.898821 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Mar 21 12:42:46.898829 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Mar 21 12:42:46.898837 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 21 12:42:46.898844 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 21 12:42:46.898852 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 21 12:42:46.898862 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 21 12:42:46.898870 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Mar 21 12:42:46.898878 kernel: Freeing SMP alternatives memory: 32K Mar 21 12:42:46.898885 kernel: pid_max: default: 32768 minimum: 301 Mar 21 12:42:46.898893 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 21 12:42:46.898900 kernel: landlock: Up and running. Mar 21 12:42:46.898908 kernel: SELinux: Initializing. Mar 21 12:42:46.898916 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 21 12:42:46.898923 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 21 12:42:46.898934 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Mar 21 12:42:46.898941 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 21 12:42:46.898949 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 21 12:42:46.898957 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 21 12:42:46.898980 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Mar 21 12:42:46.898988 kernel: ... version: 0 Mar 21 12:42:46.898996 kernel: ... bit width: 48 Mar 21 12:42:46.899003 kernel: ... generic registers: 6 Mar 21 12:42:46.899011 kernel: ... value mask: 0000ffffffffffff Mar 21 12:42:46.899021 kernel: ... max period: 00007fffffffffff Mar 21 12:42:46.899028 kernel: ... fixed-purpose events: 0 Mar 21 12:42:46.899036 kernel: ... event mask: 000000000000003f Mar 21 12:42:46.899043 kernel: signal: max sigframe size: 1776 Mar 21 12:42:46.899051 kernel: rcu: Hierarchical SRCU implementation. Mar 21 12:42:46.899059 kernel: rcu: Max phase no-delay instances is 400. Mar 21 12:42:46.899066 kernel: smp: Bringing up secondary CPUs ... Mar 21 12:42:46.899074 kernel: smpboot: x86: Booting SMP configuration: Mar 21 12:42:46.899081 kernel: .... node #0, CPUs: #1 #2 #3 Mar 21 12:42:46.899089 kernel: smp: Brought up 1 node, 4 CPUs Mar 21 12:42:46.899099 kernel: smpboot: Max logical packages: 1 Mar 21 12:42:46.899106 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Mar 21 12:42:46.899114 kernel: devtmpfs: initialized Mar 21 12:42:46.899121 kernel: x86/mm: Memory block size: 128MB Mar 21 12:42:46.899129 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bb7f000-0x9bbfefff] (524288 bytes) Mar 21 12:42:46.899137 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9bfb5000-0x9bfb6fff] (8192 bytes) Mar 21 12:42:46.899145 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 21 12:42:46.899152 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 21 12:42:46.899160 kernel: pinctrl core: initialized pinctrl subsystem Mar 21 12:42:46.899170 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 21 12:42:46.899177 kernel: audit: initializing netlink subsys (disabled) Mar 21 12:42:46.899185 kernel: audit: type=2000 audit(1742560966.715:1): state=initialized audit_enabled=0 res=1 Mar 21 12:42:46.899192 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 21 12:42:46.899200 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 21 12:42:46.899208 kernel: cpuidle: using governor menu Mar 21 12:42:46.899215 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 21 12:42:46.899223 kernel: dca service started, version 1.12.1 Mar 21 12:42:46.899233 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Mar 21 12:42:46.899240 kernel: PCI: Using configuration type 1 for base access Mar 21 12:42:46.899248 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 21 12:42:46.899256 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 21 12:42:46.899263 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 21 12:42:46.899271 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 21 12:42:46.899278 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 21 12:42:46.899286 kernel: ACPI: Added _OSI(Module Device) Mar 21 12:42:46.899293 kernel: ACPI: Added _OSI(Processor Device) Mar 21 12:42:46.899303 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 21 12:42:46.899311 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 21 12:42:46.899318 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 21 12:42:46.899326 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 21 12:42:46.899333 kernel: ACPI: Interpreter enabled Mar 21 12:42:46.899341 kernel: ACPI: PM: (supports S0 S5) Mar 21 12:42:46.899348 kernel: ACPI: Using IOAPIC for interrupt routing Mar 21 12:42:46.899356 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 21 12:42:46.899448 kernel: PCI: Using E820 reservations for host bridge windows Mar 21 12:42:46.899459 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Mar 21 12:42:46.899467 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 21 12:42:46.899653 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 21 12:42:46.899805 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Mar 21 12:42:46.899947 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Mar 21 12:42:46.899958 kernel: PCI host bridge to bus 0000:00 Mar 21 12:42:46.900084 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Mar 21 12:42:46.900205 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Mar 21 12:42:46.900321 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Mar 21 12:42:46.900464 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Mar 21 12:42:46.900580 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Mar 21 12:42:46.900711 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Mar 21 12:42:46.900827 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 21 12:42:46.900965 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Mar 21 12:42:46.901107 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Mar 21 12:42:46.901229 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] Mar 21 12:42:46.901352 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xc1044000-0xc1044fff] Mar 21 12:42:46.901491 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] Mar 21 12:42:46.901613 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Mar 21 12:42:46.901755 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Mar 21 12:42:46.901894 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Mar 21 12:42:46.902019 kernel: pci 0000:00:02.0: reg 0x10: [io 0x6100-0x611f] Mar 21 12:42:46.902142 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xc1043000-0xc1043fff] Mar 21 12:42:46.902266 kernel: pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] Mar 21 12:42:46.902438 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Mar 21 12:42:46.902565 kernel: pci 0000:00:03.0: reg 0x10: [io 0x6000-0x607f] Mar 21 12:42:46.902705 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff] Mar 21 12:42:46.902846 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] Mar 21 12:42:46.902978 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Mar 21 12:42:46.903099 kernel: pci 0000:00:04.0: reg 0x10: [io 0x60e0-0x60ff] Mar 21 12:42:46.903222 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff] Mar 21 12:42:46.903343 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] Mar 21 12:42:46.903503 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] Mar 21 12:42:46.903634 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Mar 21 12:42:46.903789 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Mar 21 12:42:46.903920 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Mar 21 12:42:46.904042 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x60c0-0x60df] Mar 21 12:42:46.904163 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xc1040000-0xc1040fff] Mar 21 12:42:46.904294 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Mar 21 12:42:46.904442 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6080-0x60bf] Mar 21 12:42:46.904454 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Mar 21 12:42:46.904466 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Mar 21 12:42:46.904473 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Mar 21 12:42:46.904481 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Mar 21 12:42:46.904489 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Mar 21 12:42:46.904496 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Mar 21 12:42:46.904504 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Mar 21 12:42:46.904511 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Mar 21 12:42:46.904520 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Mar 21 12:42:46.904532 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Mar 21 12:42:46.904542 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Mar 21 12:42:46.904551 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Mar 21 12:42:46.904561 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Mar 21 12:42:46.904570 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Mar 21 12:42:46.904580 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Mar 21 12:42:46.904590 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Mar 21 12:42:46.904600 kernel: iommu: Default domain type: Translated Mar 21 12:42:46.904610 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 21 12:42:46.904622 kernel: efivars: Registered efivars operations Mar 21 12:42:46.904630 kernel: PCI: Using ACPI for IRQ routing Mar 21 12:42:46.904637 kernel: PCI: pci_cache_line_size set to 64 bytes Mar 21 12:42:46.904654 kernel: e820: reserve RAM buffer [mem 0x0009f000-0x0009ffff] Mar 21 12:42:46.904664 kernel: e820: reserve RAM buffer [mem 0x9a14a018-0x9bffffff] Mar 21 12:42:46.904675 kernel: e820: reserve RAM buffer [mem 0x9a187018-0x9bffffff] Mar 21 12:42:46.904684 kernel: e820: reserve RAM buffer [mem 0x9b8ed000-0x9bffffff] Mar 21 12:42:46.904694 kernel: e820: reserve RAM buffer [mem 0x9bfb1000-0x9bffffff] Mar 21 12:42:46.904833 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Mar 21 12:42:46.904976 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Mar 21 12:42:46.905100 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Mar 21 12:42:46.905111 kernel: vgaarb: loaded Mar 21 12:42:46.905119 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Mar 21 12:42:46.905128 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Mar 21 12:42:46.905147 kernel: clocksource: Switched to clocksource kvm-clock Mar 21 12:42:46.905156 kernel: VFS: Disk quotas dquot_6.6.0 Mar 21 12:42:46.905164 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 21 12:42:46.905171 kernel: pnp: PnP ACPI init Mar 21 12:42:46.905321 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Mar 21 12:42:46.905332 kernel: pnp: PnP ACPI: found 6 devices Mar 21 12:42:46.905340 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 21 12:42:46.905348 kernel: NET: Registered PF_INET protocol family Mar 21 12:42:46.905356 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 21 12:42:46.905377 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 21 12:42:46.905385 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 21 12:42:46.905393 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 21 12:42:46.905403 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 21 12:42:46.905411 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 21 12:42:46.905419 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 21 12:42:46.905426 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 21 12:42:46.905434 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 21 12:42:46.905442 kernel: NET: Registered PF_XDP protocol family Mar 21 12:42:46.905569 kernel: pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window Mar 21 12:42:46.905717 kernel: pci 0000:00:04.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref] Mar 21 12:42:46.905840 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Mar 21 12:42:46.905967 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Mar 21 12:42:46.906082 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Mar 21 12:42:46.906194 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Mar 21 12:42:46.906306 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Mar 21 12:42:46.906434 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Mar 21 12:42:46.906445 kernel: PCI: CLS 0 bytes, default 64 Mar 21 12:42:46.906453 kernel: Initialise system trusted keyrings Mar 21 12:42:46.906461 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 21 12:42:46.906473 kernel: Key type asymmetric registered Mar 21 12:42:46.906481 kernel: Asymmetric key parser 'x509' registered Mar 21 12:42:46.906488 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 21 12:42:46.906496 kernel: io scheduler mq-deadline registered Mar 21 12:42:46.906503 kernel: io scheduler kyber registered Mar 21 12:42:46.906511 kernel: io scheduler bfq registered Mar 21 12:42:46.906519 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 21 12:42:46.906545 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Mar 21 12:42:46.906555 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Mar 21 12:42:46.906566 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Mar 21 12:42:46.906574 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 21 12:42:46.906582 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 21 12:42:46.906591 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Mar 21 12:42:46.906598 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Mar 21 12:42:46.906606 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Mar 21 12:42:46.906614 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Mar 21 12:42:46.906760 kernel: rtc_cmos 00:04: RTC can wake from S4 Mar 21 12:42:46.906883 kernel: rtc_cmos 00:04: registered as rtc0 Mar 21 12:42:46.906999 kernel: rtc_cmos 00:04: setting system clock to 2025-03-21T12:42:46 UTC (1742560966) Mar 21 12:42:46.907116 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Mar 21 12:42:46.907127 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Mar 21 12:42:46.907135 kernel: efifb: probing for efifb Mar 21 12:42:46.907143 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Mar 21 12:42:46.907151 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Mar 21 12:42:46.907159 kernel: efifb: scrolling: redraw Mar 21 12:42:46.907170 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 21 12:42:46.907189 kernel: Console: switching to colour frame buffer device 160x50 Mar 21 12:42:46.907204 kernel: fb0: EFI VGA frame buffer device Mar 21 12:42:46.907220 kernel: pstore: Using crash dump compression: deflate Mar 21 12:42:46.907235 kernel: pstore: Registered efi_pstore as persistent store backend Mar 21 12:42:46.907244 kernel: NET: Registered PF_INET6 protocol family Mar 21 12:42:46.907260 kernel: Segment Routing with IPv6 Mar 21 12:42:46.907276 kernel: In-situ OAM (IOAM) with IPv6 Mar 21 12:42:46.907294 kernel: NET: Registered PF_PACKET protocol family Mar 21 12:42:46.907303 kernel: Key type dns_resolver registered Mar 21 12:42:46.907329 kernel: IPI shorthand broadcast: enabled Mar 21 12:42:46.907345 kernel: sched_clock: Marking stable (586002968, 134340498)->(806124341, -85780875) Mar 21 12:42:46.907353 kernel: registered taskstats version 1 Mar 21 12:42:46.907386 kernel: Loading compiled-in X.509 certificates Mar 21 12:42:46.907395 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: d76f2258ffed89096a9428010e5ac0a0babcea9e' Mar 21 12:42:46.907406 kernel: Key type .fscrypt registered Mar 21 12:42:46.907414 kernel: Key type fscrypt-provisioning registered Mar 21 12:42:46.907422 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 21 12:42:46.907432 kernel: ima: Allocated hash algorithm: sha1 Mar 21 12:42:46.907440 kernel: ima: No architecture policies found Mar 21 12:42:46.907448 kernel: clk: Disabling unused clocks Mar 21 12:42:46.907456 kernel: Freeing unused kernel image (initmem) memory: 43588K Mar 21 12:42:46.907464 kernel: Write protecting the kernel read-only data: 40960k Mar 21 12:42:46.907472 kernel: Freeing unused kernel image (rodata/data gap) memory: 1564K Mar 21 12:42:46.907482 kernel: Run /init as init process Mar 21 12:42:46.907489 kernel: with arguments: Mar 21 12:42:46.907497 kernel: /init Mar 21 12:42:46.907505 kernel: with environment: Mar 21 12:42:46.907513 kernel: HOME=/ Mar 21 12:42:46.907520 kernel: TERM=linux Mar 21 12:42:46.907528 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 21 12:42:46.907537 systemd[1]: Successfully made /usr/ read-only. Mar 21 12:42:46.907550 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) Mar 21 12:42:46.907561 systemd[1]: Detected virtualization kvm. Mar 21 12:42:46.907570 systemd[1]: Detected architecture x86-64. Mar 21 12:42:46.907578 systemd[1]: Running in initrd. Mar 21 12:42:46.907586 systemd[1]: No hostname configured, using default hostname. Mar 21 12:42:46.907595 systemd[1]: Hostname set to . Mar 21 12:42:46.907604 systemd[1]: Initializing machine ID from VM UUID. Mar 21 12:42:46.907612 systemd[1]: Queued start job for default target initrd.target. Mar 21 12:42:46.907623 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 21 12:42:46.907631 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 21 12:42:46.907641 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 21 12:42:46.907659 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 21 12:42:46.907671 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 21 12:42:46.907684 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 21 12:42:46.907697 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 21 12:42:46.907712 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 21 12:42:46.907721 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 21 12:42:46.907730 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 21 12:42:46.907739 systemd[1]: Reached target paths.target - Path Units. Mar 21 12:42:46.907747 systemd[1]: Reached target slices.target - Slice Units. Mar 21 12:42:46.907755 systemd[1]: Reached target swap.target - Swaps. Mar 21 12:42:46.907764 systemd[1]: Reached target timers.target - Timer Units. Mar 21 12:42:46.907772 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 21 12:42:46.907783 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 21 12:42:46.907792 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 21 12:42:46.907800 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 21 12:42:46.907808 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 21 12:42:46.907817 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 21 12:42:46.907825 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 21 12:42:46.907834 systemd[1]: Reached target sockets.target - Socket Units. Mar 21 12:42:46.907842 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 21 12:42:46.907851 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 21 12:42:46.907861 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 21 12:42:46.907870 systemd[1]: Starting systemd-fsck-usr.service... Mar 21 12:42:46.907878 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 21 12:42:46.907886 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 21 12:42:46.907895 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 21 12:42:46.907903 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 21 12:42:46.907912 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 21 12:42:46.907923 systemd[1]: Finished systemd-fsck-usr.service. Mar 21 12:42:46.907932 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 21 12:42:46.907964 systemd-journald[191]: Collecting audit messages is disabled. Mar 21 12:42:46.907987 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:42:46.907996 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 21 12:42:46.908005 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 21 12:42:46.908014 systemd-journald[191]: Journal started Mar 21 12:42:46.908032 systemd-journald[191]: Runtime Journal (/run/log/journal/29b092b7651647d4b977677652722156) is 6M, max 47.9M, 41.9M free. Mar 21 12:42:46.910410 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 21 12:42:46.911934 systemd-modules-load[192]: Inserted module 'overlay' Mar 21 12:42:46.918128 systemd[1]: Started systemd-journald.service - Journal Service. Mar 21 12:42:46.921081 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 21 12:42:46.926043 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 21 12:42:46.935682 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 21 12:42:46.937697 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 21 12:42:46.939217 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 21 12:42:46.955387 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 21 12:42:46.957295 systemd-modules-load[192]: Inserted module 'br_netfilter' Mar 21 12:42:46.958273 kernel: Bridge firewalling registered Mar 21 12:42:46.959518 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 21 12:42:46.962322 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 21 12:42:46.968836 dracut-cmdline[223]: dracut-dracut-053 Mar 21 12:42:46.976262 dracut-cmdline[223]: Using kernel command line parameters: rd.driver.pre=btrfs 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=fb715041d083099c6a15c8aee7cc93fc3f3ca8764fc0aaaff245a06641d663d2 Mar 21 12:42:46.989513 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 21 12:42:46.993131 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 21 12:42:47.039905 systemd-resolved[262]: Positive Trust Anchors: Mar 21 12:42:47.039931 systemd-resolved[262]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 21 12:42:47.039972 systemd-resolved[262]: 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 Mar 21 12:42:47.042513 systemd-resolved[262]: Defaulting to hostname 'linux'. Mar 21 12:42:47.043721 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 21 12:42:47.050265 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 21 12:42:47.061385 kernel: SCSI subsystem initialized Mar 21 12:42:47.072392 kernel: Loading iSCSI transport class v2.0-870. Mar 21 12:42:47.083390 kernel: iscsi: registered transport (tcp) Mar 21 12:42:47.104446 kernel: iscsi: registered transport (qla4xxx) Mar 21 12:42:47.104493 kernel: QLogic iSCSI HBA Driver Mar 21 12:42:47.153645 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 21 12:42:47.157273 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 21 12:42:47.200671 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 21 12:42:47.200729 kernel: device-mapper: uevent: version 1.0.3 Mar 21 12:42:47.200740 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 21 12:42:47.243397 kernel: raid6: avx2x4 gen() 30246 MB/s Mar 21 12:42:47.260383 kernel: raid6: avx2x2 gen() 31384 MB/s Mar 21 12:42:47.277465 kernel: raid6: avx2x1 gen() 25872 MB/s Mar 21 12:42:47.277486 kernel: raid6: using algorithm avx2x2 gen() 31384 MB/s Mar 21 12:42:47.295482 kernel: raid6: .... xor() 19964 MB/s, rmw enabled Mar 21 12:42:47.295503 kernel: raid6: using avx2x2 recovery algorithm Mar 21 12:42:47.316383 kernel: xor: automatically using best checksumming function avx Mar 21 12:42:47.461398 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 21 12:42:47.473930 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 21 12:42:47.476566 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 21 12:42:47.508623 systemd-udevd[414]: Using default interface naming scheme 'v255'. Mar 21 12:42:47.514970 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 21 12:42:47.516454 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 21 12:42:47.538045 dracut-pre-trigger[416]: rd.md=0: removing MD RAID activation Mar 21 12:42:47.569531 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 21 12:42:47.571560 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 21 12:42:47.648640 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 21 12:42:47.652580 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 21 12:42:47.676644 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 21 12:42:47.680730 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 21 12:42:47.683664 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 21 12:42:47.685103 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 21 12:42:47.690699 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 21 12:42:47.705407 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Mar 21 12:42:47.741307 kernel: cryptd: max_cpu_qlen set to 1000 Mar 21 12:42:47.741342 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Mar 21 12:42:47.741543 kernel: libata version 3.00 loaded. Mar 21 12:42:47.741560 kernel: AVX2 version of gcm_enc/dec engaged. Mar 21 12:42:47.741575 kernel: AES CTR mode by8 optimization enabled Mar 21 12:42:47.741588 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Mar 21 12:42:47.741603 kernel: GPT:9289727 != 19775487 Mar 21 12:42:47.741616 kernel: GPT:Alternate GPT header not at the end of the disk. Mar 21 12:42:47.741641 kernel: GPT:9289727 != 19775487 Mar 21 12:42:47.741654 kernel: GPT: Use GNU Parted to correct GPT errors. Mar 21 12:42:47.741672 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 21 12:42:47.741686 kernel: ahci 0000:00:1f.2: version 3.0 Mar 21 12:42:47.757733 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Mar 21 12:42:47.757750 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Mar 21 12:42:47.757904 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Mar 21 12:42:47.758045 kernel: scsi host0: ahci Mar 21 12:42:47.758199 kernel: scsi host1: ahci Mar 21 12:42:47.758351 kernel: scsi host2: ahci Mar 21 12:42:47.758510 kernel: scsi host3: ahci Mar 21 12:42:47.758666 kernel: scsi host4: ahci Mar 21 12:42:47.758809 kernel: scsi host5: ahci Mar 21 12:42:47.758954 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 Mar 21 12:42:47.758966 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 Mar 21 12:42:47.758977 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 Mar 21 12:42:47.758991 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 Mar 21 12:42:47.759002 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 Mar 21 12:42:47.759013 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 Mar 21 12:42:47.714194 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 21 12:42:47.762576 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by (udev-worker) (469) Mar 21 12:42:47.721810 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 21 12:42:47.722936 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 21 12:42:47.766258 kernel: BTRFS: device fsid c99b4410-5d95-4377-8189-88a588aa2514 devid 1 transid 38 /dev/vda3 scanned by (udev-worker) (474) Mar 21 12:42:47.726522 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 21 12:42:47.727665 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 21 12:42:47.727824 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:42:47.734022 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 21 12:42:47.736534 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 21 12:42:47.747330 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 21 12:42:47.747963 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:42:47.788858 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Mar 21 12:42:47.809254 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Mar 21 12:42:47.816887 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Mar 21 12:42:47.817303 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Mar 21 12:42:47.827288 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 21 12:42:47.829957 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 21 12:42:47.831041 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 21 12:42:47.854592 disk-uuid[554]: Primary Header is updated. Mar 21 12:42:47.854592 disk-uuid[554]: Secondary Entries is updated. Mar 21 12:42:47.854592 disk-uuid[554]: Secondary Header is updated. Mar 21 12:42:47.856331 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:42:47.860974 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 21 12:42:47.859818 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 21 12:42:47.891067 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 21 12:42:48.065412 kernel: ata2: SATA link down (SStatus 0 SControl 300) Mar 21 12:42:48.074150 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Mar 21 12:42:48.074176 kernel: ata1: SATA link down (SStatus 0 SControl 300) Mar 21 12:42:48.074187 kernel: ata4: SATA link down (SStatus 0 SControl 300) Mar 21 12:42:48.074388 kernel: ata6: SATA link down (SStatus 0 SControl 300) Mar 21 12:42:48.075385 kernel: ata5: SATA link down (SStatus 0 SControl 300) Mar 21 12:42:48.076389 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Mar 21 12:42:48.076402 kernel: ata3.00: applying bridge limits Mar 21 12:42:48.077385 kernel: ata3.00: configured for UDMA/100 Mar 21 12:42:48.079385 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Mar 21 12:42:48.148631 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Mar 21 12:42:48.161000 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 21 12:42:48.161014 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Mar 21 12:42:48.868010 disk-uuid[556]: The operation has completed successfully. Mar 21 12:42:48.869165 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 21 12:42:48.897778 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 21 12:42:48.897895 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 21 12:42:48.935138 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 21 12:42:48.952409 sh[593]: Success Mar 21 12:42:48.965389 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Mar 21 12:42:48.998579 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 21 12:42:49.002385 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 21 12:42:49.019221 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 21 12:42:49.026800 kernel: BTRFS info (device dm-0): first mount of filesystem c99b4410-5d95-4377-8189-88a588aa2514 Mar 21 12:42:49.026827 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 21 12:42:49.026838 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 21 12:42:49.027999 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 21 12:42:49.029592 kernel: BTRFS info (device dm-0): using free space tree Mar 21 12:42:49.033398 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 21 12:42:49.034868 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 21 12:42:49.035702 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 21 12:42:49.038487 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 21 12:42:49.064675 kernel: BTRFS info (device vda6): first mount of filesystem 667b391b-b0e4-4f87-a670-43615a660c46 Mar 21 12:42:49.064712 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 21 12:42:49.064723 kernel: BTRFS info (device vda6): using free space tree Mar 21 12:42:49.068380 kernel: BTRFS info (device vda6): auto enabling async discard Mar 21 12:42:49.072404 kernel: BTRFS info (device vda6): last unmount of filesystem 667b391b-b0e4-4f87-a670-43615a660c46 Mar 21 12:42:49.145032 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 21 12:42:49.147677 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 21 12:42:49.162573 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 21 12:42:49.164133 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 21 12:42:49.197418 systemd-networkd[769]: lo: Link UP Mar 21 12:42:49.197429 systemd-networkd[769]: lo: Gained carrier Mar 21 12:42:49.199094 systemd-networkd[769]: Enumeration completed Mar 21 12:42:49.199190 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 21 12:42:49.199442 systemd-networkd[769]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 21 12:42:49.199447 systemd-networkd[769]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 21 12:42:49.200327 systemd-networkd[769]: eth0: Link UP Mar 21 12:42:49.200331 systemd-networkd[769]: eth0: Gained carrier Mar 21 12:42:49.200337 systemd-networkd[769]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 21 12:42:49.201325 systemd[1]: Reached target network.target - Network. Mar 21 12:42:49.213293 ignition[771]: Ignition 2.20.0 Mar 21 12:42:49.213300 ignition[771]: Stage: fetch-offline Mar 21 12:42:49.215430 systemd-networkd[769]: eth0: DHCPv4 address 10.0.0.151/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 21 12:42:49.213330 ignition[771]: no configs at "/usr/lib/ignition/base.d" Mar 21 12:42:49.213340 ignition[771]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:42:49.213446 ignition[771]: parsed url from cmdline: "" Mar 21 12:42:49.213450 ignition[771]: no config URL provided Mar 21 12:42:49.213455 ignition[771]: reading system config file "/usr/lib/ignition/user.ign" Mar 21 12:42:49.213464 ignition[771]: no config at "/usr/lib/ignition/user.ign" Mar 21 12:42:49.213491 ignition[771]: op(1): [started] loading QEMU firmware config module Mar 21 12:42:49.213496 ignition[771]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 21 12:42:49.221555 ignition[771]: op(1): [finished] loading QEMU firmware config module Mar 21 12:42:49.223711 ignition[771]: parsing config with SHA512: f6c7c5164f44e2ee954803980fe28c2436d4274ef7b09cf08dbea2ece317024888d9ef3ecde90319a0e3f1000ed3a4cdd5a6d3d8012a5f1cf6fea96ceb085118 Mar 21 12:42:49.227268 unknown[771]: fetched base config from "system" Mar 21 12:42:49.227283 unknown[771]: fetched user config from "qemu" Mar 21 12:42:49.228109 ignition[771]: fetch-offline: fetch-offline passed Mar 21 12:42:49.228279 ignition[771]: Ignition finished successfully Mar 21 12:42:49.230058 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 21 12:42:49.232430 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Mar 21 12:42:49.233515 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 21 12:42:49.261318 ignition[785]: Ignition 2.20.0 Mar 21 12:42:49.261329 ignition[785]: Stage: kargs Mar 21 12:42:49.261489 ignition[785]: no configs at "/usr/lib/ignition/base.d" Mar 21 12:42:49.261500 ignition[785]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:42:49.262099 ignition[785]: kargs: kargs passed Mar 21 12:42:49.262138 ignition[785]: Ignition finished successfully Mar 21 12:42:49.266128 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 21 12:42:49.268241 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 21 12:42:49.292614 ignition[793]: Ignition 2.20.0 Mar 21 12:42:49.292627 ignition[793]: Stage: disks Mar 21 12:42:49.292827 ignition[793]: no configs at "/usr/lib/ignition/base.d" Mar 21 12:42:49.292841 ignition[793]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:42:49.293706 ignition[793]: disks: disks passed Mar 21 12:42:49.295861 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 21 12:42:49.293759 ignition[793]: Ignition finished successfully Mar 21 12:42:49.297199 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 21 12:42:49.298713 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 21 12:42:49.300788 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 21 12:42:49.301776 systemd[1]: Reached target sysinit.target - System Initialization. Mar 21 12:42:49.302162 systemd[1]: Reached target basic.target - Basic System. Mar 21 12:42:49.303498 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 21 12:42:49.331132 systemd-fsck[803]: ROOT: clean, 14/553520 files, 52654/553472 blocks Mar 21 12:42:49.336907 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 21 12:42:49.340594 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 21 12:42:49.437387 kernel: EXT4-fs (vda9): mounted filesystem c540419e-275b-4bd7-8ebd-24b19ec75c0b r/w with ordered data mode. Quota mode: none. Mar 21 12:42:49.438372 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 21 12:42:49.439246 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 21 12:42:49.442278 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 21 12:42:49.444001 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 21 12:42:49.445198 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Mar 21 12:42:49.445237 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 21 12:42:49.445259 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 21 12:42:49.462260 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 21 12:42:49.464010 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 21 12:42:49.467433 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (811) Mar 21 12:42:49.469549 kernel: BTRFS info (device vda6): first mount of filesystem 667b391b-b0e4-4f87-a670-43615a660c46 Mar 21 12:42:49.469566 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 21 12:42:49.469592 kernel: BTRFS info (device vda6): using free space tree Mar 21 12:42:49.472399 kernel: BTRFS info (device vda6): auto enabling async discard Mar 21 12:42:49.474436 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 21 12:42:49.499286 initrd-setup-root[835]: cut: /sysroot/etc/passwd: No such file or directory Mar 21 12:42:49.504192 initrd-setup-root[842]: cut: /sysroot/etc/group: No such file or directory Mar 21 12:42:49.507824 initrd-setup-root[849]: cut: /sysroot/etc/shadow: No such file or directory Mar 21 12:42:49.511160 initrd-setup-root[856]: cut: /sysroot/etc/gshadow: No such file or directory Mar 21 12:42:49.592460 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 21 12:42:49.593731 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 21 12:42:49.596148 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 21 12:42:49.613381 kernel: BTRFS info (device vda6): last unmount of filesystem 667b391b-b0e4-4f87-a670-43615a660c46 Mar 21 12:42:49.641673 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 21 12:42:49.732592 ignition[928]: INFO : Ignition 2.20.0 Mar 21 12:42:49.732592 ignition[928]: INFO : Stage: mount Mar 21 12:42:49.734287 ignition[928]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 21 12:42:49.734287 ignition[928]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:42:49.734287 ignition[928]: INFO : mount: mount passed Mar 21 12:42:49.734287 ignition[928]: INFO : Ignition finished successfully Mar 21 12:42:49.739593 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 21 12:42:49.741536 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 21 12:42:50.025942 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 21 12:42:50.027652 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 21 12:42:50.045110 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/vda6 scanned by mount (937) Mar 21 12:42:50.045137 kernel: BTRFS info (device vda6): first mount of filesystem 667b391b-b0e4-4f87-a670-43615a660c46 Mar 21 12:42:50.045148 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 21 12:42:50.046595 kernel: BTRFS info (device vda6): using free space tree Mar 21 12:42:50.049386 kernel: BTRFS info (device vda6): auto enabling async discard Mar 21 12:42:50.050443 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 21 12:42:50.086496 ignition[954]: INFO : Ignition 2.20.0 Mar 21 12:42:50.086496 ignition[954]: INFO : Stage: files Mar 21 12:42:50.088184 ignition[954]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 21 12:42:50.088184 ignition[954]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:42:50.088184 ignition[954]: DEBUG : files: compiled without relabeling support, skipping Mar 21 12:42:50.091671 ignition[954]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 21 12:42:50.091671 ignition[954]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 21 12:42:50.091671 ignition[954]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 21 12:42:50.091671 ignition[954]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 21 12:42:50.097249 ignition[954]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 21 12:42:50.097249 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Mar 21 12:42:50.097249 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Mar 21 12:42:50.097249 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 21 12:42:50.097249 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 21 12:42:50.097249 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Mar 21 12:42:50.097249 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Mar 21 12:42:50.097249 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Mar 21 12:42:50.097249 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.32.0-x86-64.raw: attempt #1 Mar 21 12:42:50.091676 unknown[954]: wrote ssh authorized keys file for user: core Mar 21 12:42:50.418499 systemd-networkd[769]: eth0: Gained IPv6LL Mar 21 12:42:50.461717 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Mar 21 12:42:50.839494 ignition[954]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Mar 21 12:42:50.839494 ignition[954]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Mar 21 12:42:50.843206 ignition[954]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 21 12:42:50.843206 ignition[954]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 21 12:42:50.843206 ignition[954]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Mar 21 12:42:50.843206 ignition[954]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Mar 21 12:42:50.859324 ignition[954]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Mar 21 12:42:50.863907 ignition[954]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Mar 21 12:42:50.865469 ignition[954]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Mar 21 12:42:50.865469 ignition[954]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 21 12:42:50.865469 ignition[954]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 21 12:42:50.865469 ignition[954]: INFO : files: files passed Mar 21 12:42:50.865469 ignition[954]: INFO : Ignition finished successfully Mar 21 12:42:50.866915 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 21 12:42:50.870709 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 21 12:42:50.873014 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 21 12:42:50.889685 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 21 12:42:50.889818 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 21 12:42:50.892900 initrd-setup-root-after-ignition[983]: grep: /sysroot/oem/oem-release: No such file or directory Mar 21 12:42:50.894261 initrd-setup-root-after-ignition[985]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 21 12:42:50.894261 initrd-setup-root-after-ignition[985]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 21 12:42:50.899536 initrd-setup-root-after-ignition[989]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 21 12:42:50.895717 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 21 12:42:50.897625 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 21 12:42:50.900283 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 21 12:42:50.959795 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 21 12:42:50.959907 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 21 12:42:50.962128 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 21 12:42:50.964182 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 21 12:42:50.966180 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 21 12:42:50.966912 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 21 12:42:50.983837 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 21 12:42:50.985341 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 21 12:42:51.006058 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 21 12:42:51.007312 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 21 12:42:51.009513 systemd[1]: Stopped target timers.target - Timer Units. Mar 21 12:42:51.011499 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 21 12:42:51.011617 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 21 12:42:51.013684 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 21 12:42:51.015376 systemd[1]: Stopped target basic.target - Basic System. Mar 21 12:42:51.017352 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 21 12:42:51.019394 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 21 12:42:51.021369 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 21 12:42:51.023562 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 21 12:42:51.025667 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 21 12:42:51.027894 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 21 12:42:51.029028 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 21 12:42:51.030966 systemd[1]: Stopped target swap.target - Swaps. Mar 21 12:42:51.032925 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 21 12:42:51.033034 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 21 12:42:51.035160 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 21 12:42:51.036946 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 21 12:42:51.038830 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 21 12:42:51.038923 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 21 12:42:51.040951 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 21 12:42:51.041057 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 21 12:42:51.043190 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 21 12:42:51.043302 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 21 12:42:51.045190 systemd[1]: Stopped target paths.target - Path Units. Mar 21 12:42:51.047074 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 21 12:42:51.051448 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 21 12:42:51.053560 systemd[1]: Stopped target slices.target - Slice Units. Mar 21 12:42:51.055450 systemd[1]: Stopped target sockets.target - Socket Units. Mar 21 12:42:51.057140 systemd[1]: iscsid.socket: Deactivated successfully. Mar 21 12:42:51.057235 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 21 12:42:51.059100 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 21 12:42:51.059186 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 21 12:42:51.061591 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 21 12:42:51.061712 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 21 12:42:51.063616 systemd[1]: ignition-files.service: Deactivated successfully. Mar 21 12:42:51.063733 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 21 12:42:51.066186 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 21 12:42:51.068706 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 21 12:42:51.069942 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 21 12:42:51.070089 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 21 12:42:51.072098 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 21 12:42:51.072211 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 21 12:42:51.084736 ignition[1009]: INFO : Ignition 2.20.0 Mar 21 12:42:51.084736 ignition[1009]: INFO : Stage: umount Mar 21 12:42:51.084736 ignition[1009]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 21 12:42:51.084736 ignition[1009]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 21 12:42:51.078203 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 21 12:42:51.091443 ignition[1009]: INFO : umount: umount passed Mar 21 12:42:51.091443 ignition[1009]: INFO : Ignition finished successfully Mar 21 12:42:51.078307 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 21 12:42:51.088164 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 21 12:42:51.088286 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 21 12:42:51.089756 systemd[1]: Stopped target network.target - Network. Mar 21 12:42:51.091447 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 21 12:42:51.091499 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 21 12:42:51.093177 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 21 12:42:51.093225 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 21 12:42:51.098163 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 21 12:42:51.098215 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 21 12:42:51.099335 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 21 12:42:51.099421 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 21 12:42:51.101261 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 21 12:42:51.103227 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 21 12:42:51.106278 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 21 12:42:51.111323 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 21 12:42:51.111483 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 21 12:42:51.115624 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 21 12:42:51.115857 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 21 12:42:51.115978 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 21 12:42:51.119133 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 21 12:42:51.119931 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 21 12:42:51.119993 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 21 12:42:51.122283 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 21 12:42:51.123331 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 21 12:42:51.123395 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 21 12:42:51.125891 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 21 12:42:51.125977 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 21 12:42:51.128856 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 21 12:42:51.128906 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 21 12:42:51.130934 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 21 12:42:51.130981 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 21 12:42:51.133250 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 21 12:42:51.136568 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 21 12:42:51.136633 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 21 12:42:51.151566 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 21 12:42:51.151704 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 21 12:42:51.153760 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 21 12:42:51.153923 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 21 12:42:51.156166 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 21 12:42:51.156238 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 21 12:42:51.157497 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 21 12:42:51.157547 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 21 12:42:51.159682 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 21 12:42:51.159734 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 21 12:42:51.161885 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 21 12:42:51.161932 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 21 12:42:51.163845 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 21 12:42:51.163892 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 21 12:42:51.166699 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 21 12:42:51.167748 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 21 12:42:51.167803 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 21 12:42:51.169904 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Mar 21 12:42:51.169953 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 21 12:42:51.171977 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 21 12:42:51.172026 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 21 12:42:51.174038 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 21 12:42:51.174086 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:42:51.177077 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 21 12:42:51.177137 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 21 12:42:51.182195 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 21 12:42:51.182300 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 21 12:42:51.256624 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 21 12:42:51.256757 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 21 12:42:51.258739 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 21 12:42:51.260438 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 21 12:42:51.260495 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 21 12:42:51.263350 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 21 12:42:51.294110 systemd[1]: Switching root. Mar 21 12:42:51.324724 systemd-journald[191]: Journal stopped Mar 21 12:42:52.724927 systemd-journald[191]: Received SIGTERM from PID 1 (systemd). Mar 21 12:42:52.724994 kernel: SELinux: policy capability network_peer_controls=1 Mar 21 12:42:52.725008 kernel: SELinux: policy capability open_perms=1 Mar 21 12:42:52.725020 kernel: SELinux: policy capability extended_socket_class=1 Mar 21 12:42:52.725031 kernel: SELinux: policy capability always_check_network=0 Mar 21 12:42:52.725046 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 21 12:42:52.725058 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 21 12:42:52.725073 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 21 12:42:52.725091 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 21 12:42:52.725108 kernel: audit: type=1403 audit(1742560971.933:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 21 12:42:52.725121 systemd[1]: Successfully loaded SELinux policy in 41.612ms. Mar 21 12:42:52.725142 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 13.157ms. Mar 21 12:42:52.725158 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) Mar 21 12:42:52.725170 systemd[1]: Detected virtualization kvm. Mar 21 12:42:52.725185 systemd[1]: Detected architecture x86-64. Mar 21 12:42:52.725197 systemd[1]: Detected first boot. Mar 21 12:42:52.725215 systemd[1]: Initializing machine ID from VM UUID. Mar 21 12:42:52.725227 zram_generator::config[1056]: No configuration found. Mar 21 12:42:52.725245 kernel: Guest personality initialized and is inactive Mar 21 12:42:52.725257 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Mar 21 12:42:52.725268 kernel: Initialized host personality Mar 21 12:42:52.725280 kernel: NET: Registered PF_VSOCK protocol family Mar 21 12:42:52.725294 systemd[1]: Populated /etc with preset unit settings. Mar 21 12:42:52.725307 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 21 12:42:52.725319 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 21 12:42:52.725332 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 21 12:42:52.725344 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 21 12:42:52.725356 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 21 12:42:52.725380 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 21 12:42:52.725393 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 21 12:42:52.725405 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 21 12:42:52.725421 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 21 12:42:52.725433 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 21 12:42:52.725447 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 21 12:42:52.725459 systemd[1]: Created slice user.slice - User and Session Slice. Mar 21 12:42:52.725472 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 21 12:42:52.725492 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 21 12:42:52.725504 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 21 12:42:52.725516 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 21 12:42:52.725529 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 21 12:42:52.725544 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 21 12:42:52.725557 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 21 12:42:52.725570 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 21 12:42:52.725582 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 21 12:42:52.725594 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 21 12:42:52.725607 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 21 12:42:52.725619 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 21 12:42:52.725633 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 21 12:42:52.725646 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 21 12:42:52.725659 systemd[1]: Reached target slices.target - Slice Units. Mar 21 12:42:52.725671 systemd[1]: Reached target swap.target - Swaps. Mar 21 12:42:52.725683 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 21 12:42:52.725695 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 21 12:42:52.725708 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 21 12:42:52.725722 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 21 12:42:52.725734 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 21 12:42:52.725748 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 21 12:42:52.725763 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 21 12:42:52.725777 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 21 12:42:52.725790 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 21 12:42:52.725805 systemd[1]: Mounting media.mount - External Media Directory... Mar 21 12:42:52.725817 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 21 12:42:52.725829 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 21 12:42:52.725842 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 21 12:42:52.725854 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 21 12:42:52.725869 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). Mar 21 12:42:52.725883 systemd[1]: Reached target machines.target - Containers. Mar 21 12:42:52.725895 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 21 12:42:52.725907 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 21 12:42:52.725920 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 21 12:42:52.725932 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 21 12:42:52.725945 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 21 12:42:52.725957 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 21 12:42:52.725969 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 21 12:42:52.725984 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 21 12:42:52.725997 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 21 12:42:52.726010 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 21 12:42:52.726022 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 21 12:42:52.726034 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 21 12:42:52.726046 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 21 12:42:52.726058 systemd[1]: Stopped systemd-fsck-usr.service. Mar 21 12:42:52.726071 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). Mar 21 12:42:52.726085 kernel: fuse: init (API version 7.39) Mar 21 12:42:52.726097 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 21 12:42:52.726109 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 21 12:42:52.726121 kernel: loop: module loaded Mar 21 12:42:52.726133 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 21 12:42:52.726145 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 21 12:42:52.726158 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 21 12:42:52.726173 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 21 12:42:52.726186 systemd[1]: verity-setup.service: Deactivated successfully. Mar 21 12:42:52.726198 systemd[1]: Stopped verity-setup.service. Mar 21 12:42:52.726211 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 21 12:42:52.726223 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 21 12:42:52.726235 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 21 12:42:52.726249 systemd[1]: Mounted media.mount - External Media Directory. Mar 21 12:42:52.726262 kernel: ACPI: bus type drm_connector registered Mar 21 12:42:52.726274 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 21 12:42:52.726286 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 21 12:42:52.726301 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 21 12:42:52.726331 systemd-journald[1131]: Collecting audit messages is disabled. Mar 21 12:42:52.726375 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 21 12:42:52.726388 systemd-journald[1131]: Journal started Mar 21 12:42:52.726411 systemd-journald[1131]: Runtime Journal (/run/log/journal/29b092b7651647d4b977677652722156) is 6M, max 47.9M, 41.9M free. Mar 21 12:42:52.489742 systemd[1]: Queued start job for default target multi-user.target. Mar 21 12:42:52.501277 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Mar 21 12:42:52.501765 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 21 12:42:52.728560 systemd[1]: Started systemd-journald.service - Journal Service. Mar 21 12:42:52.729575 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 21 12:42:52.731355 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 21 12:42:52.731603 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 21 12:42:52.733287 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 21 12:42:52.733523 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 21 12:42:52.735077 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 21 12:42:52.735308 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 21 12:42:52.736803 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 21 12:42:52.737016 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 21 12:42:52.738697 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 21 12:42:52.738916 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 21 12:42:52.740437 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 21 12:42:52.740657 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 21 12:42:52.742087 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 21 12:42:52.743673 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 21 12:42:52.745336 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 21 12:42:52.746939 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 21 12:42:52.763556 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 21 12:42:52.766390 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 21 12:42:52.768682 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 21 12:42:52.769986 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 21 12:42:52.770077 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 21 12:42:52.772141 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 21 12:42:52.779498 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 21 12:42:52.782842 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 21 12:42:52.784095 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 21 12:42:52.787385 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 21 12:42:52.791357 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 21 12:42:52.792688 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 21 12:42:52.794111 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 21 12:42:52.795394 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 21 12:42:52.798262 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 21 12:42:52.804481 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 21 12:42:52.808167 systemd-journald[1131]: Time spent on flushing to /var/log/journal/29b092b7651647d4b977677652722156 is 32.666ms for 1011 entries. Mar 21 12:42:52.808167 systemd-journald[1131]: System Journal (/var/log/journal/29b092b7651647d4b977677652722156) is 8M, max 195.6M, 187.6M free. Mar 21 12:42:52.849609 systemd-journald[1131]: Received client request to flush runtime journal. Mar 21 12:42:52.849643 kernel: loop0: detected capacity change from 0 to 218376 Mar 21 12:42:52.808542 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 21 12:42:52.815041 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 21 12:42:52.816647 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 21 12:42:52.818064 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 21 12:42:52.820575 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 21 12:42:52.823315 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 21 12:42:52.831122 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 21 12:42:52.836896 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 21 12:42:52.840538 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 21 12:42:52.851874 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 21 12:42:52.854612 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 21 12:42:52.863389 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 21 12:42:52.864230 udevadm[1187]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 21 12:42:52.926265 systemd-tmpfiles[1177]: ACLs are not supported, ignoring. Mar 21 12:42:52.926284 systemd-tmpfiles[1177]: ACLs are not supported, ignoring. Mar 21 12:42:52.933804 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 21 12:42:52.959801 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 21 12:42:52.983455 kernel: loop1: detected capacity change from 0 to 109808 Mar 21 12:42:53.015896 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 21 12:42:53.019665 kernel: loop2: detected capacity change from 0 to 151640 Mar 21 12:42:53.019267 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 21 12:42:53.031906 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 21 12:42:53.048785 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. Mar 21 12:42:53.048805 systemd-tmpfiles[1199]: ACLs are not supported, ignoring. Mar 21 12:42:53.054321 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 21 12:42:53.066402 kernel: loop3: detected capacity change from 0 to 218376 Mar 21 12:42:53.076385 kernel: loop4: detected capacity change from 0 to 109808 Mar 21 12:42:53.084382 kernel: loop5: detected capacity change from 0 to 151640 Mar 21 12:42:53.094266 (sd-merge)[1204]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Mar 21 12:42:53.094870 (sd-merge)[1204]: Merged extensions into '/usr'. Mar 21 12:42:53.099202 systemd[1]: Reload requested from client PID 1176 ('systemd-sysext') (unit systemd-sysext.service)... Mar 21 12:42:53.099218 systemd[1]: Reloading... Mar 21 12:42:53.166435 zram_generator::config[1235]: No configuration found. Mar 21 12:42:53.226863 ldconfig[1171]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 21 12:42:53.291326 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 21 12:42:53.355689 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 21 12:42:53.356110 systemd[1]: Reloading finished in 256 ms. Mar 21 12:42:53.378936 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 21 12:42:53.380633 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 21 12:42:53.395798 systemd[1]: Starting ensure-sysext.service... Mar 21 12:42:53.397705 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 21 12:42:53.408757 systemd[1]: Reload requested from client PID 1270 ('systemctl') (unit ensure-sysext.service)... Mar 21 12:42:53.408772 systemd[1]: Reloading... Mar 21 12:42:53.474492 zram_generator::config[1304]: No configuration found. Mar 21 12:42:53.525494 systemd-tmpfiles[1272]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 21 12:42:53.525858 systemd-tmpfiles[1272]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 21 12:42:53.527125 systemd-tmpfiles[1272]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 21 12:42:53.527524 systemd-tmpfiles[1272]: ACLs are not supported, ignoring. Mar 21 12:42:53.527633 systemd-tmpfiles[1272]: ACLs are not supported, ignoring. Mar 21 12:42:53.532276 systemd-tmpfiles[1272]: Detected autofs mount point /boot during canonicalization of boot. Mar 21 12:42:53.532293 systemd-tmpfiles[1272]: Skipping /boot Mar 21 12:42:53.546703 systemd-tmpfiles[1272]: Detected autofs mount point /boot during canonicalization of boot. Mar 21 12:42:53.546717 systemd-tmpfiles[1272]: Skipping /boot Mar 21 12:42:53.613194 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 21 12:42:53.677610 systemd[1]: Reloading finished in 268 ms. Mar 21 12:42:53.707250 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 21 12:42:53.715949 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 21 12:42:53.718339 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 21 12:42:53.726864 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 21 12:42:53.730651 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 21 12:42:53.735420 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 21 12:42:53.737707 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 21 12:42:53.744532 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 21 12:42:53.744722 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 21 12:42:53.746345 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 21 12:42:53.748961 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 21 12:42:53.756865 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 21 12:42:53.758458 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 21 12:42:53.758660 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). Mar 21 12:42:53.760526 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 21 12:42:53.764065 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 21 12:42:53.778777 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 21 12:42:53.780726 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 21 12:42:53.782849 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 21 12:42:53.783074 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 21 12:42:53.784922 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 21 12:42:53.785143 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 21 12:42:53.786923 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 21 12:42:53.787480 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 21 12:42:53.788813 augenrules[1369]: No rules Mar 21 12:42:53.790335 systemd[1]: audit-rules.service: Deactivated successfully. Mar 21 12:42:53.791254 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 21 12:42:53.803375 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 21 12:42:53.809616 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 21 12:42:53.811216 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 21 12:42:53.823412 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 21 12:42:53.825568 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 21 12:42:53.832569 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 21 12:42:53.835563 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 21 12:42:53.838760 systemd-udevd[1363]: Using default interface naming scheme 'v255'. Mar 21 12:42:53.845402 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 21 12:42:53.848414 augenrules[1380]: /sbin/augenrules: No change Mar 21 12:42:53.850760 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 21 12:42:53.850812 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). Mar 21 12:42:53.852507 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 21 12:42:53.853628 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 21 12:42:53.855469 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 21 12:42:53.856990 augenrules[1407]: No rules Mar 21 12:42:53.857245 systemd[1]: Finished ensure-sysext.service. Mar 21 12:42:53.861814 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 21 12:42:53.863699 systemd[1]: audit-rules.service: Deactivated successfully. Mar 21 12:42:53.863948 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 21 12:42:53.865287 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 21 12:42:53.865523 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 21 12:42:53.867018 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 21 12:42:53.867225 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 21 12:42:53.868747 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 21 12:42:53.869013 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 21 12:42:53.872825 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 21 12:42:53.873102 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 21 12:42:53.874433 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 21 12:42:53.876041 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 21 12:42:53.894435 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 21 12:42:53.895680 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 21 12:42:53.895787 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 21 12:42:53.900633 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Mar 21 12:42:53.902483 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). Mar 21 12:42:53.926383 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1425) Mar 21 12:42:53.942147 systemd-resolved[1342]: Positive Trust Anchors: Mar 21 12:42:53.942167 systemd-resolved[1342]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 21 12:42:53.942199 systemd-resolved[1342]: 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 Mar 21 12:42:53.946879 systemd-resolved[1342]: Defaulting to hostname 'linux'. Mar 21 12:42:53.949307 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 21 12:42:53.951160 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 21 12:42:53.979381 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 21 12:42:53.992106 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 21 12:42:53.996523 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 21 12:42:54.007356 systemd-networkd[1439]: lo: Link UP Mar 21 12:42:54.007514 systemd-networkd[1439]: lo: Gained carrier Mar 21 12:42:54.009693 systemd-networkd[1439]: Enumeration completed Mar 21 12:42:54.009783 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 21 12:42:54.010061 systemd-networkd[1439]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 21 12:42:54.010074 systemd-networkd[1439]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 21 12:42:54.011644 systemd-networkd[1439]: eth0: Link UP Mar 21 12:42:54.011657 systemd-networkd[1439]: eth0: Gained carrier Mar 21 12:42:54.011670 systemd-networkd[1439]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 21 12:42:54.012104 systemd[1]: Reached target network.target - Network. Mar 21 12:42:54.014710 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 21 12:42:54.017607 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 21 12:42:54.028433 systemd-networkd[1439]: eth0: DHCPv4 address 10.0.0.151/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 21 12:42:54.028489 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Mar 21 12:42:54.029861 systemd-timesyncd[1440]: Network configuration changed, trying to establish connection. Mar 21 12:42:54.931866 systemd-resolved[1342]: Clock change detected. Flushing caches. Mar 21 12:42:54.932019 systemd-timesyncd[1440]: Contacted time server 10.0.0.1:123 (10.0.0.1). Mar 21 12:42:54.932063 systemd-timesyncd[1440]: Initial clock synchronization to Fri 2025-03-21 12:42:54.931452 UTC. Mar 21 12:42:54.932915 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 21 12:42:54.933941 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Mar 21 12:42:54.939878 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Mar 21 12:42:54.947431 kernel: ACPI: button: Power Button [PWRF] Mar 21 12:42:54.947483 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Mar 21 12:42:54.947750 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Mar 21 12:42:54.947975 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Mar 21 12:42:54.944111 systemd[1]: Reached target time-set.target - System Time Set. Mar 21 12:42:54.951245 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Mar 21 12:42:54.963122 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 21 12:42:54.998092 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 21 12:42:55.038885 kernel: mousedev: PS/2 mouse device common for all mice Mar 21 12:42:55.051162 kernel: kvm_amd: TSC scaling supported Mar 21 12:42:55.051191 kernel: kvm_amd: Nested Virtualization enabled Mar 21 12:42:55.051205 kernel: kvm_amd: Nested Paging enabled Mar 21 12:42:55.051217 kernel: kvm_amd: LBR virtualization supported Mar 21 12:42:55.051841 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Mar 21 12:42:55.051875 kernel: kvm_amd: Virtual GIF supported Mar 21 12:42:55.072884 kernel: EDAC MC: Ver: 3.0.0 Mar 21 12:42:55.093801 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 21 12:42:55.104077 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 21 12:42:55.106887 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 21 12:42:55.127982 lvm[1470]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 21 12:42:55.159210 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 21 12:42:55.160791 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 21 12:42:55.161942 systemd[1]: Reached target sysinit.target - System Initialization. Mar 21 12:42:55.163150 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 21 12:42:55.164407 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 21 12:42:55.165849 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 21 12:42:55.167031 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 21 12:42:55.168280 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 21 12:42:55.169517 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 21 12:42:55.169547 systemd[1]: Reached target paths.target - Path Units. Mar 21 12:42:55.170451 systemd[1]: Reached target timers.target - Timer Units. Mar 21 12:42:55.172189 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 21 12:42:55.174896 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 21 12:42:55.178307 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 21 12:42:55.179767 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 21 12:42:55.181123 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 21 12:42:55.188969 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 21 12:42:55.190936 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 21 12:42:55.193589 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 21 12:42:55.195289 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 21 12:42:55.196447 systemd[1]: Reached target sockets.target - Socket Units. Mar 21 12:42:55.197407 systemd[1]: Reached target basic.target - Basic System. Mar 21 12:42:55.198380 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 21 12:42:55.198410 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 21 12:42:55.199411 systemd[1]: Starting containerd.service - containerd container runtime... Mar 21 12:42:55.201477 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 21 12:42:55.204349 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 21 12:42:55.220871 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 21 12:42:55.222045 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). Mar 21 12:42:55.226236 lvm[1474]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 21 12:42:55.225966 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 21 12:42:55.226584 jq[1477]: false Mar 21 12:42:55.229183 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 21 12:42:55.234990 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 21 12:42:55.250094 extend-filesystems[1478]: Found loop3 Mar 21 12:42:55.251069 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 21 12:42:55.250384 dbus-daemon[1476]: [system] SELinux support is enabled Mar 21 12:42:55.251352 extend-filesystems[1478]: Found loop4 Mar 21 12:42:55.251352 extend-filesystems[1478]: Found loop5 Mar 21 12:42:55.251352 extend-filesystems[1478]: Found sr0 Mar 21 12:42:55.251352 extend-filesystems[1478]: Found vda Mar 21 12:42:55.251352 extend-filesystems[1478]: Found vda1 Mar 21 12:42:55.251352 extend-filesystems[1478]: Found vda2 Mar 21 12:42:55.251352 extend-filesystems[1478]: Found vda3 Mar 21 12:42:55.251352 extend-filesystems[1478]: Found usr Mar 21 12:42:55.251352 extend-filesystems[1478]: Found vda4 Mar 21 12:42:55.251352 extend-filesystems[1478]: Found vda6 Mar 21 12:42:55.251352 extend-filesystems[1478]: Found vda7 Mar 21 12:42:55.251352 extend-filesystems[1478]: Found vda9 Mar 21 12:42:55.251352 extend-filesystems[1478]: Checking size of /dev/vda9 Mar 21 12:42:55.254947 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 21 12:42:55.256939 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 21 12:42:55.270018 systemd[1]: Starting update-engine.service - Update Engine... Mar 21 12:42:55.279986 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 21 12:42:55.282191 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 21 12:42:55.287616 extend-filesystems[1478]: Resized partition /dev/vda9 Mar 21 12:42:55.290494 extend-filesystems[1498]: resize2fs 1.47.2 (1-Jan-2025) Mar 21 12:42:55.323285 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1438) Mar 21 12:42:55.292377 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 21 12:42:55.297993 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 21 12:42:55.323554 jq[1495]: true Mar 21 12:42:55.299126 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 21 12:42:55.299457 systemd[1]: motdgen.service: Deactivated successfully. Mar 21 12:42:55.300868 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 21 12:42:55.315432 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 21 12:42:55.315689 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 21 12:42:55.329465 update_engine[1494]: I20250321 12:42:55.328956 1494 main.cc:92] Flatcar Update Engine starting Mar 21 12:42:55.329766 jq[1501]: true Mar 21 12:42:55.331894 update_engine[1494]: I20250321 12:42:55.331517 1494 update_check_scheduler.cc:74] Next update check in 2m4s Mar 21 12:42:55.335454 (ntainerd)[1507]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 21 12:42:55.336916 sshd_keygen[1493]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 21 12:42:55.343853 systemd[1]: Started update-engine.service - Update Engine. Mar 21 12:42:55.345266 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). Mar 21 12:42:55.345296 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 21 12:42:55.346579 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). Mar 21 12:42:55.346600 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 21 12:42:55.353998 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 21 12:42:55.359214 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Mar 21 12:42:55.367780 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 21 12:42:55.370535 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 21 12:42:55.390022 systemd[1]: issuegen.service: Deactivated successfully. Mar 21 12:42:55.390255 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 21 12:42:55.396314 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 21 12:42:55.409078 systemd-logind[1488]: Watching system buttons on /dev/input/event1 (Power Button) Mar 21 12:42:55.409110 systemd-logind[1488]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 21 12:42:55.409406 systemd-logind[1488]: New seat seat0. Mar 21 12:42:55.411951 systemd[1]: Started systemd-logind.service - User Login Management. Mar 21 12:42:55.469859 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 21 12:42:55.475317 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 21 12:42:55.480503 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 21 12:42:55.481259 locksmithd[1524]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 21 12:42:55.481994 systemd[1]: Reached target getty.target - Login Prompts. Mar 21 12:42:55.499844 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Mar 21 12:42:55.519244 extend-filesystems[1498]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Mar 21 12:42:55.519244 extend-filesystems[1498]: old_desc_blocks = 1, new_desc_blocks = 1 Mar 21 12:42:55.519244 extend-filesystems[1498]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Mar 21 12:42:55.523416 extend-filesystems[1478]: Resized filesystem in /dev/vda9 Mar 21 12:42:55.526109 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 21 12:42:55.526400 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 21 12:42:55.529156 bash[1533]: Updated "/home/core/.ssh/authorized_keys" Mar 21 12:42:55.530929 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 21 12:42:55.532872 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 21 12:42:55.624343 containerd[1507]: time="2025-03-21T12:42:55Z" 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 Mar 21 12:42:55.625047 containerd[1507]: time="2025-03-21T12:42:55.624992808Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 21 12:42:55.634756 containerd[1507]: time="2025-03-21T12:42:55.634704772Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.947µs" Mar 21 12:42:55.634756 containerd[1507]: time="2025-03-21T12:42:55.634747632Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 21 12:42:55.634812 containerd[1507]: time="2025-03-21T12:42:55.634768721Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 21 12:42:55.635021 containerd[1507]: time="2025-03-21T12:42:55.634994585Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 21 12:42:55.635061 containerd[1507]: time="2025-03-21T12:42:55.635019762Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 21 12:42:55.635061 containerd[1507]: time="2025-03-21T12:42:55.635048246Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 21 12:42:55.635140 containerd[1507]: time="2025-03-21T12:42:55.635115532Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 21 12:42:55.635140 containerd[1507]: time="2025-03-21T12:42:55.635132283Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 21 12:42:55.635449 containerd[1507]: time="2025-03-21T12:42:55.635423259Z" 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 Mar 21 12:42:55.635449 containerd[1507]: time="2025-03-21T12:42:55.635442084Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 21 12:42:55.635506 containerd[1507]: time="2025-03-21T12:42:55.635454107Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 21 12:42:55.635506 containerd[1507]: time="2025-03-21T12:42:55.635463855Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 21 12:42:55.635576 containerd[1507]: time="2025-03-21T12:42:55.635553503Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 21 12:42:55.635812 containerd[1507]: time="2025-03-21T12:42:55.635785939Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 21 12:42:55.635853 containerd[1507]: time="2025-03-21T12:42:55.635838948Z" 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 Mar 21 12:42:55.635881 containerd[1507]: time="2025-03-21T12:42:55.635858716Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 21 12:42:55.635917 containerd[1507]: time="2025-03-21T12:42:55.635901897Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 21 12:42:55.636175 containerd[1507]: time="2025-03-21T12:42:55.636142668Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 21 12:42:55.636250 containerd[1507]: time="2025-03-21T12:42:55.636233348Z" level=info msg="metadata content store policy set" policy=shared Mar 21 12:42:55.641918 containerd[1507]: time="2025-03-21T12:42:55.641888373Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 21 12:42:55.641961 containerd[1507]: time="2025-03-21T12:42:55.641928008Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 21 12:42:55.641961 containerd[1507]: time="2025-03-21T12:42:55.641943667Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 21 12:42:55.641961 containerd[1507]: time="2025-03-21T12:42:55.641957643Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 21 12:42:55.642030 containerd[1507]: time="2025-03-21T12:42:55.641981458Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 21 12:42:55.642030 containerd[1507]: time="2025-03-21T12:42:55.641995995Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 21 12:42:55.642030 containerd[1507]: time="2025-03-21T12:42:55.642007897Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 21 12:42:55.642030 containerd[1507]: time="2025-03-21T12:42:55.642020371Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 21 12:42:55.642100 containerd[1507]: time="2025-03-21T12:42:55.642032433Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 21 12:42:55.642100 containerd[1507]: time="2025-03-21T12:42:55.642044376Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 21 12:42:55.642100 containerd[1507]: time="2025-03-21T12:42:55.642055346Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 21 12:42:55.642100 containerd[1507]: time="2025-03-21T12:42:55.642071356Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 21 12:42:55.642195 containerd[1507]: time="2025-03-21T12:42:55.642176504Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 21 12:42:55.642217 containerd[1507]: time="2025-03-21T12:42:55.642204125Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 21 12:42:55.642237 containerd[1507]: time="2025-03-21T12:42:55.642218553Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 21 12:42:55.642257 containerd[1507]: time="2025-03-21T12:42:55.642235605Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 21 12:42:55.642257 containerd[1507]: time="2025-03-21T12:42:55.642246856Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 21 12:42:55.642300 containerd[1507]: time="2025-03-21T12:42:55.642257135Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 21 12:42:55.642300 containerd[1507]: time="2025-03-21T12:42:55.642268626Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 21 12:42:55.642300 containerd[1507]: time="2025-03-21T12:42:55.642278515Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 21 12:42:55.642300 containerd[1507]: time="2025-03-21T12:42:55.642289275Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 21 12:42:55.642300 containerd[1507]: time="2025-03-21T12:42:55.642300436Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 21 12:42:55.642397 containerd[1507]: time="2025-03-21T12:42:55.642312348Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 21 12:42:55.642397 containerd[1507]: time="2025-03-21T12:42:55.642364887Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 21 12:42:55.642397 containerd[1507]: time="2025-03-21T12:42:55.642377300Z" level=info msg="Start snapshots syncer" Mar 21 12:42:55.642464 containerd[1507]: time="2025-03-21T12:42:55.642398660Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 21 12:42:55.642677 containerd[1507]: time="2025-03-21T12:42:55.642636987Z" 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\"}" Mar 21 12:42:55.642789 containerd[1507]: time="2025-03-21T12:42:55.642685478Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 21 12:42:55.642789 containerd[1507]: time="2025-03-21T12:42:55.642752774Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 21 12:42:55.642912 containerd[1507]: time="2025-03-21T12:42:55.642883219Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 21 12:42:55.642912 containerd[1507]: time="2025-03-21T12:42:55.642908086Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 21 12:42:55.642954 containerd[1507]: time="2025-03-21T12:42:55.642919567Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 21 12:42:55.642954 containerd[1507]: time="2025-03-21T12:42:55.642930758Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 21 12:42:55.642954 containerd[1507]: time="2025-03-21T12:42:55.642942891Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 21 12:42:55.642954 containerd[1507]: time="2025-03-21T12:42:55.642952960Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 21 12:42:55.643040 containerd[1507]: time="2025-03-21T12:42:55.642965984Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 21 12:42:55.643040 containerd[1507]: time="2025-03-21T12:42:55.642993636Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 21 12:42:55.643040 containerd[1507]: time="2025-03-21T12:42:55.643004907Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 21 12:42:55.643040 containerd[1507]: time="2025-03-21T12:42:55.643014215Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 21 12:42:55.643115 containerd[1507]: time="2025-03-21T12:42:55.643060702Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 21 12:42:55.643115 containerd[1507]: time="2025-03-21T12:42:55.643073326Z" 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 Mar 21 12:42:55.643115 containerd[1507]: time="2025-03-21T12:42:55.643082022Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 21 12:42:55.643115 containerd[1507]: time="2025-03-21T12:42:55.643091339Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 21 12:42:55.643115 containerd[1507]: time="2025-03-21T12:42:55.643099595Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 21 12:42:55.643115 containerd[1507]: time="2025-03-21T12:42:55.643109263Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 21 12:42:55.643115 containerd[1507]: time="2025-03-21T12:42:55.643118861Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 21 12:42:55.643242 containerd[1507]: time="2025-03-21T12:42:55.643137436Z" level=info msg="runtime interface created" Mar 21 12:42:55.643242 containerd[1507]: time="2025-03-21T12:42:55.643143687Z" level=info msg="created NRI interface" Mar 21 12:42:55.643242 containerd[1507]: time="2025-03-21T12:42:55.643151292Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 21 12:42:55.643242 containerd[1507]: time="2025-03-21T12:42:55.643161491Z" level=info msg="Connect containerd service" Mar 21 12:42:55.643242 containerd[1507]: time="2025-03-21T12:42:55.643187981Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 21 12:42:55.643863 containerd[1507]: time="2025-03-21T12:42:55.643811510Z" 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" Mar 21 12:42:55.733482 containerd[1507]: time="2025-03-21T12:42:55.733413095Z" level=info msg="Start subscribing containerd event" Mar 21 12:42:55.733597 containerd[1507]: time="2025-03-21T12:42:55.733502953Z" level=info msg="Start recovering state" Mar 21 12:42:55.733597 containerd[1507]: time="2025-03-21T12:42:55.733559930Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 21 12:42:55.733653 containerd[1507]: time="2025-03-21T12:42:55.733634290Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 21 12:42:55.733703 containerd[1507]: time="2025-03-21T12:42:55.733637406Z" level=info msg="Start event monitor" Mar 21 12:42:55.733703 containerd[1507]: time="2025-03-21T12:42:55.733679945Z" level=info msg="Start cni network conf syncer for default" Mar 21 12:42:55.733703 containerd[1507]: time="2025-03-21T12:42:55.733689112Z" level=info msg="Start streaming server" Mar 21 12:42:55.733703 containerd[1507]: time="2025-03-21T12:42:55.733699071Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 21 12:42:55.733779 containerd[1507]: time="2025-03-21T12:42:55.733707397Z" level=info msg="runtime interface starting up..." Mar 21 12:42:55.733957 containerd[1507]: time="2025-03-21T12:42:55.733892494Z" level=info msg="starting plugins..." Mar 21 12:42:55.733982 containerd[1507]: time="2025-03-21T12:42:55.733959459Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 21 12:42:55.734166 containerd[1507]: time="2025-03-21T12:42:55.734149005Z" level=info msg="containerd successfully booted in 0.110605s" Mar 21 12:42:55.734329 systemd[1]: Started containerd.service - containerd container runtime. Mar 21 12:42:56.301035 systemd-networkd[1439]: eth0: Gained IPv6LL Mar 21 12:42:56.304197 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 21 12:42:56.306034 systemd[1]: Reached target network-online.target - Network is Online. Mar 21 12:42:56.308588 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 21 12:42:56.310908 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 21 12:42:56.321028 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 21 12:42:56.337924 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 21 12:42:56.338217 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 21 12:42:56.339841 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 21 12:42:56.344692 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 21 12:42:56.989330 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 21 12:42:56.990963 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 21 12:42:56.994830 (kubelet)[1594]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Mar 21 12:42:56.995076 systemd[1]: Startup finished in 717ms (kernel) + 5.231s (initrd) + 4.210s (userspace) = 10.159s. Mar 21 12:42:57.387127 kubelet[1594]: E0321 12:42:57.386955 1594 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" Mar 21 12:42:57.391272 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 21 12:42:57.391482 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 21 12:42:57.391869 systemd[1]: kubelet.service: Consumed 955ms CPU time, 255.6M memory peak. Mar 21 12:43:00.898042 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 21 12:43:00.899284 systemd[1]: Started sshd@0-10.0.0.151:22-10.0.0.1:34184.service - OpenSSH per-connection server daemon (10.0.0.1:34184). Mar 21 12:43:00.983142 sshd[1608]: Accepted publickey for core from 10.0.0.1 port 34184 ssh2: RSA SHA256:lTgMMt/0ISQMJMexy8Vr8KG+9PSByON0JAakDTVcySk Mar 21 12:43:00.985099 sshd-session[1608]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:43:00.995894 systemd-logind[1488]: New session 1 of user core. Mar 21 12:43:00.997090 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 21 12:43:00.998280 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 21 12:43:01.021488 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 21 12:43:01.024095 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 21 12:43:01.043125 (systemd)[1612]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 21 12:43:01.045357 systemd-logind[1488]: New session c1 of user core. Mar 21 12:43:01.187323 systemd[1612]: Queued start job for default target default.target. Mar 21 12:43:01.197089 systemd[1612]: Created slice app.slice - User Application Slice. Mar 21 12:43:01.197115 systemd[1612]: Reached target paths.target - Paths. Mar 21 12:43:01.197155 systemd[1612]: Reached target timers.target - Timers. Mar 21 12:43:01.198638 systemd[1612]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 21 12:43:01.210463 systemd[1612]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 21 12:43:01.210643 systemd[1612]: Reached target sockets.target - Sockets. Mar 21 12:43:01.210703 systemd[1612]: Reached target basic.target - Basic System. Mar 21 12:43:01.210777 systemd[1612]: Reached target default.target - Main User Target. Mar 21 12:43:01.210845 systemd[1612]: Startup finished in 159ms. Mar 21 12:43:01.211024 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 21 12:43:01.212632 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 21 12:43:01.272991 systemd[1]: Started sshd@1-10.0.0.151:22-10.0.0.1:34192.service - OpenSSH per-connection server daemon (10.0.0.1:34192). Mar 21 12:43:01.318908 sshd[1623]: Accepted publickey for core from 10.0.0.1 port 34192 ssh2: RSA SHA256:lTgMMt/0ISQMJMexy8Vr8KG+9PSByON0JAakDTVcySk Mar 21 12:43:01.320340 sshd-session[1623]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:43:01.324609 systemd-logind[1488]: New session 2 of user core. Mar 21 12:43:01.336948 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 21 12:43:01.390056 sshd[1625]: Connection closed by 10.0.0.1 port 34192 Mar 21 12:43:01.390401 sshd-session[1623]: pam_unix(sshd:session): session closed for user core Mar 21 12:43:01.408379 systemd[1]: sshd@1-10.0.0.151:22-10.0.0.1:34192.service: Deactivated successfully. Mar 21 12:43:01.410337 systemd[1]: session-2.scope: Deactivated successfully. Mar 21 12:43:01.411918 systemd-logind[1488]: Session 2 logged out. Waiting for processes to exit. Mar 21 12:43:01.413182 systemd[1]: Started sshd@2-10.0.0.151:22-10.0.0.1:34200.service - OpenSSH per-connection server daemon (10.0.0.1:34200). Mar 21 12:43:01.413917 systemd-logind[1488]: Removed session 2. Mar 21 12:43:01.467377 sshd[1630]: Accepted publickey for core from 10.0.0.1 port 34200 ssh2: RSA SHA256:lTgMMt/0ISQMJMexy8Vr8KG+9PSByON0JAakDTVcySk Mar 21 12:43:01.468788 sshd-session[1630]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:43:01.472689 systemd-logind[1488]: New session 3 of user core. Mar 21 12:43:01.485939 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 21 12:43:01.535094 sshd[1633]: Connection closed by 10.0.0.1 port 34200 Mar 21 12:43:01.535421 sshd-session[1630]: pam_unix(sshd:session): session closed for user core Mar 21 12:43:01.546230 systemd[1]: sshd@2-10.0.0.151:22-10.0.0.1:34200.service: Deactivated successfully. Mar 21 12:43:01.547758 systemd[1]: session-3.scope: Deactivated successfully. Mar 21 12:43:01.549389 systemd-logind[1488]: Session 3 logged out. Waiting for processes to exit. Mar 21 12:43:01.550589 systemd[1]: Started sshd@3-10.0.0.151:22-10.0.0.1:34214.service - OpenSSH per-connection server daemon (10.0.0.1:34214). Mar 21 12:43:01.551326 systemd-logind[1488]: Removed session 3. Mar 21 12:43:01.597843 sshd[1638]: Accepted publickey for core from 10.0.0.1 port 34214 ssh2: RSA SHA256:lTgMMt/0ISQMJMexy8Vr8KG+9PSByON0JAakDTVcySk Mar 21 12:43:01.599225 sshd-session[1638]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:43:01.603024 systemd-logind[1488]: New session 4 of user core. Mar 21 12:43:01.612956 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 21 12:43:01.665359 sshd[1641]: Connection closed by 10.0.0.1 port 34214 Mar 21 12:43:01.665758 sshd-session[1638]: pam_unix(sshd:session): session closed for user core Mar 21 12:43:01.677489 systemd[1]: sshd@3-10.0.0.151:22-10.0.0.1:34214.service: Deactivated successfully. Mar 21 12:43:01.679226 systemd[1]: session-4.scope: Deactivated successfully. Mar 21 12:43:01.680787 systemd-logind[1488]: Session 4 logged out. Waiting for processes to exit. Mar 21 12:43:01.682076 systemd[1]: Started sshd@4-10.0.0.151:22-10.0.0.1:34228.service - OpenSSH per-connection server daemon (10.0.0.1:34228). Mar 21 12:43:01.682777 systemd-logind[1488]: Removed session 4. Mar 21 12:43:01.730447 sshd[1646]: Accepted publickey for core from 10.0.0.1 port 34228 ssh2: RSA SHA256:lTgMMt/0ISQMJMexy8Vr8KG+9PSByON0JAakDTVcySk Mar 21 12:43:01.732030 sshd-session[1646]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 21 12:43:01.736445 systemd-logind[1488]: New session 5 of user core. Mar 21 12:43:01.745944 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 21 12:43:01.803678 sudo[1650]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Mar 21 12:43:01.804035 sudo[1650]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 21 12:43:01.817380 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 21 12:43:01.850038 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 21 12:43:01.850298 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 21 12:43:02.276010 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 21 12:43:02.276176 systemd[1]: kubelet.service: Consumed 955ms CPU time, 255.6M memory peak. Mar 21 12:43:02.278418 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 21 12:43:02.302871 systemd[1]: Reload requested from client PID 1692 ('systemctl') (unit session-5.scope)... Mar 21 12:43:02.302887 systemd[1]: Reloading... Mar 21 12:43:02.390235 zram_generator::config[1737]: No configuration found. Mar 21 12:43:02.956517 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 21 12:43:03.056514 systemd[1]: Reloading finished in 753 ms. Mar 21 12:43:03.117084 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Mar 21 12:43:03.118656 systemd[1]: kubelet.service: Deactivated successfully. Mar 21 12:43:03.118948 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 21 12:43:03.118992 systemd[1]: kubelet.service: Consumed 152ms CPU time, 91.8M memory peak. Mar 21 12:43:03.120551 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 21 12:43:03.318096 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 21 12:43:03.322222 (kubelet)[1785]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Mar 21 12:43:03.362031 kubelet[1785]: 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. Mar 21 12:43:03.362031 kubelet[1785]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Mar 21 12:43:03.362031 kubelet[1785]: 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. Mar 21 12:43:03.362445 kubelet[1785]: I0321 12:43:03.362077 1785 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Mar 21 12:43:03.805353 kubelet[1785]: I0321 12:43:03.805230 1785 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" Mar 21 12:43:03.805353 kubelet[1785]: I0321 12:43:03.805261 1785 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Mar 21 12:43:03.805537 kubelet[1785]: I0321 12:43:03.805514 1785 server.go:954] "Client rotation is on, will bootstrap in background" Mar 21 12:43:03.827964 kubelet[1785]: I0321 12:43:03.827920 1785 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Mar 21 12:43:03.833992 kubelet[1785]: I0321 12:43:03.833964 1785 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Mar 21 12:43:03.839410 kubelet[1785]: I0321 12:43:03.839356 1785 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Mar 21 12:43:03.840841 kubelet[1785]: I0321 12:43:03.840724 1785 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Mar 21 12:43:03.841114 kubelet[1785]: I0321 12:43:03.840793 1785 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.151","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Mar 21 12:43:03.841387 kubelet[1785]: I0321 12:43:03.841363 1785 topology_manager.go:138] "Creating topology manager with none policy" Mar 21 12:43:03.841545 kubelet[1785]: I0321 12:43:03.841529 1785 container_manager_linux.go:304] "Creating device plugin manager" Mar 21 12:43:03.841711 kubelet[1785]: I0321 12:43:03.841686 1785 state_mem.go:36] "Initialized new in-memory state store" Mar 21 12:43:03.844389 kubelet[1785]: I0321 12:43:03.844367 1785 kubelet.go:446] "Attempting to sync node with API server" Mar 21 12:43:03.844389 kubelet[1785]: I0321 12:43:03.844388 1785 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Mar 21 12:43:03.844472 kubelet[1785]: I0321 12:43:03.844424 1785 kubelet.go:352] "Adding apiserver pod source" Mar 21 12:43:03.844543 kubelet[1785]: E0321 12:43:03.844519 1785 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:03.844600 kubelet[1785]: E0321 12:43:03.844562 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:03.845902 kubelet[1785]: I0321 12:43:03.845878 1785 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Mar 21 12:43:03.848524 kubelet[1785]: I0321 12:43:03.848504 1785 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.1" apiVersion="v1" Mar 21 12:43:03.848932 kubelet[1785]: I0321 12:43:03.848916 1785 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Mar 21 12:43:03.849402 kubelet[1785]: W0321 12:43:03.849385 1785 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Mar 21 12:43:03.851497 kubelet[1785]: I0321 12:43:03.851472 1785 watchdog_linux.go:99] "Systemd watchdog is not enabled" Mar 21 12:43:03.851532 kubelet[1785]: I0321 12:43:03.851517 1785 server.go:1287] "Started kubelet" Mar 21 12:43:03.853966 kubelet[1785]: I0321 12:43:03.853581 1785 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Mar 21 12:43:03.853966 kubelet[1785]: I0321 12:43:03.853591 1785 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Mar 21 12:43:03.853966 kubelet[1785]: I0321 12:43:03.853596 1785 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Mar 21 12:43:03.853966 kubelet[1785]: I0321 12:43:03.853916 1785 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Mar 21 12:43:03.854476 kubelet[1785]: I0321 12:43:03.854434 1785 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Mar 21 12:43:03.854927 kubelet[1785]: I0321 12:43:03.854907 1785 server.go:490] "Adding debug handlers to kubelet server" Mar 21 12:43:03.856430 kubelet[1785]: W0321 12:43:03.856395 1785 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Mar 21 12:43:03.856478 kubelet[1785]: W0321 12:43:03.856431 1785 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.151" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Mar 21 12:43:03.856478 kubelet[1785]: E0321 12:43:03.856451 1785 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" Mar 21 12:43:03.856478 kubelet[1785]: E0321 12:43:03.856460 1785 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.151\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" Mar 21 12:43:03.856928 kubelet[1785]: I0321 12:43:03.856620 1785 volume_manager.go:297] "Starting Kubelet Volume Manager" Mar 21 12:43:03.856928 kubelet[1785]: E0321 12:43:03.856633 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:03.856928 kubelet[1785]: I0321 12:43:03.856752 1785 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Mar 21 12:43:03.856928 kubelet[1785]: I0321 12:43:03.856800 1785 reconciler.go:26] "Reconciler: start to sync state" Mar 21 12:43:03.857710 kubelet[1785]: I0321 12:43:03.857680 1785 factory.go:221] Registration of the systemd container factory successfully Mar 21 12:43:03.857771 kubelet[1785]: I0321 12:43:03.857759 1785 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 Mar 21 12:43:03.858358 kubelet[1785]: E0321 12:43:03.858181 1785 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Mar 21 12:43:03.858891 kubelet[1785]: I0321 12:43:03.858869 1785 factory.go:221] Registration of the containerd container factory successfully Mar 21 12:43:03.861600 kubelet[1785]: W0321 12:43:03.861581 1785 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope Mar 21 12:43:03.861669 kubelet[1785]: E0321 12:43:03.861607 1785 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:anonymous\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError" Mar 21 12:43:03.862556 kubelet[1785]: E0321 12:43:03.861672 1785 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.151.182ed1fb79ec59fa default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.151,UID:10.0.0.151,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.151,},FirstTimestamp:2025-03-21 12:43:03.851489786 +0000 UTC m=+0.525532892,LastTimestamp:2025-03-21 12:43:03.851489786 +0000 UTC m=+0.525532892,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.151,}" Mar 21 12:43:03.862734 kubelet[1785]: E0321 12:43:03.862706 1785 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.151\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" Mar 21 12:43:03.865557 kubelet[1785]: E0321 12:43:03.864699 1785 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.151.182ed1fb7a523b46 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.151,UID:10.0.0.151,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.0.0.151,},FirstTimestamp:2025-03-21 12:43:03.858166598 +0000 UTC m=+0.532209704,LastTimestamp:2025-03-21 12:43:03.858166598 +0000 UTC m=+0.532209704,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.151,}" Mar 21 12:43:03.870111 kubelet[1785]: I0321 12:43:03.870093 1785 cpu_manager.go:221] "Starting CPU manager" policy="none" Mar 21 12:43:03.870111 kubelet[1785]: I0321 12:43:03.870107 1785 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Mar 21 12:43:03.870183 kubelet[1785]: I0321 12:43:03.870122 1785 state_mem.go:36] "Initialized new in-memory state store" Mar 21 12:43:03.873681 kubelet[1785]: E0321 12:43:03.873516 1785 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.151.182ed1fb7afb119e default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.151,UID:10.0.0.151,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientMemory,Message:Node 10.0.0.151 status is now: NodeHasSufficientMemory,Source:EventSource{Component:kubelet,Host:10.0.0.151,},FirstTimestamp:2025-03-21 12:43:03.869231518 +0000 UTC m=+0.543274625,LastTimestamp:2025-03-21 12:43:03.869231518 +0000 UTC m=+0.543274625,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.151,}" Mar 21 12:43:03.879888 kubelet[1785]: E0321 12:43:03.879746 1785 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.151.182ed1fb7afb2381 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.151,UID:10.0.0.151,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasNoDiskPressure,Message:Node 10.0.0.151 status is now: NodeHasNoDiskPressure,Source:EventSource{Component:kubelet,Host:10.0.0.151,},FirstTimestamp:2025-03-21 12:43:03.869236097 +0000 UTC m=+0.543279203,LastTimestamp:2025-03-21 12:43:03.869236097 +0000 UTC m=+0.543279203,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.151,}" Mar 21 12:43:03.883400 kubelet[1785]: E0321 12:43:03.883205 1785 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.151.182ed1fb7afb2c6d default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.151,UID:10.0.0.151,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientPID,Message:Node 10.0.0.151 status is now: NodeHasSufficientPID,Source:EventSource{Component:kubelet,Host:10.0.0.151,},FirstTimestamp:2025-03-21 12:43:03.869238381 +0000 UTC m=+0.543281487,LastTimestamp:2025-03-21 12:43:03.869238381 +0000 UTC m=+0.543281487,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.151,}" Mar 21 12:43:03.957279 kubelet[1785]: E0321 12:43:03.957230 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.058389 kubelet[1785]: E0321 12:43:04.058289 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.069339 kubelet[1785]: E0321 12:43:04.069282 1785 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.151\" not found" node="10.0.0.151" Mar 21 12:43:04.158643 kubelet[1785]: E0321 12:43:04.158603 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.259110 kubelet[1785]: E0321 12:43:04.259068 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.360253 kubelet[1785]: E0321 12:43:04.360152 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.460703 kubelet[1785]: E0321 12:43:04.460666 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.561139 kubelet[1785]: E0321 12:43:04.561118 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.661750 kubelet[1785]: E0321 12:43:04.661595 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.762159 kubelet[1785]: E0321 12:43:04.762126 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.807344 kubelet[1785]: I0321 12:43:04.807316 1785 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Mar 21 12:43:04.840411 kubelet[1785]: I0321 12:43:04.840379 1785 policy_none.go:49] "None policy: Start" Mar 21 12:43:04.840411 kubelet[1785]: I0321 12:43:04.840402 1785 memory_manager.go:186] "Starting memorymanager" policy="None" Mar 21 12:43:04.840411 kubelet[1785]: I0321 12:43:04.840417 1785 state_mem.go:35] "Initializing new in-memory state store" Mar 21 12:43:04.844741 kubelet[1785]: E0321 12:43:04.844707 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:04.851359 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Mar 21 12:43:04.862893 kubelet[1785]: E0321 12:43:04.862434 1785 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.151\" not found" Mar 21 12:43:04.862742 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Mar 21 12:43:04.865853 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Mar 21 12:43:04.871799 kubelet[1785]: I0321 12:43:04.871768 1785 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Mar 21 12:43:04.872064 kubelet[1785]: I0321 12:43:04.872014 1785 eviction_manager.go:189] "Eviction manager: starting control loop" Mar 21 12:43:04.872064 kubelet[1785]: I0321 12:43:04.872045 1785 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Mar 21 12:43:04.872390 kubelet[1785]: I0321 12:43:04.872339 1785 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Mar 21 12:43:04.873172 kubelet[1785]: E0321 12:43:04.873090 1785 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Mar 21 12:43:04.873172 kubelet[1785]: E0321 12:43:04.873130 1785 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.151\" not found" Mar 21 12:43:04.873545 kubelet[1785]: I0321 12:43:04.873510 1785 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Mar 21 12:43:04.875312 kubelet[1785]: I0321 12:43:04.875270 1785 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Mar 21 12:43:04.875312 kubelet[1785]: I0321 12:43:04.875293 1785 status_manager.go:227] "Starting to sync pod status with apiserver" Mar 21 12:43:04.875312 kubelet[1785]: I0321 12:43:04.875310 1785 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Mar 21 12:43:04.875312 kubelet[1785]: I0321 12:43:04.875317 1785 kubelet.go:2388] "Starting kubelet main sync loop" Mar 21 12:43:04.875871 kubelet[1785]: E0321 12:43:04.875365 1785 kubelet.go:2412] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Mar 21 12:43:04.973741 kubelet[1785]: I0321 12:43:04.973620 1785 kubelet_node_status.go:76] "Attempting to register node" node="10.0.0.151" Mar 21 12:43:05.061740 kubelet[1785]: I0321 12:43:05.061682 1785 kubelet_node_status.go:79] "Successfully registered node" node="10.0.0.151" Mar 21 12:43:05.165389 kubelet[1785]: I0321 12:43:05.165351 1785 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Mar 21 12:43:05.165806 containerd[1507]: time="2025-03-21T12:43:05.165758880Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Mar 21 12:43:05.166160 kubelet[1785]: I0321 12:43:05.166050 1785 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Mar 21 12:43:05.349911 sudo[1650]: pam_unix(sudo:session): session closed for user root Mar 21 12:43:05.351431 sshd[1649]: Connection closed by 10.0.0.1 port 34228 Mar 21 12:43:05.351780 sshd-session[1646]: pam_unix(sshd:session): session closed for user core Mar 21 12:43:05.356168 systemd[1]: sshd@4-10.0.0.151:22-10.0.0.1:34228.service: Deactivated successfully. Mar 21 12:43:05.358497 systemd[1]: session-5.scope: Deactivated successfully. Mar 21 12:43:05.358733 systemd[1]: session-5.scope: Consumed 479ms CPU time, 76.5M memory peak. Mar 21 12:43:05.360030 systemd-logind[1488]: Session 5 logged out. Waiting for processes to exit. Mar 21 12:43:05.360877 systemd-logind[1488]: Removed session 5. Mar 21 12:43:05.844946 kubelet[1785]: E0321 12:43:05.844775 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:05.846924 kubelet[1785]: I0321 12:43:05.846897 1785 apiserver.go:52] "Watching apiserver" Mar 21 12:43:05.855469 systemd[1]: Created slice kubepods-burstable-pod72fdb19a_80ed_4f19_8e42_a39530bff1ae.slice - libcontainer container kubepods-burstable-pod72fdb19a_80ed_4f19_8e42_a39530bff1ae.slice. Mar 21 12:43:05.858033 kubelet[1785]: I0321 12:43:05.858001 1785 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Mar 21 12:43:05.867800 kubelet[1785]: I0321 12:43:05.867766 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/72fdb19a-80ed-4f19-8e42-a39530bff1ae-cni\") pod \"kube-flannel-ds-hmt4j\" (UID: \"72fdb19a-80ed-4f19-8e42-a39530bff1ae\") " pod="kube-flannel/kube-flannel-ds-hmt4j" Mar 21 12:43:05.867800 kubelet[1785]: I0321 12:43:05.867793 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/72fdb19a-80ed-4f19-8e42-a39530bff1ae-flannel-cfg\") pod \"kube-flannel-ds-hmt4j\" (UID: \"72fdb19a-80ed-4f19-8e42-a39530bff1ae\") " pod="kube-flannel/kube-flannel-ds-hmt4j" Mar 21 12:43:05.867800 kubelet[1785]: I0321 12:43:05.867811 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/72fdb19a-80ed-4f19-8e42-a39530bff1ae-xtables-lock\") pod \"kube-flannel-ds-hmt4j\" (UID: \"72fdb19a-80ed-4f19-8e42-a39530bff1ae\") " pod="kube-flannel/kube-flannel-ds-hmt4j" Mar 21 12:43:05.868006 kubelet[1785]: I0321 12:43:05.867839 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/c64c7055-9286-48f0-a60a-5db4dfe0d8a8-xtables-lock\") pod \"kube-proxy-nljsc\" (UID: \"c64c7055-9286-48f0-a60a-5db4dfe0d8a8\") " pod="kube-system/kube-proxy-nljsc" Mar 21 12:43:05.868006 kubelet[1785]: I0321 12:43:05.867853 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/72fdb19a-80ed-4f19-8e42-a39530bff1ae-run\") pod \"kube-flannel-ds-hmt4j\" (UID: \"72fdb19a-80ed-4f19-8e42-a39530bff1ae\") " pod="kube-flannel/kube-flannel-ds-hmt4j" Mar 21 12:43:05.868006 kubelet[1785]: I0321 12:43:05.867880 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/72fdb19a-80ed-4f19-8e42-a39530bff1ae-cni-plugin\") pod \"kube-flannel-ds-hmt4j\" (UID: \"72fdb19a-80ed-4f19-8e42-a39530bff1ae\") " pod="kube-flannel/kube-flannel-ds-hmt4j" Mar 21 12:43:05.868006 kubelet[1785]: I0321 12:43:05.867899 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-grsx2\" (UniqueName: \"kubernetes.io/projected/72fdb19a-80ed-4f19-8e42-a39530bff1ae-kube-api-access-grsx2\") pod \"kube-flannel-ds-hmt4j\" (UID: \"72fdb19a-80ed-4f19-8e42-a39530bff1ae\") " pod="kube-flannel/kube-flannel-ds-hmt4j" Mar 21 12:43:05.868006 kubelet[1785]: I0321 12:43:05.867912 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/c64c7055-9286-48f0-a60a-5db4dfe0d8a8-kube-proxy\") pod \"kube-proxy-nljsc\" (UID: \"c64c7055-9286-48f0-a60a-5db4dfe0d8a8\") " pod="kube-system/kube-proxy-nljsc" Mar 21 12:43:05.868148 kubelet[1785]: I0321 12:43:05.867925 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/c64c7055-9286-48f0-a60a-5db4dfe0d8a8-lib-modules\") pod \"kube-proxy-nljsc\" (UID: \"c64c7055-9286-48f0-a60a-5db4dfe0d8a8\") " pod="kube-system/kube-proxy-nljsc" Mar 21 12:43:05.868148 kubelet[1785]: I0321 12:43:05.867940 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rlldt\" (UniqueName: \"kubernetes.io/projected/c64c7055-9286-48f0-a60a-5db4dfe0d8a8-kube-api-access-rlldt\") pod \"kube-proxy-nljsc\" (UID: \"c64c7055-9286-48f0-a60a-5db4dfe0d8a8\") " pod="kube-system/kube-proxy-nljsc" Mar 21 12:43:05.879253 systemd[1]: Created slice kubepods-besteffort-podc64c7055_9286_48f0_a60a_5db4dfe0d8a8.slice - libcontainer container kubepods-besteffort-podc64c7055_9286_48f0_a60a_5db4dfe0d8a8.slice. Mar 21 12:43:06.178208 containerd[1507]: time="2025-03-21T12:43:06.178078521Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-hmt4j,Uid:72fdb19a-80ed-4f19-8e42-a39530bff1ae,Namespace:kube-flannel,Attempt:0,}" Mar 21 12:43:06.196696 containerd[1507]: time="2025-03-21T12:43:06.196647564Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-nljsc,Uid:c64c7055-9286-48f0-a60a-5db4dfe0d8a8,Namespace:kube-system,Attempt:0,}" Mar 21 12:43:06.845327 kubelet[1785]: E0321 12:43:06.845280 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:07.312089 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3127378161.mount: Deactivated successfully. Mar 21 12:43:07.321000 containerd[1507]: time="2025-03-21T12:43:07.320953459Z" 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\"}" Mar 21 12:43:07.322671 containerd[1507]: time="2025-03-21T12:43:07.322612400Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321138" Mar 21 12:43:07.323707 containerd[1507]: time="2025-03-21T12:43:07.323664954Z" 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\"}" Mar 21 12:43:07.324607 containerd[1507]: time="2025-03-21T12:43:07.324565152Z" level=info msg="ImageCreate event name:\"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Mar 21 12:43:07.325500 containerd[1507]: time="2025-03-21T12:43:07.325452607Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Mar 21 12:43:07.327043 containerd[1507]: time="2025-03-21T12:43:07.327014836Z" 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\"}" Mar 21 12:43:07.327652 containerd[1507]: time="2025-03-21T12:43:07.327626183Z" 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 1.125698397s" Mar 21 12:43:07.328673 containerd[1507]: time="2025-03-21T12:43:07.328648340Z" 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 1.142797002s" Mar 21 12:43:07.349782 containerd[1507]: time="2025-03-21T12:43:07.349736057Z" level=info msg="connecting to shim 907c3eb78f579197e1c7ee38eba4535f5fe0762cb694dd514724edcde3324892" address="unix:///run/containerd/s/a62bec908841892cb6f17466e994c4b7bdaf10ccff3ab6308cd8ac2226775924" namespace=k8s.io protocol=ttrpc version=3 Mar 21 12:43:07.351040 containerd[1507]: time="2025-03-21T12:43:07.351010056Z" level=info msg="connecting to shim c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49" address="unix:///run/containerd/s/f96ad10328be99e26da5761922fc25a3cba23ba667e377d29f9cbe2a94b64890" namespace=k8s.io protocol=ttrpc version=3 Mar 21 12:43:07.374979 systemd[1]: Started cri-containerd-907c3eb78f579197e1c7ee38eba4535f5fe0762cb694dd514724edcde3324892.scope - libcontainer container 907c3eb78f579197e1c7ee38eba4535f5fe0762cb694dd514724edcde3324892. Mar 21 12:43:07.378093 systemd[1]: Started cri-containerd-c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49.scope - libcontainer container c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49. Mar 21 12:43:07.399753 containerd[1507]: time="2025-03-21T12:43:07.399703494Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-nljsc,Uid:c64c7055-9286-48f0-a60a-5db4dfe0d8a8,Namespace:kube-system,Attempt:0,} returns sandbox id \"907c3eb78f579197e1c7ee38eba4535f5fe0762cb694dd514724edcde3324892\"" Mar 21 12:43:07.401589 containerd[1507]: time="2025-03-21T12:43:07.401537794Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.3\"" Mar 21 12:43:07.419269 containerd[1507]: time="2025-03-21T12:43:07.419223320Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-hmt4j,Uid:72fdb19a-80ed-4f19-8e42-a39530bff1ae,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49\"" Mar 21 12:43:07.845611 kubelet[1785]: E0321 12:43:07.845575 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:08.609299 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1451182071.mount: Deactivated successfully. Mar 21 12:43:08.846246 kubelet[1785]: E0321 12:43:08.846200 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:08.877111 containerd[1507]: time="2025-03-21T12:43:08.877006484Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:08.877980 containerd[1507]: time="2025-03-21T12:43:08.877943141Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.3: active requests=0, bytes read=30918185" Mar 21 12:43:08.879299 containerd[1507]: time="2025-03-21T12:43:08.879253999Z" level=info msg="ImageCreate event name:\"sha256:a1ae78fd2f9d8fc345928378dc947c7f1e95f01c1a552781827071867a95d09c\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:08.881272 containerd[1507]: time="2025-03-21T12:43:08.881232179Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:5015269547a0b7dd2c062758e9a64467b58978ff2502cad4c3f5cdf4aa554ad3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:08.881653 containerd[1507]: time="2025-03-21T12:43:08.881620667Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.3\" with image id \"sha256:a1ae78fd2f9d8fc345928378dc947c7f1e95f01c1a552781827071867a95d09c\", repo tag \"registry.k8s.io/kube-proxy:v1.32.3\", repo digest \"registry.k8s.io/kube-proxy@sha256:5015269547a0b7dd2c062758e9a64467b58978ff2502cad4c3f5cdf4aa554ad3\", size \"30917204\" in 1.480031838s" Mar 21 12:43:08.881680 containerd[1507]: time="2025-03-21T12:43:08.881651906Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.3\" returns image reference \"sha256:a1ae78fd2f9d8fc345928378dc947c7f1e95f01c1a552781827071867a95d09c\"" Mar 21 12:43:08.882621 containerd[1507]: time="2025-03-21T12:43:08.882599423Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Mar 21 12:43:08.883874 containerd[1507]: time="2025-03-21T12:43:08.883829550Z" level=info msg="CreateContainer within sandbox \"907c3eb78f579197e1c7ee38eba4535f5fe0762cb694dd514724edcde3324892\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Mar 21 12:43:08.891399 containerd[1507]: time="2025-03-21T12:43:08.891364370Z" level=info msg="Container c5e995369cd023701de40bf68b2e519dd6bdafafd3e7bffffeef82f0858950fb: CDI devices from CRI Config.CDIDevices: []" Mar 21 12:43:08.900733 containerd[1507]: time="2025-03-21T12:43:08.900691712Z" level=info msg="CreateContainer within sandbox \"907c3eb78f579197e1c7ee38eba4535f5fe0762cb694dd514724edcde3324892\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"c5e995369cd023701de40bf68b2e519dd6bdafafd3e7bffffeef82f0858950fb\"" Mar 21 12:43:08.901277 containerd[1507]: time="2025-03-21T12:43:08.901246964Z" level=info msg="StartContainer for \"c5e995369cd023701de40bf68b2e519dd6bdafafd3e7bffffeef82f0858950fb\"" Mar 21 12:43:08.902625 containerd[1507]: time="2025-03-21T12:43:08.902595022Z" level=info msg="connecting to shim c5e995369cd023701de40bf68b2e519dd6bdafafd3e7bffffeef82f0858950fb" address="unix:///run/containerd/s/a62bec908841892cb6f17466e994c4b7bdaf10ccff3ab6308cd8ac2226775924" protocol=ttrpc version=3 Mar 21 12:43:08.923985 systemd[1]: Started cri-containerd-c5e995369cd023701de40bf68b2e519dd6bdafafd3e7bffffeef82f0858950fb.scope - libcontainer container c5e995369cd023701de40bf68b2e519dd6bdafafd3e7bffffeef82f0858950fb. Mar 21 12:43:08.963155 containerd[1507]: time="2025-03-21T12:43:08.963115700Z" level=info msg="StartContainer for \"c5e995369cd023701de40bf68b2e519dd6bdafafd3e7bffffeef82f0858950fb\" returns successfully" Mar 21 12:43:09.847268 kubelet[1785]: E0321 12:43:09.847232 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:10.480979 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1990130099.mount: Deactivated successfully. Mar 21 12:43:10.515891 containerd[1507]: time="2025-03-21T12:43:10.515846805Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:10.516517 containerd[1507]: time="2025-03-21T12:43:10.516456869Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Mar 21 12:43:10.517509 containerd[1507]: time="2025-03-21T12:43:10.517473285Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:10.519545 containerd[1507]: time="2025-03-21T12:43:10.519514423Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:10.520012 containerd[1507]: time="2025-03-21T12:43:10.519971079Z" 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 1.637344365s" Mar 21 12:43:10.520012 containerd[1507]: time="2025-03-21T12:43:10.520006796Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Mar 21 12:43:10.521873 containerd[1507]: time="2025-03-21T12:43:10.521835125Z" level=info msg="CreateContainer within sandbox \"c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Mar 21 12:43:10.529221 containerd[1507]: time="2025-03-21T12:43:10.529189126Z" level=info msg="Container bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0: CDI devices from CRI Config.CDIDevices: []" Mar 21 12:43:10.536534 containerd[1507]: time="2025-03-21T12:43:10.536480190Z" level=info msg="CreateContainer within sandbox \"c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0\"" Mar 21 12:43:10.539172 containerd[1507]: time="2025-03-21T12:43:10.539074224Z" level=info msg="StartContainer for \"bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0\"" Mar 21 12:43:10.540352 containerd[1507]: time="2025-03-21T12:43:10.540323748Z" level=info msg="connecting to shim bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0" address="unix:///run/containerd/s/f96ad10328be99e26da5761922fc25a3cba23ba667e377d29f9cbe2a94b64890" protocol=ttrpc version=3 Mar 21 12:43:10.560965 systemd[1]: Started cri-containerd-bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0.scope - libcontainer container bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0. Mar 21 12:43:10.587182 systemd[1]: cri-containerd-bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0.scope: Deactivated successfully. Mar 21 12:43:10.588471 containerd[1507]: time="2025-03-21T12:43:10.588402302Z" level=info msg="TaskExit event in podsandbox handler container_id:\"bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0\" id:\"bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0\" pid:2114 exited_at:{seconds:1742560990 nanos:588014946}" Mar 21 12:43:10.588585 containerd[1507]: time="2025-03-21T12:43:10.588492020Z" level=info msg="received exit event container_id:\"bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0\" id:\"bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0\" pid:2114 exited_at:{seconds:1742560990 nanos:588014946}" Mar 21 12:43:10.588614 containerd[1507]: time="2025-03-21T12:43:10.588572121Z" level=info msg="StartContainer for \"bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0\" returns successfully" Mar 21 12:43:10.848043 kubelet[1785]: E0321 12:43:10.847935 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:10.934362 kubelet[1785]: I0321 12:43:10.934311 1785 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-nljsc" podStartSLOduration=4.452941808 podStartE2EDuration="5.934297339s" podCreationTimestamp="2025-03-21 12:43:05 +0000 UTC" firstStartedPulling="2025-03-21 12:43:07.401059236 +0000 UTC m=+4.075102332" lastFinishedPulling="2025-03-21 12:43:08.882414757 +0000 UTC m=+5.556457863" observedRunningTime="2025-03-21 12:43:09.894680216 +0000 UTC m=+6.568723322" watchObservedRunningTime="2025-03-21 12:43:10.934297339 +0000 UTC m=+7.608340445" Mar 21 12:43:11.417448 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-bfeb429d8a1c961bd15f0f630b830f37ca5c63dfcbe9bf3cb8b9db7f66b05ea0-rootfs.mount: Deactivated successfully. Mar 21 12:43:11.848728 kubelet[1785]: E0321 12:43:11.848607 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:11.892098 containerd[1507]: time="2025-03-21T12:43:11.892066207Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Mar 21 12:43:12.849575 kubelet[1785]: E0321 12:43:12.849537 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:13.597456 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3933446976.mount: Deactivated successfully. Mar 21 12:43:13.849753 kubelet[1785]: E0321 12:43:13.849647 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:14.084941 containerd[1507]: time="2025-03-21T12:43:14.084890746Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:14.085591 containerd[1507]: time="2025-03-21T12:43:14.085548890Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Mar 21 12:43:14.086621 containerd[1507]: time="2025-03-21T12:43:14.086592217Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:14.088951 containerd[1507]: time="2025-03-21T12:43:14.088912588Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:14.089814 containerd[1507]: time="2025-03-21T12:43:14.089764566Z" 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 2.197662381s" Mar 21 12:43:14.089814 containerd[1507]: time="2025-03-21T12:43:14.089804521Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Mar 21 12:43:14.091655 containerd[1507]: time="2025-03-21T12:43:14.091617261Z" level=info msg="CreateContainer within sandbox \"c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Mar 21 12:43:14.099481 containerd[1507]: time="2025-03-21T12:43:14.099439099Z" level=info msg="Container 62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c: CDI devices from CRI Config.CDIDevices: []" Mar 21 12:43:14.107076 containerd[1507]: time="2025-03-21T12:43:14.106998486Z" level=info msg="CreateContainer within sandbox \"c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c\"" Mar 21 12:43:14.107531 containerd[1507]: time="2025-03-21T12:43:14.107408064Z" level=info msg="StartContainer for \"62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c\"" Mar 21 12:43:14.108295 containerd[1507]: time="2025-03-21T12:43:14.108267987Z" level=info msg="connecting to shim 62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c" address="unix:///run/containerd/s/f96ad10328be99e26da5761922fc25a3cba23ba667e377d29f9cbe2a94b64890" protocol=ttrpc version=3 Mar 21 12:43:14.126947 systemd[1]: Started cri-containerd-62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c.scope - libcontainer container 62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c. Mar 21 12:43:14.150626 systemd[1]: cri-containerd-62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c.scope: Deactivated successfully. Mar 21 12:43:14.151794 containerd[1507]: time="2025-03-21T12:43:14.151737447Z" level=info msg="TaskExit event in podsandbox handler container_id:\"62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c\" id:\"62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c\" pid:2192 exited_at:{seconds:1742560994 nanos:151384786}" Mar 21 12:43:14.152922 containerd[1507]: time="2025-03-21T12:43:14.152889097Z" level=info msg="received exit event container_id:\"62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c\" id:\"62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c\" pid:2192 exited_at:{seconds:1742560994 nanos:151384786}" Mar 21 12:43:14.154283 containerd[1507]: time="2025-03-21T12:43:14.154236935Z" level=info msg="StartContainer for \"62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c\" returns successfully" Mar 21 12:43:14.200107 kubelet[1785]: I0321 12:43:14.200064 1785 kubelet_node_status.go:502] "Fast updating node status as it just became ready" Mar 21 12:43:14.525685 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-62e682adb6188193d85516eb9399ea30213c044b290d4915fd3e8754aff88f5c-rootfs.mount: Deactivated successfully. Mar 21 12:43:14.849891 kubelet[1785]: E0321 12:43:14.849781 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:14.897429 containerd[1507]: time="2025-03-21T12:43:14.897392367Z" level=info msg="CreateContainer within sandbox \"c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Mar 21 12:43:14.905586 containerd[1507]: time="2025-03-21T12:43:14.905545216Z" level=info msg="Container fbfc34faf294d3e6daa52602c231b0411531646331d2d24086c7b30fed550bd5: CDI devices from CRI Config.CDIDevices: []" Mar 21 12:43:14.913205 containerd[1507]: time="2025-03-21T12:43:14.913174263Z" level=info msg="CreateContainer within sandbox \"c6197f4286a341e5f97e34b39730aba47ee55b28960b415dad142274d4ee1b49\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"fbfc34faf294d3e6daa52602c231b0411531646331d2d24086c7b30fed550bd5\"" Mar 21 12:43:14.913854 containerd[1507]: time="2025-03-21T12:43:14.913809485Z" level=info msg="StartContainer for \"fbfc34faf294d3e6daa52602c231b0411531646331d2d24086c7b30fed550bd5\"" Mar 21 12:43:14.914561 containerd[1507]: time="2025-03-21T12:43:14.914539824Z" level=info msg="connecting to shim fbfc34faf294d3e6daa52602c231b0411531646331d2d24086c7b30fed550bd5" address="unix:///run/containerd/s/f96ad10328be99e26da5761922fc25a3cba23ba667e377d29f9cbe2a94b64890" protocol=ttrpc version=3 Mar 21 12:43:14.932946 systemd[1]: Started cri-containerd-fbfc34faf294d3e6daa52602c231b0411531646331d2d24086c7b30fed550bd5.scope - libcontainer container fbfc34faf294d3e6daa52602c231b0411531646331d2d24086c7b30fed550bd5. Mar 21 12:43:14.960181 containerd[1507]: time="2025-03-21T12:43:14.960148366Z" level=info msg="StartContainer for \"fbfc34faf294d3e6daa52602c231b0411531646331d2d24086c7b30fed550bd5\" returns successfully" Mar 21 12:43:15.850924 kubelet[1785]: E0321 12:43:15.850861 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:15.909841 kubelet[1785]: I0321 12:43:15.909753 1785 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-hmt4j" podStartSLOduration=4.239630303 podStartE2EDuration="10.90973498s" podCreationTimestamp="2025-03-21 12:43:05 +0000 UTC" firstStartedPulling="2025-03-21 12:43:07.420364761 +0000 UTC m=+4.094407867" lastFinishedPulling="2025-03-21 12:43:14.090469438 +0000 UTC m=+10.764512544" observedRunningTime="2025-03-21 12:43:15.909661483 +0000 UTC m=+12.583704589" watchObservedRunningTime="2025-03-21 12:43:15.90973498 +0000 UTC m=+12.583778086" Mar 21 12:43:16.002327 systemd-networkd[1439]: flannel.1: Link UP Mar 21 12:43:16.002337 systemd-networkd[1439]: flannel.1: Gained carrier Mar 21 12:43:16.149181 systemd[1]: Created slice kubepods-besteffort-pode6b5bbca_9072_435d_ba84_54289a68052f.slice - libcontainer container kubepods-besteffort-pode6b5bbca_9072_435d_ba84_54289a68052f.slice. Mar 21 12:43:16.220603 kubelet[1785]: I0321 12:43:16.220523 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dt26v\" (UniqueName: \"kubernetes.io/projected/e6b5bbca-9072-435d-ba84-54289a68052f-kube-api-access-dt26v\") pod \"nginx-deployment-7fcdb87857-jnbbt\" (UID: \"e6b5bbca-9072-435d-ba84-54289a68052f\") " pod="default/nginx-deployment-7fcdb87857-jnbbt" Mar 21 12:43:16.452686 containerd[1507]: time="2025-03-21T12:43:16.452517341Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-jnbbt,Uid:e6b5bbca-9072-435d-ba84-54289a68052f,Namespace:default,Attempt:0,}" Mar 21 12:43:16.460644 systemd-networkd[1439]: cni0: Link UP Mar 21 12:43:16.460710 systemd-networkd[1439]: cni0: Gained carrier Mar 21 12:43:16.461045 systemd-networkd[1439]: cni0: Lost carrier Mar 21 12:43:16.466704 systemd-networkd[1439]: vethc0fe16de: Link UP Mar 21 12:43:16.474458 kernel: cni0: port 1(vethc0fe16de) entered blocking state Mar 21 12:43:16.474543 kernel: cni0: port 1(vethc0fe16de) entered disabled state Mar 21 12:43:16.474564 kernel: vethc0fe16de: entered allmulticast mode Mar 21 12:43:16.474580 kernel: vethc0fe16de: entered promiscuous mode Mar 21 12:43:16.474598 kernel: cni0: port 1(vethc0fe16de) entered blocking state Mar 21 12:43:16.474609 kernel: cni0: port 1(vethc0fe16de) entered forwarding state Mar 21 12:43:16.474627 kernel: cni0: port 1(vethc0fe16de) entered disabled state Mar 21 12:43:16.479735 systemd-networkd[1439]: vethc0fe16de: Gained carrier Mar 21 12:43:16.479891 kernel: cni0: port 1(vethc0fe16de) entered blocking state Mar 21 12:43:16.479919 kernel: cni0: port 1(vethc0fe16de) entered forwarding state Mar 21 12:43:16.480260 systemd-networkd[1439]: cni0: Gained carrier Mar 21 12:43:16.481620 containerd[1507]: 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)(0xc00011c8e8), "name":"cbr0", "type":"bridge"} Mar 21 12:43:16.481620 containerd[1507]: delegateAdd: netconf sent to delegate plugin: Mar 21 12:43:16.509123 containerd[1507]: {"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-03-21T12:43:16.509079686Z" level=info msg="connecting to shim a77ec14b27df721204cc4a3e49f89e9876da246f356919ab84935d550f6e8a84" address="unix:///run/containerd/s/da178cf8c2711999786473dcbe1cc334f0083ad26230064245fd02850e62da9b" namespace=k8s.io protocol=ttrpc version=3 Mar 21 12:43:16.530944 systemd[1]: Started cri-containerd-a77ec14b27df721204cc4a3e49f89e9876da246f356919ab84935d550f6e8a84.scope - libcontainer container a77ec14b27df721204cc4a3e49f89e9876da246f356919ab84935d550f6e8a84. Mar 21 12:43:16.542007 systemd-resolved[1342]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 21 12:43:16.620024 containerd[1507]: time="2025-03-21T12:43:16.619969273Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-jnbbt,Uid:e6b5bbca-9072-435d-ba84-54289a68052f,Namespace:default,Attempt:0,} returns sandbox id \"a77ec14b27df721204cc4a3e49f89e9876da246f356919ab84935d550f6e8a84\"" Mar 21 12:43:16.620947 containerd[1507]: time="2025-03-21T12:43:16.620904557Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 21 12:43:16.851352 kubelet[1785]: E0321 12:43:16.851228 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:17.356939 systemd-networkd[1439]: flannel.1: Gained IPv6LL Mar 21 12:43:17.676977 systemd-networkd[1439]: cni0: Gained IPv6LL Mar 21 12:43:17.851801 kubelet[1785]: E0321 12:43:17.851744 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:18.252989 systemd-networkd[1439]: vethc0fe16de: Gained IPv6LL Mar 21 12:43:18.852827 kubelet[1785]: E0321 12:43:18.852744 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:19.039428 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount519013474.mount: Deactivated successfully. Mar 21 12:43:19.853702 kubelet[1785]: E0321 12:43:19.853645 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:20.590469 containerd[1507]: time="2025-03-21T12:43:20.590408724Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:20.591141 containerd[1507]: time="2025-03-21T12:43:20.591076978Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73060131" Mar 21 12:43:20.592278 containerd[1507]: time="2025-03-21T12:43:20.592237143Z" level=info msg="ImageCreate event name:\"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:20.594479 containerd[1507]: time="2025-03-21T12:43:20.594448921Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:b927c62cc716b99bce51774b46a63feb63f5414c6f985fb80cacd1933bbd0e06\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:20.595220 containerd[1507]: time="2025-03-21T12:43:20.595183569Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:b927c62cc716b99bce51774b46a63feb63f5414c6f985fb80cacd1933bbd0e06\", size \"73060009\" in 3.974244698s" Mar 21 12:43:20.595220 containerd[1507]: time="2025-03-21T12:43:20.595216100Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\"" Mar 21 12:43:20.596870 containerd[1507]: time="2025-03-21T12:43:20.596845866Z" level=info msg="CreateContainer within sandbox \"a77ec14b27df721204cc4a3e49f89e9876da246f356919ab84935d550f6e8a84\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Mar 21 12:43:20.605465 containerd[1507]: time="2025-03-21T12:43:20.605430796Z" level=info msg="Container 7e0a78402eac067f948800df523d21e42d94334376f60bb2eb2f2396c78ed8a0: CDI devices from CRI Config.CDIDevices: []" Mar 21 12:43:20.608982 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3552732500.mount: Deactivated successfully. Mar 21 12:43:20.610664 containerd[1507]: time="2025-03-21T12:43:20.610629556Z" level=info msg="CreateContainer within sandbox \"a77ec14b27df721204cc4a3e49f89e9876da246f356919ab84935d550f6e8a84\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"7e0a78402eac067f948800df523d21e42d94334376f60bb2eb2f2396c78ed8a0\"" Mar 21 12:43:20.611109 containerd[1507]: time="2025-03-21T12:43:20.611083287Z" level=info msg="StartContainer for \"7e0a78402eac067f948800df523d21e42d94334376f60bb2eb2f2396c78ed8a0\"" Mar 21 12:43:20.611998 containerd[1507]: time="2025-03-21T12:43:20.611953899Z" level=info msg="connecting to shim 7e0a78402eac067f948800df523d21e42d94334376f60bb2eb2f2396c78ed8a0" address="unix:///run/containerd/s/da178cf8c2711999786473dcbe1cc334f0083ad26230064245fd02850e62da9b" protocol=ttrpc version=3 Mar 21 12:43:20.631959 systemd[1]: Started cri-containerd-7e0a78402eac067f948800df523d21e42d94334376f60bb2eb2f2396c78ed8a0.scope - libcontainer container 7e0a78402eac067f948800df523d21e42d94334376f60bb2eb2f2396c78ed8a0. Mar 21 12:43:20.659429 containerd[1507]: time="2025-03-21T12:43:20.659395639Z" level=info msg="StartContainer for \"7e0a78402eac067f948800df523d21e42d94334376f60bb2eb2f2396c78ed8a0\" returns successfully" Mar 21 12:43:20.854453 kubelet[1785]: E0321 12:43:20.854343 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:20.915917 kubelet[1785]: I0321 12:43:20.915863 1785 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-jnbbt" podStartSLOduration=0.940650895 podStartE2EDuration="4.915848049s" podCreationTimestamp="2025-03-21 12:43:16 +0000 UTC" firstStartedPulling="2025-03-21 12:43:16.620683893 +0000 UTC m=+13.294726999" lastFinishedPulling="2025-03-21 12:43:20.595881047 +0000 UTC m=+17.269924153" observedRunningTime="2025-03-21 12:43:20.915708167 +0000 UTC m=+17.589751273" watchObservedRunningTime="2025-03-21 12:43:20.915848049 +0000 UTC m=+17.589891155" Mar 21 12:43:21.855491 kubelet[1785]: E0321 12:43:21.855423 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:22.658965 systemd[1]: Created slice kubepods-besteffort-podb83b6768_ab85_41f9_b04c_38f06dfd5cb1.slice - libcontainer container kubepods-besteffort-podb83b6768_ab85_41f9_b04c_38f06dfd5cb1.slice. Mar 21 12:43:22.754199 kubelet[1785]: I0321 12:43:22.754148 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/b83b6768-ab85-41f9-b04c-38f06dfd5cb1-data\") pod \"nfs-server-provisioner-0\" (UID: \"b83b6768-ab85-41f9-b04c-38f06dfd5cb1\") " pod="default/nfs-server-provisioner-0" Mar 21 12:43:22.754199 kubelet[1785]: I0321 12:43:22.754198 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ccdnx\" (UniqueName: \"kubernetes.io/projected/b83b6768-ab85-41f9-b04c-38f06dfd5cb1-kube-api-access-ccdnx\") pod \"nfs-server-provisioner-0\" (UID: \"b83b6768-ab85-41f9-b04c-38f06dfd5cb1\") " pod="default/nfs-server-provisioner-0" Mar 21 12:43:22.855622 kubelet[1785]: E0321 12:43:22.855560 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:22.961914 containerd[1507]: time="2025-03-21T12:43:22.961772778Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b83b6768-ab85-41f9-b04c-38f06dfd5cb1,Namespace:default,Attempt:0,}" Mar 21 12:43:22.970477 systemd-networkd[1439]: vethda56e4eb: Link UP Mar 21 12:43:22.972127 kernel: cni0: port 2(vethda56e4eb) entered blocking state Mar 21 12:43:22.972160 kernel: cni0: port 2(vethda56e4eb) entered disabled state Mar 21 12:43:22.972195 kernel: vethda56e4eb: entered allmulticast mode Mar 21 12:43:22.973851 kernel: vethda56e4eb: entered promiscuous mode Mar 21 12:43:22.979088 kernel: cni0: port 2(vethda56e4eb) entered blocking state Mar 21 12:43:22.979140 kernel: cni0: port 2(vethda56e4eb) entered forwarding state Mar 21 12:43:22.979314 systemd-networkd[1439]: vethda56e4eb: Gained carrier Mar 21 12:43:22.981381 containerd[1507]: 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)(0xc0000ae8e8), "name":"cbr0", "type":"bridge"} Mar 21 12:43:22.981381 containerd[1507]: delegateAdd: netconf sent to delegate plugin: Mar 21 12:43:23.006752 containerd[1507]: {"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-03-21T12:43:23.006703069Z" level=info msg="connecting to shim 58f10a521eef79aa3fc77c44728b775f6feda517b688ba6aa54f1cd34a7ed57d" address="unix:///run/containerd/s/967e223d63ac33924c54cae110af579d8135ac0660d45a1fcaf6808e08f76e72" namespace=k8s.io protocol=ttrpc version=3 Mar 21 12:43:23.033970 systemd[1]: Started cri-containerd-58f10a521eef79aa3fc77c44728b775f6feda517b688ba6aa54f1cd34a7ed57d.scope - libcontainer container 58f10a521eef79aa3fc77c44728b775f6feda517b688ba6aa54f1cd34a7ed57d. Mar 21 12:43:23.044861 systemd-resolved[1342]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 21 12:43:23.071864 containerd[1507]: time="2025-03-21T12:43:23.071824976Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:b83b6768-ab85-41f9-b04c-38f06dfd5cb1,Namespace:default,Attempt:0,} returns sandbox id \"58f10a521eef79aa3fc77c44728b775f6feda517b688ba6aa54f1cd34a7ed57d\"" Mar 21 12:43:23.073103 containerd[1507]: time="2025-03-21T12:43:23.073070451Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Mar 21 12:43:23.844973 kubelet[1785]: E0321 12:43:23.844922 1785 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:23.856551 kubelet[1785]: E0321 12:43:23.856525 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:24.268938 systemd-networkd[1439]: vethda56e4eb: Gained IPv6LL Mar 21 12:43:24.857447 kubelet[1785]: E0321 12:43:24.857398 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:24.931900 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3861326687.mount: Deactivated successfully. Mar 21 12:43:25.857591 kubelet[1785]: E0321 12:43:25.857530 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:26.739161 containerd[1507]: time="2025-03-21T12:43:26.739103652Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:26.739962 containerd[1507]: time="2025-03-21T12:43:26.739916336Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Mar 21 12:43:26.741145 containerd[1507]: time="2025-03-21T12:43:26.741099305Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:26.743266 containerd[1507]: time="2025-03-21T12:43:26.743216515Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:26.744120 containerd[1507]: time="2025-03-21T12:43:26.744069274Z" 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 3.67095433s" Mar 21 12:43:26.744120 containerd[1507]: time="2025-03-21T12:43:26.744116473Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Mar 21 12:43:26.746006 containerd[1507]: time="2025-03-21T12:43:26.745965471Z" level=info msg="CreateContainer within sandbox \"58f10a521eef79aa3fc77c44728b775f6feda517b688ba6aa54f1cd34a7ed57d\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Mar 21 12:43:26.754078 containerd[1507]: time="2025-03-21T12:43:26.754043820Z" level=info msg="Container 90d98507ccc28813dd1bfeb4343b85fe4e8458983acc474bfd96916c7c77821e: CDI devices from CRI Config.CDIDevices: []" Mar 21 12:43:26.757879 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3105057345.mount: Deactivated successfully. Mar 21 12:43:26.760804 containerd[1507]: time="2025-03-21T12:43:26.760761027Z" level=info msg="CreateContainer within sandbox \"58f10a521eef79aa3fc77c44728b775f6feda517b688ba6aa54f1cd34a7ed57d\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"90d98507ccc28813dd1bfeb4343b85fe4e8458983acc474bfd96916c7c77821e\"" Mar 21 12:43:26.761282 containerd[1507]: time="2025-03-21T12:43:26.761223214Z" level=info msg="StartContainer for \"90d98507ccc28813dd1bfeb4343b85fe4e8458983acc474bfd96916c7c77821e\"" Mar 21 12:43:26.762227 containerd[1507]: time="2025-03-21T12:43:26.762196910Z" level=info msg="connecting to shim 90d98507ccc28813dd1bfeb4343b85fe4e8458983acc474bfd96916c7c77821e" address="unix:///run/containerd/s/967e223d63ac33924c54cae110af579d8135ac0660d45a1fcaf6808e08f76e72" protocol=ttrpc version=3 Mar 21 12:43:26.789959 systemd[1]: Started cri-containerd-90d98507ccc28813dd1bfeb4343b85fe4e8458983acc474bfd96916c7c77821e.scope - libcontainer container 90d98507ccc28813dd1bfeb4343b85fe4e8458983acc474bfd96916c7c77821e. Mar 21 12:43:26.817042 containerd[1507]: time="2025-03-21T12:43:26.817006608Z" level=info msg="StartContainer for \"90d98507ccc28813dd1bfeb4343b85fe4e8458983acc474bfd96916c7c77821e\" returns successfully" Mar 21 12:43:26.857678 kubelet[1785]: E0321 12:43:26.857648 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:26.930937 kubelet[1785]: I0321 12:43:26.930873 1785 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.258872437 podStartE2EDuration="4.930853361s" podCreationTimestamp="2025-03-21 12:43:22 +0000 UTC" firstStartedPulling="2025-03-21 12:43:23.072753797 +0000 UTC m=+19.746796904" lastFinishedPulling="2025-03-21 12:43:26.744734722 +0000 UTC m=+23.418777828" observedRunningTime="2025-03-21 12:43:26.930757341 +0000 UTC m=+23.604800447" watchObservedRunningTime="2025-03-21 12:43:26.930853361 +0000 UTC m=+23.604896467" Mar 21 12:43:27.858324 kubelet[1785]: E0321 12:43:27.858272 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:28.859024 kubelet[1785]: E0321 12:43:28.858962 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:29.859317 kubelet[1785]: E0321 12:43:29.859263 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:30.860425 kubelet[1785]: E0321 12:43:30.860370 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:31.861098 kubelet[1785]: E0321 12:43:31.861023 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:32.862190 kubelet[1785]: E0321 12:43:32.862121 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:33.862240 kubelet[1785]: E0321 12:43:33.862203 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:34.862762 kubelet[1785]: E0321 12:43:34.862706 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:35.863664 kubelet[1785]: E0321 12:43:35.863602 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:36.729398 systemd[1]: Created slice kubepods-besteffort-pod2636fabf_e295_4045_b8a6_c0ac01634788.slice - libcontainer container kubepods-besteffort-pod2636fabf_e295_4045_b8a6_c0ac01634788.slice. Mar 21 12:43:36.825319 kubelet[1785]: I0321 12:43:36.825280 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-8a447c86-8c5d-404c-914a-21d866173123\" (UniqueName: \"kubernetes.io/nfs/2636fabf-e295-4045-b8a6-c0ac01634788-pvc-8a447c86-8c5d-404c-914a-21d866173123\") pod \"test-pod-1\" (UID: \"2636fabf-e295-4045-b8a6-c0ac01634788\") " pod="default/test-pod-1" Mar 21 12:43:36.825319 kubelet[1785]: I0321 12:43:36.825314 1785 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-8szh5\" (UniqueName: \"kubernetes.io/projected/2636fabf-e295-4045-b8a6-c0ac01634788-kube-api-access-8szh5\") pod \"test-pod-1\" (UID: \"2636fabf-e295-4045-b8a6-c0ac01634788\") " pod="default/test-pod-1" Mar 21 12:43:36.864057 kubelet[1785]: E0321 12:43:36.864029 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:36.948856 kernel: FS-Cache: Loaded Mar 21 12:43:37.013362 kernel: RPC: Registered named UNIX socket transport module. Mar 21 12:43:37.013448 kernel: RPC: Registered udp transport module. Mar 21 12:43:37.013479 kernel: RPC: Registered tcp transport module. Mar 21 12:43:37.013498 kernel: RPC: Registered tcp-with-tls transport module. Mar 21 12:43:37.014190 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 21 12:43:37.219897 kernel: NFS: Registering the id_resolver key type Mar 21 12:43:37.220034 kernel: Key type id_resolver registered Mar 21 12:43:37.220072 kernel: Key type id_legacy registered Mar 21 12:43:37.243011 nfsidmap[2790]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 21 12:43:37.245217 nfsidmap[2791]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 21 12:43:37.332742 containerd[1507]: time="2025-03-21T12:43:37.332590593Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2636fabf-e295-4045-b8a6-c0ac01634788,Namespace:default,Attempt:0,}" Mar 21 12:43:37.341177 systemd-networkd[1439]: veth7882c1da: Link UP Mar 21 12:43:37.343308 kernel: cni0: port 3(veth7882c1da) entered blocking state Mar 21 12:43:37.343344 kernel: cni0: port 3(veth7882c1da) entered disabled state Mar 21 12:43:37.343362 kernel: veth7882c1da: entered allmulticast mode Mar 21 12:43:37.344040 kernel: veth7882c1da: entered promiscuous mode Mar 21 12:43:37.349004 kernel: cni0: port 3(veth7882c1da) entered blocking state Mar 21 12:43:37.349040 kernel: cni0: port 3(veth7882c1da) entered forwarding state Mar 21 12:43:37.349297 systemd-networkd[1439]: veth7882c1da: Gained carrier Mar 21 12:43:37.350897 containerd[1507]: 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)(0xc000018938), "name":"cbr0", "type":"bridge"} Mar 21 12:43:37.350897 containerd[1507]: delegateAdd: netconf sent to delegate plugin: Mar 21 12:43:37.374371 containerd[1507]: {"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-03-21T12:43:37.374266580Z" level=info msg="connecting to shim 938abd94857f2d6327f602bf585a5e6ec108e47225745bec6273b9b01d2db3fe" address="unix:///run/containerd/s/54fcf7e973b47c9b059eca406327f1f63650356724ad17282e5503df5f8dd54f" namespace=k8s.io protocol=ttrpc version=3 Mar 21 12:43:37.400957 systemd[1]: Started cri-containerd-938abd94857f2d6327f602bf585a5e6ec108e47225745bec6273b9b01d2db3fe.scope - libcontainer container 938abd94857f2d6327f602bf585a5e6ec108e47225745bec6273b9b01d2db3fe. Mar 21 12:43:37.411703 systemd-resolved[1342]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 21 12:43:37.439147 containerd[1507]: time="2025-03-21T12:43:37.439107418Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2636fabf-e295-4045-b8a6-c0ac01634788,Namespace:default,Attempt:0,} returns sandbox id \"938abd94857f2d6327f602bf585a5e6ec108e47225745bec6273b9b01d2db3fe\"" Mar 21 12:43:37.440265 containerd[1507]: time="2025-03-21T12:43:37.440229393Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 21 12:43:37.808944 containerd[1507]: time="2025-03-21T12:43:37.808899477Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 21 12:43:37.809646 containerd[1507]: time="2025-03-21T12:43:37.809571763Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Mar 21 12:43:37.812410 containerd[1507]: time="2025-03-21T12:43:37.812364066Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:b927c62cc716b99bce51774b46a63feb63f5414c6f985fb80cacd1933bbd0e06\", size \"73060009\" in 372.097889ms" Mar 21 12:43:37.812410 containerd[1507]: time="2025-03-21T12:43:37.812406700Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\"" Mar 21 12:43:37.814265 containerd[1507]: time="2025-03-21T12:43:37.814240330Z" level=info msg="CreateContainer within sandbox \"938abd94857f2d6327f602bf585a5e6ec108e47225745bec6273b9b01d2db3fe\" for container &ContainerMetadata{Name:test,Attempt:0,}" Mar 21 12:43:37.821968 containerd[1507]: time="2025-03-21T12:43:37.821933956Z" level=info msg="Container f77d8d5fa9c122d5f4384178ff31b7200305b360bf718105ef2ca58284a87bb3: CDI devices from CRI Config.CDIDevices: []" Mar 21 12:43:37.829207 containerd[1507]: time="2025-03-21T12:43:37.829176538Z" level=info msg="CreateContainer within sandbox \"938abd94857f2d6327f602bf585a5e6ec108e47225745bec6273b9b01d2db3fe\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"f77d8d5fa9c122d5f4384178ff31b7200305b360bf718105ef2ca58284a87bb3\"" Mar 21 12:43:37.829576 containerd[1507]: time="2025-03-21T12:43:37.829545638Z" level=info msg="StartContainer for \"f77d8d5fa9c122d5f4384178ff31b7200305b360bf718105ef2ca58284a87bb3\"" Mar 21 12:43:37.830379 containerd[1507]: time="2025-03-21T12:43:37.830350775Z" level=info msg="connecting to shim f77d8d5fa9c122d5f4384178ff31b7200305b360bf718105ef2ca58284a87bb3" address="unix:///run/containerd/s/54fcf7e973b47c9b059eca406327f1f63650356724ad17282e5503df5f8dd54f" protocol=ttrpc version=3 Mar 21 12:43:37.853956 systemd[1]: Started cri-containerd-f77d8d5fa9c122d5f4384178ff31b7200305b360bf718105ef2ca58284a87bb3.scope - libcontainer container f77d8d5fa9c122d5f4384178ff31b7200305b360bf718105ef2ca58284a87bb3. Mar 21 12:43:37.864589 kubelet[1785]: E0321 12:43:37.864559 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:37.881655 containerd[1507]: time="2025-03-21T12:43:37.881617337Z" level=info msg="StartContainer for \"f77d8d5fa9c122d5f4384178ff31b7200305b360bf718105ef2ca58284a87bb3\" returns successfully" Mar 21 12:43:37.942866 kubelet[1785]: I0321 12:43:37.942803 1785 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.569767759 podStartE2EDuration="15.942788389s" podCreationTimestamp="2025-03-21 12:43:22 +0000 UTC" firstStartedPulling="2025-03-21 12:43:37.439963172 +0000 UTC m=+34.114006278" lastFinishedPulling="2025-03-21 12:43:37.812983802 +0000 UTC m=+34.487026908" observedRunningTime="2025-03-21 12:43:37.942692914 +0000 UTC m=+34.616736020" watchObservedRunningTime="2025-03-21 12:43:37.942788389 +0000 UTC m=+34.616831495" Mar 21 12:43:38.733044 systemd-networkd[1439]: veth7882c1da: Gained IPv6LL Mar 21 12:43:38.865407 kubelet[1785]: E0321 12:43:38.865345 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:39.866191 kubelet[1785]: E0321 12:43:39.866129 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:40.838934 update_engine[1494]: I20250321 12:43:40.838845 1494 update_attempter.cc:509] Updating boot flags... Mar 21 12:43:40.867295 kubelet[1785]: E0321 12:43:40.867214 1785 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 21 12:43:40.874865 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2939) Mar 21 12:43:40.910955 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2937) Mar 21 12:43:40.942839 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2937)