Mar 20 18:04:40.876293 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 Thu Mar 20 13:16:44 -00 2025 Mar 20 18:04:40.876314 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=30d38910dcb9abcb2ae1fb8c4b62196472dfae1a70f494441b86ff0de2ee88c9 Mar 20 18:04:40.876325 kernel: BIOS-provided physical RAM map: Mar 20 18:04:40.876331 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 20 18:04:40.876338 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Mar 20 18:04:40.876344 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Mar 20 18:04:40.876352 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Mar 20 18:04:40.876358 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Mar 20 18:04:40.876365 kernel: BIOS-e820: [mem 0x000000000080c000-0x0000000000810fff] usable Mar 20 18:04:40.876371 kernel: BIOS-e820: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Mar 20 18:04:40.876378 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009bd3efff] usable Mar 20 18:04:40.876387 kernel: BIOS-e820: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Mar 20 18:04:40.876393 kernel: BIOS-e820: [mem 0x000000009be00000-0x000000009c8ecfff] usable Mar 20 18:04:40.876400 kernel: BIOS-e820: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Mar 20 18:04:40.876408 kernel: BIOS-e820: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Mar 20 18:04:40.876415 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Mar 20 18:04:40.876424 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009ce91fff] usable Mar 20 18:04:40.876431 kernel: BIOS-e820: [mem 0x000000009ce92000-0x000000009ce95fff] reserved Mar 20 18:04:40.876438 kernel: BIOS-e820: [mem 0x000000009ce96000-0x000000009ce97fff] ACPI NVS Mar 20 18:04:40.876445 kernel: BIOS-e820: [mem 0x000000009ce98000-0x000000009cedbfff] usable Mar 20 18:04:40.876452 kernel: BIOS-e820: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Mar 20 18:04:40.876459 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Mar 20 18:04:40.876466 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Mar 20 18:04:40.876473 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 20 18:04:40.876488 kernel: BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Mar 20 18:04:40.876495 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 20 18:04:40.876501 kernel: NX (Execute Disable) protection: active Mar 20 18:04:40.876510 kernel: APIC: Static calls initialized Mar 20 18:04:40.876517 kernel: e820: update [mem 0x9b351018-0x9b35ac57] usable ==> usable Mar 20 18:04:40.876524 kernel: e820: update [mem 0x9b351018-0x9b35ac57] usable ==> usable Mar 20 18:04:40.876531 kernel: e820: update [mem 0x9b314018-0x9b350e57] usable ==> usable Mar 20 18:04:40.876538 kernel: e820: update [mem 0x9b314018-0x9b350e57] usable ==> usable Mar 20 18:04:40.876545 kernel: extended physical RAM map: Mar 20 18:04:40.876552 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 20 18:04:40.876559 kernel: reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable Mar 20 18:04:40.876566 kernel: reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Mar 20 18:04:40.876573 kernel: reserve setup_data: [mem 0x0000000000808000-0x000000000080afff] usable Mar 20 18:04:40.876580 kernel: reserve setup_data: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Mar 20 18:04:40.876587 kernel: reserve setup_data: [mem 0x000000000080c000-0x0000000000810fff] usable Mar 20 18:04:40.876596 kernel: reserve setup_data: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Mar 20 18:04:40.876606 kernel: reserve setup_data: [mem 0x0000000000900000-0x000000009b314017] usable Mar 20 18:04:40.876613 kernel: reserve setup_data: [mem 0x000000009b314018-0x000000009b350e57] usable Mar 20 18:04:40.876620 kernel: reserve setup_data: [mem 0x000000009b350e58-0x000000009b351017] usable Mar 20 18:04:40.876628 kernel: reserve setup_data: [mem 0x000000009b351018-0x000000009b35ac57] usable Mar 20 18:04:40.876635 kernel: reserve setup_data: [mem 0x000000009b35ac58-0x000000009bd3efff] usable Mar 20 18:04:40.876644 kernel: reserve setup_data: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Mar 20 18:04:40.876652 kernel: reserve setup_data: [mem 0x000000009be00000-0x000000009c8ecfff] usable Mar 20 18:04:40.876659 kernel: reserve setup_data: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Mar 20 18:04:40.876666 kernel: reserve setup_data: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Mar 20 18:04:40.876674 kernel: reserve setup_data: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Mar 20 18:04:40.876681 kernel: reserve setup_data: [mem 0x000000009cbff000-0x000000009ce91fff] usable Mar 20 18:04:40.876688 kernel: reserve setup_data: [mem 0x000000009ce92000-0x000000009ce95fff] reserved Mar 20 18:04:40.876695 kernel: reserve setup_data: [mem 0x000000009ce96000-0x000000009ce97fff] ACPI NVS Mar 20 18:04:40.876703 kernel: reserve setup_data: [mem 0x000000009ce98000-0x000000009cedbfff] usable Mar 20 18:04:40.876710 kernel: reserve setup_data: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Mar 20 18:04:40.876719 kernel: reserve setup_data: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Mar 20 18:04:40.876726 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Mar 20 18:04:40.876734 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 20 18:04:40.876741 kernel: reserve setup_data: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Mar 20 18:04:40.876748 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 20 18:04:40.876756 kernel: efi: EFI v2.7 by EDK II Mar 20 18:04:40.876763 kernel: efi: SMBIOS=0x9c988000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9ba0d198 RNG=0x9cb73018 Mar 20 18:04:40.876770 kernel: random: crng init done Mar 20 18:04:40.876778 kernel: efi: Remove mem142: MMIO range=[0xffc00000-0xffffffff] (4MB) from e820 map Mar 20 18:04:40.876785 kernel: e820: remove [mem 0xffc00000-0xffffffff] reserved Mar 20 18:04:40.876792 kernel: secureboot: Secure boot disabled Mar 20 18:04:40.876802 kernel: SMBIOS 2.8 present. Mar 20 18:04:40.876809 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Mar 20 18:04:40.876816 kernel: Hypervisor detected: KVM Mar 20 18:04:40.876824 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Mar 20 18:04:40.876831 kernel: kvm-clock: using sched offset of 2630969251 cycles Mar 20 18:04:40.876838 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Mar 20 18:04:40.876846 kernel: tsc: Detected 2794.748 MHz processor Mar 20 18:04:40.876854 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 20 18:04:40.876862 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 20 18:04:40.876869 kernel: last_pfn = 0x9cedc max_arch_pfn = 0x400000000 Mar 20 18:04:40.876878 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Mar 20 18:04:40.876886 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 20 18:04:40.876893 kernel: Using GB pages for direct mapping Mar 20 18:04:40.876901 kernel: ACPI: Early table checksum verification disabled Mar 20 18:04:40.876908 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Mar 20 18:04:40.876916 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Mar 20 18:04:40.876924 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 18:04:40.876931 kernel: ACPI: DSDT 0x000000009CB7A000 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 18:04:40.876938 kernel: ACPI: FACS 0x000000009CBDD000 000040 Mar 20 18:04:40.876948 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 18:04:40.876956 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 18:04:40.876963 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 18:04:40.876971 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 18:04:40.876978 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Mar 20 18:04:40.876985 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Mar 20 18:04:40.876993 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1a7] Mar 20 18:04:40.877001 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Mar 20 18:04:40.877008 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Mar 20 18:04:40.877017 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Mar 20 18:04:40.877025 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Mar 20 18:04:40.877032 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Mar 20 18:04:40.877039 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Mar 20 18:04:40.877047 kernel: No NUMA configuration found Mar 20 18:04:40.877054 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cedbfff] Mar 20 18:04:40.877062 kernel: NODE_DATA(0) allocated [mem 0x9ce3a000-0x9ce3ffff] Mar 20 18:04:40.877069 kernel: Zone ranges: Mar 20 18:04:40.877077 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 20 18:04:40.877086 kernel: DMA32 [mem 0x0000000001000000-0x000000009cedbfff] Mar 20 18:04:40.877094 kernel: Normal empty Mar 20 18:04:40.877101 kernel: Movable zone start for each node Mar 20 18:04:40.877108 kernel: Early memory node ranges Mar 20 18:04:40.877116 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Mar 20 18:04:40.877123 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Mar 20 18:04:40.877130 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Mar 20 18:04:40.877138 kernel: node 0: [mem 0x000000000080c000-0x0000000000810fff] Mar 20 18:04:40.877145 kernel: node 0: [mem 0x0000000000900000-0x000000009bd3efff] Mar 20 18:04:40.877152 kernel: node 0: [mem 0x000000009be00000-0x000000009c8ecfff] Mar 20 18:04:40.877162 kernel: node 0: [mem 0x000000009cbff000-0x000000009ce91fff] Mar 20 18:04:40.877169 kernel: node 0: [mem 0x000000009ce98000-0x000000009cedbfff] Mar 20 18:04:40.877177 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cedbfff] Mar 20 18:04:40.877184 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 20 18:04:40.877204 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Mar 20 18:04:40.877220 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Mar 20 18:04:40.877230 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 20 18:04:40.877237 kernel: On node 0, zone DMA: 239 pages in unavailable ranges Mar 20 18:04:40.877245 kernel: On node 0, zone DMA32: 193 pages in unavailable ranges Mar 20 18:04:40.877253 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Mar 20 18:04:40.877260 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Mar 20 18:04:40.877268 kernel: On node 0, zone DMA32: 12580 pages in unavailable ranges Mar 20 18:04:40.877278 kernel: ACPI: PM-Timer IO Port: 0x608 Mar 20 18:04:40.877285 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Mar 20 18:04:40.877293 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Mar 20 18:04:40.877301 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Mar 20 18:04:40.877309 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Mar 20 18:04:40.877318 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 20 18:04:40.877326 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Mar 20 18:04:40.877334 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Mar 20 18:04:40.877342 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 20 18:04:40.877349 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Mar 20 18:04:40.877357 kernel: TSC deadline timer available Mar 20 18:04:40.877365 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Mar 20 18:04:40.877372 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Mar 20 18:04:40.877380 kernel: kvm-guest: KVM setup pv remote TLB flush Mar 20 18:04:40.877390 kernel: kvm-guest: setup PV sched yield Mar 20 18:04:40.877397 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Mar 20 18:04:40.877405 kernel: Booting paravirtualized kernel on KVM Mar 20 18:04:40.877413 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 20 18:04:40.877421 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Mar 20 18:04:40.877429 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288 Mar 20 18:04:40.877437 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152 Mar 20 18:04:40.877444 kernel: pcpu-alloc: [0] 0 1 2 3 Mar 20 18:04:40.877452 kernel: kvm-guest: PV spinlocks enabled Mar 20 18:04:40.877462 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 20 18:04:40.877470 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=30d38910dcb9abcb2ae1fb8c4b62196472dfae1a70f494441b86ff0de2ee88c9 Mar 20 18:04:40.877484 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 20 18:04:40.877492 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 20 18:04:40.877500 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 20 18:04:40.877508 kernel: Fallback order for Node 0: 0 Mar 20 18:04:40.877515 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629460 Mar 20 18:04:40.877524 kernel: Policy zone: DMA32 Mar 20 18:04:40.877533 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 20 18:04:40.877541 kernel: Memory: 2385672K/2565800K available (14336K kernel code, 2304K rwdata, 25060K rodata, 43592K init, 1472K bss, 179872K reserved, 0K cma-reserved) Mar 20 18:04:40.877549 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 20 18:04:40.877557 kernel: ftrace: allocating 37985 entries in 149 pages Mar 20 18:04:40.877565 kernel: ftrace: allocated 149 pages with 4 groups Mar 20 18:04:40.877572 kernel: Dynamic Preempt: voluntary Mar 20 18:04:40.877580 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 20 18:04:40.877593 kernel: rcu: RCU event tracing is enabled. Mar 20 18:04:40.877602 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 20 18:04:40.877611 kernel: Trampoline variant of Tasks RCU enabled. Mar 20 18:04:40.877619 kernel: Rude variant of Tasks RCU enabled. Mar 20 18:04:40.877627 kernel: Tracing variant of Tasks RCU enabled. Mar 20 18:04:40.877635 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 20 18:04:40.877642 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 20 18:04:40.877650 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Mar 20 18:04:40.877658 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 20 18:04:40.877665 kernel: Console: colour dummy device 80x25 Mar 20 18:04:40.877673 kernel: printk: console [ttyS0] enabled Mar 20 18:04:40.877681 kernel: ACPI: Core revision 20230628 Mar 20 18:04:40.877691 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Mar 20 18:04:40.877698 kernel: APIC: Switch to symmetric I/O mode setup Mar 20 18:04:40.877706 kernel: x2apic enabled Mar 20 18:04:40.877714 kernel: APIC: Switched APIC routing to: physical x2apic Mar 20 18:04:40.877722 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Mar 20 18:04:40.877729 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Mar 20 18:04:40.877737 kernel: kvm-guest: setup PV IPIs Mar 20 18:04:40.877745 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Mar 20 18:04:40.877752 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Mar 20 18:04:40.877762 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Mar 20 18:04:40.877770 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Mar 20 18:04:40.877778 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Mar 20 18:04:40.877786 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Mar 20 18:04:40.877793 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 20 18:04:40.877801 kernel: Spectre V2 : Mitigation: Retpolines Mar 20 18:04:40.877809 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 20 18:04:40.877817 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 20 18:04:40.877826 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Mar 20 18:04:40.877834 kernel: RETBleed: Mitigation: untrained return thunk Mar 20 18:04:40.877842 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Mar 20 18:04:40.877850 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Mar 20 18:04:40.877857 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Mar 20 18:04:40.877866 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Mar 20 18:04:40.877874 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Mar 20 18:04:40.877881 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 20 18:04:40.877889 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 20 18:04:40.877899 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 20 18:04:40.877907 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 20 18:04:40.877914 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Mar 20 18:04:40.877922 kernel: Freeing SMP alternatives memory: 32K Mar 20 18:04:40.877930 kernel: pid_max: default: 32768 minimum: 301 Mar 20 18:04:40.877938 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 20 18:04:40.877945 kernel: landlock: Up and running. Mar 20 18:04:40.877953 kernel: SELinux: Initializing. Mar 20 18:04:40.877961 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 20 18:04:40.877971 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 20 18:04:40.877978 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Mar 20 18:04:40.877986 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 20 18:04:40.877994 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 20 18:04:40.878002 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 20 18:04:40.878010 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Mar 20 18:04:40.878017 kernel: ... version: 0 Mar 20 18:04:40.878025 kernel: ... bit width: 48 Mar 20 18:04:40.878035 kernel: ... generic registers: 6 Mar 20 18:04:40.878042 kernel: ... value mask: 0000ffffffffffff Mar 20 18:04:40.878050 kernel: ... max period: 00007fffffffffff Mar 20 18:04:40.878058 kernel: ... fixed-purpose events: 0 Mar 20 18:04:40.878065 kernel: ... event mask: 000000000000003f Mar 20 18:04:40.878073 kernel: signal: max sigframe size: 1776 Mar 20 18:04:40.878080 kernel: rcu: Hierarchical SRCU implementation. Mar 20 18:04:40.878088 kernel: rcu: Max phase no-delay instances is 400. Mar 20 18:04:40.878096 kernel: smp: Bringing up secondary CPUs ... Mar 20 18:04:40.878104 kernel: smpboot: x86: Booting SMP configuration: Mar 20 18:04:40.878113 kernel: .... node #0, CPUs: #1 #2 #3 Mar 20 18:04:40.878121 kernel: smp: Brought up 1 node, 4 CPUs Mar 20 18:04:40.878128 kernel: smpboot: Max logical packages: 1 Mar 20 18:04:40.878136 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Mar 20 18:04:40.878144 kernel: devtmpfs: initialized Mar 20 18:04:40.878151 kernel: x86/mm: Memory block size: 128MB Mar 20 18:04:40.878159 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Mar 20 18:04:40.878167 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Mar 20 18:04:40.878175 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00811000-0x008fffff] (978944 bytes) Mar 20 18:04:40.878185 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Mar 20 18:04:40.878204 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9ce96000-0x9ce97fff] (8192 bytes) Mar 20 18:04:40.878212 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Mar 20 18:04:40.878219 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 20 18:04:40.878227 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 20 18:04:40.878235 kernel: pinctrl core: initialized pinctrl subsystem Mar 20 18:04:40.878243 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 20 18:04:40.878250 kernel: audit: initializing netlink subsys (disabled) Mar 20 18:04:40.878258 kernel: audit: type=2000 audit(1742493881.336:1): state=initialized audit_enabled=0 res=1 Mar 20 18:04:40.878268 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 20 18:04:40.878276 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 20 18:04:40.878284 kernel: cpuidle: using governor menu Mar 20 18:04:40.878291 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 20 18:04:40.878299 kernel: dca service started, version 1.12.1 Mar 20 18:04:40.878307 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Mar 20 18:04:40.878315 kernel: PCI: Using configuration type 1 for base access Mar 20 18:04:40.878322 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 20 18:04:40.878332 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 20 18:04:40.878340 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 20 18:04:40.878348 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 20 18:04:40.878355 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 20 18:04:40.878363 kernel: ACPI: Added _OSI(Module Device) Mar 20 18:04:40.878371 kernel: ACPI: Added _OSI(Processor Device) Mar 20 18:04:40.878378 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 20 18:04:40.878386 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 20 18:04:40.878393 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 20 18:04:40.878403 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 20 18:04:40.878411 kernel: ACPI: Interpreter enabled Mar 20 18:04:40.878418 kernel: ACPI: PM: (supports S0 S3 S5) Mar 20 18:04:40.878426 kernel: ACPI: Using IOAPIC for interrupt routing Mar 20 18:04:40.878434 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 20 18:04:40.878442 kernel: PCI: Using E820 reservations for host bridge windows Mar 20 18:04:40.878449 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Mar 20 18:04:40.878457 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 20 18:04:40.878639 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 20 18:04:40.878773 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Mar 20 18:04:40.878895 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Mar 20 18:04:40.878905 kernel: PCI host bridge to bus 0000:00 Mar 20 18:04:40.879034 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Mar 20 18:04:40.879150 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Mar 20 18:04:40.879287 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Mar 20 18:04:40.879406 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Mar 20 18:04:40.879526 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Mar 20 18:04:40.879638 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Mar 20 18:04:40.879749 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 20 18:04:40.879891 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Mar 20 18:04:40.880022 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Mar 20 18:04:40.880144 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] Mar 20 18:04:40.880286 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xc1044000-0xc1044fff] Mar 20 18:04:40.880409 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] Mar 20 18:04:40.880537 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Mar 20 18:04:40.880659 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Mar 20 18:04:40.880791 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Mar 20 18:04:40.880914 kernel: pci 0000:00:02.0: reg 0x10: [io 0x6100-0x611f] Mar 20 18:04:40.881035 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xc1043000-0xc1043fff] Mar 20 18:04:40.881162 kernel: pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] Mar 20 18:04:40.881325 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Mar 20 18:04:40.881511 kernel: pci 0000:00:03.0: reg 0x10: [io 0x6000-0x607f] Mar 20 18:04:40.881635 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff] Mar 20 18:04:40.881757 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] Mar 20 18:04:40.881887 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Mar 20 18:04:40.882015 kernel: pci 0000:00:04.0: reg 0x10: [io 0x60e0-0x60ff] Mar 20 18:04:40.882136 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff] Mar 20 18:04:40.882274 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] Mar 20 18:04:40.882398 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] Mar 20 18:04:40.882551 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Mar 20 18:04:40.882747 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Mar 20 18:04:40.882899 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Mar 20 18:04:40.883048 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x60c0-0x60df] Mar 20 18:04:40.883183 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xc1040000-0xc1040fff] Mar 20 18:04:40.883347 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Mar 20 18:04:40.883470 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6080-0x60bf] Mar 20 18:04:40.883489 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Mar 20 18:04:40.883497 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Mar 20 18:04:40.883505 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Mar 20 18:04:40.883512 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Mar 20 18:04:40.883525 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Mar 20 18:04:40.883532 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Mar 20 18:04:40.883540 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Mar 20 18:04:40.883548 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Mar 20 18:04:40.883555 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Mar 20 18:04:40.883563 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Mar 20 18:04:40.883571 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Mar 20 18:04:40.883578 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Mar 20 18:04:40.883586 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Mar 20 18:04:40.883596 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Mar 20 18:04:40.883604 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Mar 20 18:04:40.883611 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Mar 20 18:04:40.883619 kernel: iommu: Default domain type: Translated Mar 20 18:04:40.883627 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 20 18:04:40.883635 kernel: efivars: Registered efivars operations Mar 20 18:04:40.883642 kernel: PCI: Using ACPI for IRQ routing Mar 20 18:04:40.883650 kernel: PCI: pci_cache_line_size set to 64 bytes Mar 20 18:04:40.883658 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Mar 20 18:04:40.883667 kernel: e820: reserve RAM buffer [mem 0x00811000-0x008fffff] Mar 20 18:04:40.883675 kernel: e820: reserve RAM buffer [mem 0x9b314018-0x9bffffff] Mar 20 18:04:40.883683 kernel: e820: reserve RAM buffer [mem 0x9b351018-0x9bffffff] Mar 20 18:04:40.883691 kernel: e820: reserve RAM buffer [mem 0x9bd3f000-0x9bffffff] Mar 20 18:04:40.883698 kernel: e820: reserve RAM buffer [mem 0x9c8ed000-0x9fffffff] Mar 20 18:04:40.883706 kernel: e820: reserve RAM buffer [mem 0x9ce92000-0x9fffffff] Mar 20 18:04:40.883714 kernel: e820: reserve RAM buffer [mem 0x9cedc000-0x9fffffff] Mar 20 18:04:40.883837 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Mar 20 18:04:40.883961 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Mar 20 18:04:40.884123 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Mar 20 18:04:40.884134 kernel: vgaarb: loaded Mar 20 18:04:40.884142 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Mar 20 18:04:40.884150 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Mar 20 18:04:40.884158 kernel: clocksource: Switched to clocksource kvm-clock Mar 20 18:04:40.884165 kernel: VFS: Disk quotas dquot_6.6.0 Mar 20 18:04:40.884173 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 20 18:04:40.884181 kernel: pnp: PnP ACPI init Mar 20 18:04:40.884342 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Mar 20 18:04:40.884354 kernel: pnp: PnP ACPI: found 6 devices Mar 20 18:04:40.884362 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 20 18:04:40.884370 kernel: NET: Registered PF_INET protocol family Mar 20 18:04:40.884393 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 20 18:04:40.884404 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 20 18:04:40.884412 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 20 18:04:40.884420 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 20 18:04:40.884430 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 20 18:04:40.884438 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 20 18:04:40.884446 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 20 18:04:40.884454 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 20 18:04:40.884462 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 20 18:04:40.884470 kernel: NET: Registered PF_XDP protocol family Mar 20 18:04:40.884605 kernel: pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window Mar 20 18:04:40.884730 kernel: pci 0000:00:04.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref] Mar 20 18:04:40.884850 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Mar 20 18:04:40.884980 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Mar 20 18:04:40.885133 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Mar 20 18:04:40.885264 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Mar 20 18:04:40.885386 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Mar 20 18:04:40.885508 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Mar 20 18:04:40.885519 kernel: PCI: CLS 0 bytes, default 64 Mar 20 18:04:40.885528 kernel: Initialise system trusted keyrings Mar 20 18:04:40.885540 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 20 18:04:40.885548 kernel: Key type asymmetric registered Mar 20 18:04:40.885556 kernel: Asymmetric key parser 'x509' registered Mar 20 18:04:40.885564 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 20 18:04:40.885572 kernel: io scheduler mq-deadline registered Mar 20 18:04:40.885580 kernel: io scheduler kyber registered Mar 20 18:04:40.885587 kernel: io scheduler bfq registered Mar 20 18:04:40.885595 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 20 18:04:40.885604 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Mar 20 18:04:40.885612 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Mar 20 18:04:40.885622 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Mar 20 18:04:40.885630 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 20 18:04:40.885639 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 20 18:04:40.885649 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Mar 20 18:04:40.885657 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Mar 20 18:04:40.885667 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Mar 20 18:04:40.885793 kernel: rtc_cmos 00:04: RTC can wake from S4 Mar 20 18:04:40.885910 kernel: rtc_cmos 00:04: registered as rtc0 Mar 20 18:04:40.885921 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Mar 20 18:04:40.886035 kernel: rtc_cmos 00:04: setting system clock to 2025-03-20T18:04:40 UTC (1742493880) Mar 20 18:04:40.886150 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Mar 20 18:04:40.886160 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Mar 20 18:04:40.886168 kernel: efifb: probing for efifb Mar 20 18:04:40.886180 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Mar 20 18:04:40.886188 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Mar 20 18:04:40.886292 kernel: efifb: scrolling: redraw Mar 20 18:04:40.886300 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 20 18:04:40.886316 kernel: Console: switching to colour frame buffer device 160x50 Mar 20 18:04:40.886325 kernel: fb0: EFI VGA frame buffer device Mar 20 18:04:40.886333 kernel: pstore: Using crash dump compression: deflate Mar 20 18:04:40.886341 kernel: pstore: Registered efi_pstore as persistent store backend Mar 20 18:04:40.886349 kernel: NET: Registered PF_INET6 protocol family Mar 20 18:04:40.886360 kernel: Segment Routing with IPv6 Mar 20 18:04:40.886368 kernel: In-situ OAM (IOAM) with IPv6 Mar 20 18:04:40.886376 kernel: NET: Registered PF_PACKET protocol family Mar 20 18:04:40.886384 kernel: Key type dns_resolver registered Mar 20 18:04:40.886392 kernel: IPI shorthand broadcast: enabled Mar 20 18:04:40.886400 kernel: sched_clock: Marking stable (577003172, 151149701)->(777736504, -49583631) Mar 20 18:04:40.886408 kernel: registered taskstats version 1 Mar 20 18:04:40.886416 kernel: Loading compiled-in X.509 certificates Mar 20 18:04:40.886424 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: 2c0605e0441a1fddfb1f70673dce1f0d470be9b5' Mar 20 18:04:40.886434 kernel: Key type .fscrypt registered Mar 20 18:04:40.886442 kernel: Key type fscrypt-provisioning registered Mar 20 18:04:40.886450 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 20 18:04:40.886458 kernel: ima: Allocated hash algorithm: sha1 Mar 20 18:04:40.886466 kernel: ima: No architecture policies found Mar 20 18:04:40.886474 kernel: clk: Disabling unused clocks Mar 20 18:04:40.886490 kernel: Freeing unused kernel image (initmem) memory: 43592K Mar 20 18:04:40.886498 kernel: Write protecting the kernel read-only data: 40960k Mar 20 18:04:40.886506 kernel: Freeing unused kernel image (rodata/data gap) memory: 1564K Mar 20 18:04:40.886517 kernel: Run /init as init process Mar 20 18:04:40.886525 kernel: with arguments: Mar 20 18:04:40.886534 kernel: /init Mar 20 18:04:40.886548 kernel: with environment: Mar 20 18:04:40.886562 kernel: HOME=/ Mar 20 18:04:40.886577 kernel: TERM=linux Mar 20 18:04:40.886587 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 20 18:04:40.886597 systemd[1]: Successfully made /usr/ read-only. Mar 20 18:04:40.886608 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 20 18:04:40.886620 systemd[1]: Detected virtualization kvm. Mar 20 18:04:40.886628 systemd[1]: Detected architecture x86-64. Mar 20 18:04:40.886636 systemd[1]: Running in initrd. Mar 20 18:04:40.886644 systemd[1]: No hostname configured, using default hostname. Mar 20 18:04:40.886653 systemd[1]: Hostname set to . Mar 20 18:04:40.886661 systemd[1]: Initializing machine ID from VM UUID. Mar 20 18:04:40.886670 systemd[1]: Queued start job for default target initrd.target. Mar 20 18:04:40.886680 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 20 18:04:40.886689 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 20 18:04:40.886699 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 20 18:04:40.886707 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 20 18:04:40.886716 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 20 18:04:40.886726 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 20 18:04:40.886736 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 20 18:04:40.886747 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 20 18:04:40.886755 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 20 18:04:40.886764 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 20 18:04:40.886773 systemd[1]: Reached target paths.target - Path Units. Mar 20 18:04:40.886781 systemd[1]: Reached target slices.target - Slice Units. Mar 20 18:04:40.886790 systemd[1]: Reached target swap.target - Swaps. Mar 20 18:04:40.886798 systemd[1]: Reached target timers.target - Timer Units. Mar 20 18:04:40.886806 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 20 18:04:40.886818 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 20 18:04:40.886826 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 20 18:04:40.886835 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 20 18:04:40.886843 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 20 18:04:40.886852 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 20 18:04:40.886860 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 20 18:04:40.886869 systemd[1]: Reached target sockets.target - Socket Units. Mar 20 18:04:40.886877 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 20 18:04:40.886886 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 20 18:04:40.886897 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 20 18:04:40.886905 systemd[1]: Starting systemd-fsck-usr.service... Mar 20 18:04:40.886914 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 20 18:04:40.886922 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 20 18:04:40.886931 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 18:04:40.886939 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 20 18:04:40.886948 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 20 18:04:40.886959 systemd[1]: Finished systemd-fsck-usr.service. Mar 20 18:04:40.886967 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 20 18:04:40.886999 systemd-journald[192]: Collecting audit messages is disabled. Mar 20 18:04:40.887021 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 18:04:40.887030 systemd-journald[192]: Journal started Mar 20 18:04:40.887049 systemd-journald[192]: Runtime Journal (/run/log/journal/12f806842bfb4830b56b8a90f7325126) is 6M, max 48.2M, 42.2M free. Mar 20 18:04:40.882972 systemd-modules-load[193]: Inserted module 'overlay' Mar 20 18:04:40.888812 systemd[1]: Started systemd-journald.service - Journal Service. Mar 20 18:04:40.890796 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 20 18:04:40.893912 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 20 18:04:40.895671 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 20 18:04:40.898933 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 20 18:04:40.910167 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 20 18:04:40.914139 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 20 18:04:40.921773 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 20 18:04:40.923029 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 20 18:04:40.931219 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 20 18:04:40.932749 systemd-modules-load[193]: Inserted module 'br_netfilter' Mar 20 18:04:40.933662 kernel: Bridge firewalling registered Mar 20 18:04:40.937456 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 20 18:04:40.938622 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 20 18:04:40.949768 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 20 18:04:40.951473 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 20 18:04:40.955822 dracut-cmdline[224]: dracut-dracut-053 Mar 20 18:04:40.958808 dracut-cmdline[224]: 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=30d38910dcb9abcb2ae1fb8c4b62196472dfae1a70f494441b86ff0de2ee88c9 Mar 20 18:04:40.999901 systemd-resolved[238]: Positive Trust Anchors: Mar 20 18:04:40.999915 systemd-resolved[238]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 20 18:04:40.999946 systemd-resolved[238]: 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 20 18:04:41.002368 systemd-resolved[238]: Defaulting to hostname 'linux'. Mar 20 18:04:41.003519 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 20 18:04:41.009078 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 20 18:04:41.061240 kernel: SCSI subsystem initialized Mar 20 18:04:41.071223 kernel: Loading iSCSI transport class v2.0-870. Mar 20 18:04:41.082231 kernel: iscsi: registered transport (tcp) Mar 20 18:04:41.103222 kernel: iscsi: registered transport (qla4xxx) Mar 20 18:04:41.103249 kernel: QLogic iSCSI HBA Driver Mar 20 18:04:41.155979 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 20 18:04:41.158617 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 20 18:04:41.202631 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 20 18:04:41.202666 kernel: device-mapper: uevent: version 1.0.3 Mar 20 18:04:41.203704 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 20 18:04:41.245218 kernel: raid6: avx2x4 gen() 30744 MB/s Mar 20 18:04:41.262223 kernel: raid6: avx2x2 gen() 31489 MB/s Mar 20 18:04:41.279300 kernel: raid6: avx2x1 gen() 25480 MB/s Mar 20 18:04:41.279325 kernel: raid6: using algorithm avx2x2 gen() 31489 MB/s Mar 20 18:04:41.297301 kernel: raid6: .... xor() 19981 MB/s, rmw enabled Mar 20 18:04:41.297322 kernel: raid6: using avx2x2 recovery algorithm Mar 20 18:04:41.318227 kernel: xor: automatically using best checksumming function avx Mar 20 18:04:41.468240 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 20 18:04:41.483746 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 20 18:04:41.486749 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 20 18:04:41.517410 systemd-udevd[415]: Using default interface naming scheme 'v255'. Mar 20 18:04:41.522980 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 20 18:04:41.526524 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 20 18:04:41.554537 dracut-pre-trigger[420]: rd.md=0: removing MD RAID activation Mar 20 18:04:41.589720 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 20 18:04:41.592508 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 20 18:04:41.668677 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 20 18:04:41.669983 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 20 18:04:41.693509 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 20 18:04:41.696508 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 20 18:04:41.699272 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 20 18:04:41.701759 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 20 18:04:41.705232 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Mar 20 18:04:41.720118 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Mar 20 18:04:41.723633 kernel: cryptd: max_cpu_qlen set to 1000 Mar 20 18:04:41.723647 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Mar 20 18:04:41.723658 kernel: GPT:9289727 != 19775487 Mar 20 18:04:41.723669 kernel: GPT:Alternate GPT header not at the end of the disk. Mar 20 18:04:41.723679 kernel: GPT:9289727 != 19775487 Mar 20 18:04:41.723689 kernel: GPT: Use GNU Parted to correct GPT errors. Mar 20 18:04:41.723705 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 20 18:04:41.707298 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 20 18:04:41.727206 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 20 18:04:41.737074 kernel: libata version 3.00 loaded. Mar 20 18:04:41.741222 kernel: AVX2 version of gcm_enc/dec engaged. Mar 20 18:04:41.741261 kernel: AES CTR mode by8 optimization enabled Mar 20 18:04:41.747023 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 20 18:04:41.754831 kernel: ahci 0000:00:1f.2: version 3.0 Mar 20 18:04:41.777679 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Mar 20 18:04:41.777697 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Mar 20 18:04:41.777850 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Mar 20 18:04:41.777998 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by (udev-worker) (462) Mar 20 18:04:41.778010 kernel: BTRFS: device fsid 5af3bf9c-0d36-4793-88d6-028c3ca48c10 devid 1 transid 38 /dev/vda3 scanned by (udev-worker) (469) Mar 20 18:04:41.778021 kernel: scsi host0: ahci Mar 20 18:04:41.778180 kernel: scsi host1: ahci Mar 20 18:04:41.778345 kernel: scsi host2: ahci Mar 20 18:04:41.778508 kernel: scsi host3: ahci Mar 20 18:04:41.778651 kernel: scsi host4: ahci Mar 20 18:04:41.778804 kernel: scsi host5: ahci Mar 20 18:04:41.778952 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 Mar 20 18:04:41.778963 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 Mar 20 18:04:41.778974 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 Mar 20 18:04:41.778984 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 Mar 20 18:04:41.778995 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 Mar 20 18:04:41.779005 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 Mar 20 18:04:41.747319 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 20 18:04:41.753136 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 20 18:04:41.756425 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 20 18:04:41.756646 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 18:04:41.759544 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 18:04:41.762397 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 18:04:41.776954 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Mar 20 18:04:41.793108 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 18:04:41.820023 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Mar 20 18:04:41.831515 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 20 18:04:41.840994 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Mar 20 18:04:41.844191 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Mar 20 18:04:41.847390 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 20 18:04:41.850364 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 20 18:04:41.867287 disk-uuid[557]: Primary Header is updated. Mar 20 18:04:41.867287 disk-uuid[557]: Secondary Entries is updated. Mar 20 18:04:41.867287 disk-uuid[557]: Secondary Header is updated. Mar 20 18:04:41.871226 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 20 18:04:41.876613 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 20 18:04:41.879055 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 20 18:04:42.086221 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Mar 20 18:04:42.086301 kernel: ata6: SATA link down (SStatus 0 SControl 300) Mar 20 18:04:42.086328 kernel: ata5: SATA link down (SStatus 0 SControl 300) Mar 20 18:04:42.087235 kernel: ata2: SATA link down (SStatus 0 SControl 300) Mar 20 18:04:42.087321 kernel: ata1: SATA link down (SStatus 0 SControl 300) Mar 20 18:04:42.088219 kernel: ata4: SATA link down (SStatus 0 SControl 300) Mar 20 18:04:42.089222 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Mar 20 18:04:42.089234 kernel: ata3.00: applying bridge limits Mar 20 18:04:42.090322 kernel: ata3.00: configured for UDMA/100 Mar 20 18:04:42.091225 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Mar 20 18:04:42.144752 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Mar 20 18:04:42.159977 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 20 18:04:42.160000 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Mar 20 18:04:42.878238 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 20 18:04:42.878568 disk-uuid[562]: The operation has completed successfully. Mar 20 18:04:42.909425 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 20 18:04:42.909573 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 20 18:04:42.950613 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 20 18:04:42.970252 sh[593]: Success Mar 20 18:04:42.984234 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Mar 20 18:04:43.019997 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 20 18:04:43.022646 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 20 18:04:43.041083 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 20 18:04:43.047729 kernel: BTRFS info (device dm-0): first mount of filesystem 5af3bf9c-0d36-4793-88d6-028c3ca48c10 Mar 20 18:04:43.047757 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 20 18:04:43.047768 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 20 18:04:43.048750 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 20 18:04:43.049500 kernel: BTRFS info (device dm-0): using free space tree Mar 20 18:04:43.054365 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 20 18:04:43.056681 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 20 18:04:43.059339 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 20 18:04:43.061886 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 20 18:04:43.086903 kernel: BTRFS info (device vda6): first mount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 18:04:43.086953 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 20 18:04:43.086964 kernel: BTRFS info (device vda6): using free space tree Mar 20 18:04:43.090232 kernel: BTRFS info (device vda6): auto enabling async discard Mar 20 18:04:43.094218 kernel: BTRFS info (device vda6): last unmount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 18:04:43.100083 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 20 18:04:43.102156 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 20 18:04:43.159956 ignition[686]: Ignition 2.20.0 Mar 20 18:04:43.160719 ignition[686]: Stage: fetch-offline Mar 20 18:04:43.160753 ignition[686]: no configs at "/usr/lib/ignition/base.d" Mar 20 18:04:43.160762 ignition[686]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 18:04:43.160859 ignition[686]: parsed url from cmdline: "" Mar 20 18:04:43.160863 ignition[686]: no config URL provided Mar 20 18:04:43.160868 ignition[686]: reading system config file "/usr/lib/ignition/user.ign" Mar 20 18:04:43.160877 ignition[686]: no config at "/usr/lib/ignition/user.ign" Mar 20 18:04:43.160901 ignition[686]: op(1): [started] loading QEMU firmware config module Mar 20 18:04:43.160906 ignition[686]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 20 18:04:43.168945 ignition[686]: op(1): [finished] loading QEMU firmware config module Mar 20 18:04:43.168966 ignition[686]: QEMU firmware config was not found. Ignoring... Mar 20 18:04:43.171176 ignition[686]: parsing config with SHA512: 93dc7def2c28da72e65882b4454a20c90f95df6015bd330b21fa1865529f0f1e517dfcc80ad13b20f837c3615633195373c17ce5cb21b7c4baf6fc6a89f31877 Mar 20 18:04:43.173915 unknown[686]: fetched base config from "system" Mar 20 18:04:43.173925 unknown[686]: fetched user config from "qemu" Mar 20 18:04:43.174155 ignition[686]: fetch-offline: fetch-offline passed Mar 20 18:04:43.174237 ignition[686]: Ignition finished successfully Mar 20 18:04:43.177044 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 20 18:04:43.178712 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 20 18:04:43.182982 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 20 18:04:43.223573 systemd-networkd[781]: lo: Link UP Mar 20 18:04:43.223584 systemd-networkd[781]: lo: Gained carrier Mar 20 18:04:43.225346 systemd-networkd[781]: Enumeration completed Mar 20 18:04:43.225483 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 20 18:04:43.225751 systemd-networkd[781]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 20 18:04:43.225756 systemd-networkd[781]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 20 18:04:43.226403 systemd-networkd[781]: eth0: Link UP Mar 20 18:04:43.226407 systemd-networkd[781]: eth0: Gained carrier Mar 20 18:04:43.226425 systemd-networkd[781]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 20 18:04:43.226992 systemd[1]: Reached target network.target - Network. Mar 20 18:04:43.227458 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Mar 20 18:04:43.228243 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 20 18:04:43.246256 systemd-networkd[781]: eth0: DHCPv4 address 10.0.0.126/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 20 18:04:43.254606 ignition[784]: Ignition 2.20.0 Mar 20 18:04:43.254619 ignition[784]: Stage: kargs Mar 20 18:04:43.254804 ignition[784]: no configs at "/usr/lib/ignition/base.d" Mar 20 18:04:43.254818 ignition[784]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 18:04:43.255654 ignition[784]: kargs: kargs passed Mar 20 18:04:43.255705 ignition[784]: Ignition finished successfully Mar 20 18:04:43.259468 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 20 18:04:43.262438 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 20 18:04:43.286840 ignition[794]: Ignition 2.20.0 Mar 20 18:04:43.286850 ignition[794]: Stage: disks Mar 20 18:04:43.287013 ignition[794]: no configs at "/usr/lib/ignition/base.d" Mar 20 18:04:43.287024 ignition[794]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 18:04:43.287656 ignition[794]: disks: disks passed Mar 20 18:04:43.290151 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 20 18:04:43.287697 ignition[794]: Ignition finished successfully Mar 20 18:04:43.291486 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 20 18:04:43.292957 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 20 18:04:43.295044 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 20 18:04:43.296036 systemd[1]: Reached target sysinit.target - System Initialization. Mar 20 18:04:43.297736 systemd[1]: Reached target basic.target - Basic System. Mar 20 18:04:43.299018 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 20 18:04:43.326380 systemd-fsck[805]: ROOT: clean, 14/553520 files, 52654/553472 blocks Mar 20 18:04:43.333539 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 20 18:04:43.336947 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 20 18:04:43.436231 kernel: EXT4-fs (vda9): mounted filesystem bf9c440e-9fee-4e54-8539-b83f5a9eea2f r/w with ordered data mode. Quota mode: none. Mar 20 18:04:43.437355 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 20 18:04:43.440019 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 20 18:04:43.443471 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 20 18:04:43.445974 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 20 18:04:43.447952 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Mar 20 18:04:43.448001 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 20 18:04:43.448025 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 20 18:04:43.457471 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 20 18:04:43.461217 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 20 18:04:43.466866 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (813) Mar 20 18:04:43.466894 kernel: BTRFS info (device vda6): first mount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 18:04:43.466905 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 20 18:04:43.468649 kernel: BTRFS info (device vda6): using free space tree Mar 20 18:04:43.472232 kernel: BTRFS info (device vda6): auto enabling async discard Mar 20 18:04:43.473485 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 20 18:04:43.503530 initrd-setup-root[837]: cut: /sysroot/etc/passwd: No such file or directory Mar 20 18:04:43.508468 initrd-setup-root[844]: cut: /sysroot/etc/group: No such file or directory Mar 20 18:04:43.512039 initrd-setup-root[851]: cut: /sysroot/etc/shadow: No such file or directory Mar 20 18:04:43.515791 initrd-setup-root[858]: cut: /sysroot/etc/gshadow: No such file or directory Mar 20 18:04:43.601407 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 20 18:04:43.602648 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 20 18:04:43.605102 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 20 18:04:43.621219 kernel: BTRFS info (device vda6): last unmount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 18:04:43.632381 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 20 18:04:43.643569 ignition[927]: INFO : Ignition 2.20.0 Mar 20 18:04:43.643569 ignition[927]: INFO : Stage: mount Mar 20 18:04:43.645264 ignition[927]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 20 18:04:43.645264 ignition[927]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 18:04:43.647792 ignition[927]: INFO : mount: mount passed Mar 20 18:04:43.648545 ignition[927]: INFO : Ignition finished successfully Mar 20 18:04:43.651437 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 20 18:04:43.654485 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 20 18:04:44.047228 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 20 18:04:44.048904 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 20 18:04:44.070695 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/vda6 scanned by mount (939) Mar 20 18:04:44.070722 kernel: BTRFS info (device vda6): first mount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 18:04:44.070733 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 20 18:04:44.071562 kernel: BTRFS info (device vda6): using free space tree Mar 20 18:04:44.075219 kernel: BTRFS info (device vda6): auto enabling async discard Mar 20 18:04:44.076332 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 20 18:04:44.107101 ignition[956]: INFO : Ignition 2.20.0 Mar 20 18:04:44.107101 ignition[956]: INFO : Stage: files Mar 20 18:04:44.109014 ignition[956]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 20 18:04:44.109014 ignition[956]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 18:04:44.109014 ignition[956]: DEBUG : files: compiled without relabeling support, skipping Mar 20 18:04:44.109014 ignition[956]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 20 18:04:44.109014 ignition[956]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 20 18:04:44.115452 ignition[956]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 20 18:04:44.115452 ignition[956]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 20 18:04:44.115452 ignition[956]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 20 18:04:44.115452 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Mar 20 18:04:44.115452 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Mar 20 18:04:44.115452 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 20 18:04:44.115452 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 20 18:04:44.115452 ignition[956]: 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 20 18:04:44.115452 ignition[956]: 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 20 18:04:44.115452 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Mar 20 18:04:44.115452 ignition[956]: 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 20 18:04:44.112711 unknown[956]: wrote ssh authorized keys file for user: core Mar 20 18:04:44.302356 systemd-networkd[781]: eth0: Gained IPv6LL Mar 20 18:04:44.468435 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Mar 20 18:04:44.834147 ignition[956]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Mar 20 18:04:44.834147 ignition[956]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Mar 20 18:04:44.838250 ignition[956]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 20 18:04:44.838250 ignition[956]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 20 18:04:44.838250 ignition[956]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Mar 20 18:04:44.838250 ignition[956]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Mar 20 18:04:44.851148 ignition[956]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Mar 20 18:04:44.854876 ignition[956]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Mar 20 18:04:44.856647 ignition[956]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Mar 20 18:04:44.858309 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 20 18:04:44.860068 ignition[956]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 20 18:04:44.861718 ignition[956]: INFO : files: files passed Mar 20 18:04:44.862456 ignition[956]: INFO : Ignition finished successfully Mar 20 18:04:44.865657 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 20 18:04:44.868587 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 20 18:04:44.869710 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 20 18:04:44.887935 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 20 18:04:44.888047 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 20 18:04:44.891292 initrd-setup-root-after-ignition[986]: grep: /sysroot/oem/oem-release: No such file or directory Mar 20 18:04:44.892678 initrd-setup-root-after-ignition[988]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 20 18:04:44.892678 initrd-setup-root-after-ignition[988]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 20 18:04:44.895856 initrd-setup-root-after-ignition[992]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 20 18:04:44.895658 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 20 18:04:44.897314 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 20 18:04:44.900583 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 20 18:04:44.947682 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 20 18:04:44.947798 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 20 18:04:44.950065 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 20 18:04:44.952124 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 20 18:04:44.954186 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 20 18:04:44.954915 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 20 18:04:44.984545 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 20 18:04:44.988557 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 20 18:04:45.015094 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 20 18:04:45.015710 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 20 18:04:45.016056 systemd[1]: Stopped target timers.target - Timer Units. Mar 20 18:04:45.016536 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 20 18:04:45.016689 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 20 18:04:45.017369 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 20 18:04:45.017707 systemd[1]: Stopped target basic.target - Basic System. Mar 20 18:04:45.018029 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 20 18:04:45.018531 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 20 18:04:45.018849 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 20 18:04:45.019171 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 20 18:04:45.019503 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 20 18:04:45.019859 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 20 18:04:45.076589 ignition[1012]: INFO : Ignition 2.20.0 Mar 20 18:04:45.076589 ignition[1012]: INFO : Stage: umount Mar 20 18:04:45.076589 ignition[1012]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 20 18:04:45.076589 ignition[1012]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 18:04:45.076589 ignition[1012]: INFO : umount: umount passed Mar 20 18:04:45.076589 ignition[1012]: INFO : Ignition finished successfully Mar 20 18:04:45.020223 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 20 18:04:45.020547 systemd[1]: Stopped target swap.target - Swaps. Mar 20 18:04:45.020832 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 20 18:04:45.020987 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 20 18:04:45.021878 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 20 18:04:45.022251 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 20 18:04:45.022676 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 20 18:04:45.022798 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 20 18:04:45.023173 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 20 18:04:45.023338 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 20 18:04:45.023987 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 20 18:04:45.024143 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 20 18:04:45.024608 systemd[1]: Stopped target paths.target - Path Units. Mar 20 18:04:45.024986 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 20 18:04:45.030281 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 20 18:04:45.030660 systemd[1]: Stopped target slices.target - Slice Units. Mar 20 18:04:45.030976 systemd[1]: Stopped target sockets.target - Socket Units. Mar 20 18:04:45.031482 systemd[1]: iscsid.socket: Deactivated successfully. Mar 20 18:04:45.031574 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 20 18:04:45.031993 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 20 18:04:45.032077 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 20 18:04:45.032510 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 20 18:04:45.032621 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 20 18:04:45.032993 systemd[1]: ignition-files.service: Deactivated successfully. Mar 20 18:04:45.033095 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 20 18:04:45.038595 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 20 18:04:45.038934 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 20 18:04:45.039046 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 20 18:04:45.040168 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 20 18:04:45.040444 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 20 18:04:45.040548 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 20 18:04:45.040845 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 20 18:04:45.040944 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 20 18:04:45.062474 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 20 18:04:45.062581 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 20 18:04:45.078346 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 20 18:04:45.078506 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 20 18:04:45.080742 systemd[1]: Stopped target network.target - Network. Mar 20 18:04:45.082575 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 20 18:04:45.082632 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 20 18:04:45.085947 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 20 18:04:45.086028 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 20 18:04:45.087948 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 20 18:04:45.087997 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 20 18:04:45.089802 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 20 18:04:45.089850 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 20 18:04:45.092044 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 20 18:04:45.094126 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 20 18:04:45.097284 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 20 18:04:45.099483 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 20 18:04:45.099600 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 20 18:04:45.102371 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 20 18:04:45.102588 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 20 18:04:45.102700 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 20 18:04:45.122979 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 20 18:04:45.142867 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 20 18:04:45.143792 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 20 18:04:45.158258 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 20 18:04:45.160109 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 20 18:04:45.161103 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 20 18:04:45.163606 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 20 18:04:45.164521 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 20 18:04:45.166658 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 20 18:04:45.167642 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 20 18:04:45.169740 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 20 18:04:45.169792 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 20 18:04:45.173273 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 20 18:04:45.178187 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 20 18:04:45.178297 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 20 18:04:45.191423 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 20 18:04:45.191613 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 20 18:04:45.192233 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 20 18:04:45.192284 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 20 18:04:45.195145 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 20 18:04:45.195183 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 20 18:04:45.195622 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 20 18:04:45.195669 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 20 18:04:45.196431 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 20 18:04:45.196477 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 20 18:04:45.197111 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 20 18:04:45.197157 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 20 18:04:45.198680 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 20 18:04:45.207018 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 20 18:04:45.207071 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 20 18:04:45.210924 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 20 18:04:45.210974 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 18:04:45.214786 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 20 18:04:45.214849 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 20 18:04:45.215182 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 20 18:04:45.215299 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 20 18:04:45.224773 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 20 18:04:45.224896 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 20 18:04:45.261497 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 20 18:04:45.261618 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 20 18:04:45.264058 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 20 18:04:45.265492 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 20 18:04:45.265569 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 20 18:04:45.268522 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 20 18:04:45.288556 systemd[1]: Switching root. Mar 20 18:04:45.324474 systemd-journald[192]: Journal stopped Mar 20 18:04:46.724436 systemd-journald[192]: Received SIGTERM from PID 1 (systemd). Mar 20 18:04:46.724502 kernel: SELinux: policy capability network_peer_controls=1 Mar 20 18:04:46.724520 kernel: SELinux: policy capability open_perms=1 Mar 20 18:04:46.724532 kernel: SELinux: policy capability extended_socket_class=1 Mar 20 18:04:46.724544 kernel: SELinux: policy capability always_check_network=0 Mar 20 18:04:46.724555 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 20 18:04:46.724573 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 20 18:04:46.724585 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 20 18:04:46.724598 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 20 18:04:46.724610 kernel: audit: type=1403 audit(1742493885.845:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 20 18:04:46.724622 systemd[1]: Successfully loaded SELinux policy in 42.076ms. Mar 20 18:04:46.724650 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 13.238ms. Mar 20 18:04:46.724664 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 20 18:04:46.724677 systemd[1]: Detected virtualization kvm. Mar 20 18:04:46.724691 systemd[1]: Detected architecture x86-64. Mar 20 18:04:46.724703 systemd[1]: Detected first boot. Mar 20 18:04:46.724719 systemd[1]: Initializing machine ID from VM UUID. Mar 20 18:04:46.724732 zram_generator::config[1058]: No configuration found. Mar 20 18:04:46.724745 kernel: Guest personality initialized and is inactive Mar 20 18:04:46.724756 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Mar 20 18:04:46.724768 kernel: Initialized host personality Mar 20 18:04:46.724779 kernel: NET: Registered PF_VSOCK protocol family Mar 20 18:04:46.724791 systemd[1]: Populated /etc with preset unit settings. Mar 20 18:04:46.724804 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 20 18:04:46.724816 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 20 18:04:46.724831 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 20 18:04:46.724849 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 20 18:04:46.724862 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 20 18:04:46.724875 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 20 18:04:46.724888 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 20 18:04:46.724900 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 20 18:04:46.724914 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 20 18:04:46.724932 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 20 18:04:46.724952 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 20 18:04:46.724964 systemd[1]: Created slice user.slice - User and Session Slice. Mar 20 18:04:46.724977 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 20 18:04:46.724990 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 20 18:04:46.725004 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 20 18:04:46.725016 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 20 18:04:46.725029 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 20 18:04:46.725041 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 20 18:04:46.725056 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 20 18:04:46.725068 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 20 18:04:46.725081 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 20 18:04:46.725096 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 20 18:04:46.725109 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 20 18:04:46.725123 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 20 18:04:46.725135 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 20 18:04:46.725147 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 20 18:04:46.725159 systemd[1]: Reached target slices.target - Slice Units. Mar 20 18:04:46.725174 systemd[1]: Reached target swap.target - Swaps. Mar 20 18:04:46.725186 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 20 18:04:46.725267 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 20 18:04:46.725280 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 20 18:04:46.725292 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 20 18:04:46.725305 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 20 18:04:46.725324 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 20 18:04:46.725337 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 20 18:04:46.725349 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 20 18:04:46.725365 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 20 18:04:46.725378 systemd[1]: Mounting media.mount - External Media Directory... Mar 20 18:04:46.725393 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 18:04:46.725405 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 20 18:04:46.725417 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 20 18:04:46.725429 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 20 18:04:46.725443 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 20 18:04:46.725455 systemd[1]: Reached target machines.target - Containers. Mar 20 18:04:46.725470 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 20 18:04:46.725483 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 20 18:04:46.725495 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 20 18:04:46.725507 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 20 18:04:46.725519 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 20 18:04:46.725531 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 20 18:04:46.725543 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 20 18:04:46.725555 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 20 18:04:46.725567 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 20 18:04:46.725583 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 20 18:04:46.725595 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 20 18:04:46.725607 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 20 18:04:46.725620 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 20 18:04:46.725632 systemd[1]: Stopped systemd-fsck-usr.service. Mar 20 18:04:46.725645 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 20 18:04:46.725659 kernel: fuse: init (API version 7.39) Mar 20 18:04:46.725671 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 20 18:04:46.725686 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 20 18:04:46.725698 kernel: loop: module loaded Mar 20 18:04:46.725709 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 20 18:04:46.725721 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 20 18:04:46.725735 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 20 18:04:46.725747 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 20 18:04:46.725760 systemd[1]: verity-setup.service: Deactivated successfully. Mar 20 18:04:46.725772 systemd[1]: Stopped verity-setup.service. Mar 20 18:04:46.725787 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 18:04:46.725799 kernel: ACPI: bus type drm_connector registered Mar 20 18:04:46.725811 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 20 18:04:46.725823 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 20 18:04:46.725835 systemd[1]: Mounted media.mount - External Media Directory. Mar 20 18:04:46.725864 systemd-journald[1136]: Collecting audit messages is disabled. Mar 20 18:04:46.725889 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 20 18:04:46.725901 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 20 18:04:46.725914 systemd-journald[1136]: Journal started Mar 20 18:04:46.725936 systemd-journald[1136]: Runtime Journal (/run/log/journal/12f806842bfb4830b56b8a90f7325126) is 6M, max 48.2M, 42.2M free. Mar 20 18:04:46.480051 systemd[1]: Queued start job for default target multi-user.target. Mar 20 18:04:46.496500 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Mar 20 18:04:46.497003 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 20 18:04:46.729214 systemd[1]: Started systemd-journald.service - Journal Service. Mar 20 18:04:46.730275 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 20 18:04:46.731628 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 20 18:04:46.733110 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 20 18:04:46.734796 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 20 18:04:46.735015 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 20 18:04:46.736508 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 20 18:04:46.736722 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 20 18:04:46.738175 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 20 18:04:46.738418 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 20 18:04:46.739770 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 20 18:04:46.739983 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 20 18:04:46.741524 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 20 18:04:46.741740 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 20 18:04:46.743125 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 20 18:04:46.743461 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 20 18:04:46.744870 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 20 18:04:46.746332 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 20 18:04:46.747899 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 20 18:04:46.749479 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 20 18:04:46.765749 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 20 18:04:46.768599 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 20 18:04:46.771007 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 20 18:04:46.772154 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 20 18:04:46.772183 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 20 18:04:46.774240 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 20 18:04:46.784013 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 20 18:04:46.787101 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 20 18:04:46.788489 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 20 18:04:46.790112 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 20 18:04:46.792658 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 20 18:04:46.793945 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 20 18:04:46.806625 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 20 18:04:46.807817 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 20 18:04:46.809344 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 20 18:04:46.815501 systemd-journald[1136]: Time spent on flushing to /var/log/journal/12f806842bfb4830b56b8a90f7325126 is 28.887ms for 1035 entries. Mar 20 18:04:46.815501 systemd-journald[1136]: System Journal (/var/log/journal/12f806842bfb4830b56b8a90f7325126) is 8M, max 195.6M, 187.6M free. Mar 20 18:04:46.863514 systemd-journald[1136]: Received client request to flush runtime journal. Mar 20 18:04:46.863747 kernel: loop0: detected capacity change from 0 to 218376 Mar 20 18:04:46.816316 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 20 18:04:46.817861 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 20 18:04:46.821640 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 20 18:04:46.823225 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 20 18:04:46.824853 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 20 18:04:46.827616 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 20 18:04:46.832167 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 20 18:04:46.836409 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 20 18:04:46.842940 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 20 18:04:46.856391 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 20 18:04:46.859605 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 20 18:04:46.868051 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 20 18:04:46.876476 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 20 18:04:46.876996 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 20 18:04:46.881720 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 20 18:04:46.885533 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 20 18:04:46.887097 udevadm[1190]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 20 18:04:46.907226 kernel: loop1: detected capacity change from 0 to 109808 Mar 20 18:04:46.912287 systemd-tmpfiles[1198]: ACLs are not supported, ignoring. Mar 20 18:04:46.912683 systemd-tmpfiles[1198]: ACLs are not supported, ignoring. Mar 20 18:04:46.919906 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 20 18:04:46.941225 kernel: loop2: detected capacity change from 0 to 151640 Mar 20 18:04:46.975216 kernel: loop3: detected capacity change from 0 to 218376 Mar 20 18:04:46.985233 kernel: loop4: detected capacity change from 0 to 109808 Mar 20 18:04:46.996224 kernel: loop5: detected capacity change from 0 to 151640 Mar 20 18:04:47.006807 (sd-merge)[1205]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Mar 20 18:04:47.007445 (sd-merge)[1205]: Merged extensions into '/usr'. Mar 20 18:04:47.013551 systemd[1]: Reload requested from client PID 1178 ('systemd-sysext') (unit systemd-sysext.service)... Mar 20 18:04:47.013566 systemd[1]: Reloading... Mar 20 18:04:47.095223 zram_generator::config[1236]: No configuration found. Mar 20 18:04:47.134254 ldconfig[1173]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 20 18:04:47.210081 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 20 18:04:47.276981 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 20 18:04:47.277152 systemd[1]: Reloading finished in 263 ms. Mar 20 18:04:47.300470 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 20 18:04:47.302115 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 20 18:04:47.318061 systemd[1]: Starting ensure-sysext.service... Mar 20 18:04:47.320262 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 20 18:04:47.336709 systemd[1]: Reload requested from client PID 1270 ('systemctl') (unit ensure-sysext.service)... Mar 20 18:04:47.336724 systemd[1]: Reloading... Mar 20 18:04:47.345240 systemd-tmpfiles[1271]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 20 18:04:47.346052 systemd-tmpfiles[1271]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 20 18:04:47.347131 systemd-tmpfiles[1271]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 20 18:04:47.347583 systemd-tmpfiles[1271]: ACLs are not supported, ignoring. Mar 20 18:04:47.347759 systemd-tmpfiles[1271]: ACLs are not supported, ignoring. Mar 20 18:04:47.352541 systemd-tmpfiles[1271]: Detected autofs mount point /boot during canonicalization of boot. Mar 20 18:04:47.352556 systemd-tmpfiles[1271]: Skipping /boot Mar 20 18:04:47.367458 systemd-tmpfiles[1271]: Detected autofs mount point /boot during canonicalization of boot. Mar 20 18:04:47.367472 systemd-tmpfiles[1271]: Skipping /boot Mar 20 18:04:47.389738 zram_generator::config[1301]: No configuration found. Mar 20 18:04:47.511658 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 20 18:04:47.578818 systemd[1]: Reloading finished in 241 ms. Mar 20 18:04:47.591895 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 20 18:04:47.608162 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 20 18:04:47.617810 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 20 18:04:47.620259 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 20 18:04:47.622623 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 20 18:04:47.635614 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 20 18:04:47.639357 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 20 18:04:47.645291 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 20 18:04:47.649572 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 18:04:47.649784 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 20 18:04:47.651171 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 20 18:04:47.653550 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 20 18:04:47.657901 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 20 18:04:47.659122 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 20 18:04:47.659272 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 20 18:04:47.661488 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 20 18:04:47.663237 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 18:04:47.665316 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 20 18:04:47.665553 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 20 18:04:47.667849 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 20 18:04:47.668054 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 20 18:04:47.671744 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 20 18:04:47.672363 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 20 18:04:47.674493 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 20 18:04:47.683516 systemd-udevd[1344]: Using default interface naming scheme 'v255'. Mar 20 18:04:47.687475 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 18:04:47.687705 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 20 18:04:47.690581 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 20 18:04:47.697447 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 20 18:04:47.700559 augenrules[1374]: No rules Mar 20 18:04:47.701796 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 20 18:04:47.703030 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 20 18:04:47.703226 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 20 18:04:47.707518 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 20 18:04:47.709417 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 18:04:47.711641 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 20 18:04:47.713472 systemd[1]: audit-rules.service: Deactivated successfully. Mar 20 18:04:47.713730 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 20 18:04:47.715394 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 20 18:04:47.720532 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 20 18:04:47.720759 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 20 18:04:47.722440 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 20 18:04:47.724328 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 20 18:04:47.724546 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 20 18:04:47.726375 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 20 18:04:47.726592 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 20 18:04:47.730122 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 20 18:04:47.735051 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 20 18:04:47.751547 systemd[1]: Finished ensure-sysext.service. Mar 20 18:04:47.757760 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 18:04:47.759737 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 20 18:04:47.760829 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 20 18:04:47.762486 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 20 18:04:47.764644 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 20 18:04:47.776381 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 20 18:04:47.783819 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 20 18:04:47.785397 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 20 18:04:47.785446 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 20 18:04:47.794215 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1390) Mar 20 18:04:47.797375 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 20 18:04:47.802655 augenrules[1413]: /sbin/augenrules: No change Mar 20 18:04:47.803089 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Mar 20 18:04:47.804254 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 20 18:04:47.804293 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 18:04:47.804961 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 20 18:04:47.805204 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 20 18:04:47.806670 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 20 18:04:47.806913 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 20 18:04:47.814982 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 20 18:04:47.816874 augenrules[1439]: No rules Mar 20 18:04:47.820786 systemd[1]: audit-rules.service: Deactivated successfully. Mar 20 18:04:47.821687 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 20 18:04:47.826213 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 20 18:04:47.826490 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 20 18:04:47.847595 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 20 18:04:47.849966 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 20 18:04:47.853156 systemd-resolved[1342]: Positive Trust Anchors: Mar 20 18:04:47.853177 systemd-resolved[1342]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 20 18:04:47.853225 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 20 18:04:47.858579 systemd-resolved[1342]: Defaulting to hostname 'linux'. Mar 20 18:04:47.859928 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 20 18:04:47.859998 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 20 18:04:47.861366 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 20 18:04:47.862720 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 20 18:04:47.871265 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 20 18:04:47.872288 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Mar 20 18:04:47.874692 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 20 18:04:47.886472 kernel: ACPI: button: Power Button [PWRF] Mar 20 18:04:47.900111 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 20 18:04:47.906564 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Mar 20 18:04:47.908249 systemd[1]: Reached target time-set.target - System Time Set. Mar 20 18:04:47.913222 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 Mar 20 18:04:47.913283 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Mar 20 18:04:47.917207 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Mar 20 18:04:47.917556 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Mar 20 18:04:47.917747 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Mar 20 18:04:47.929289 systemd-networkd[1427]: lo: Link UP Mar 20 18:04:47.929298 systemd-networkd[1427]: lo: Gained carrier Mar 20 18:04:47.931772 systemd-networkd[1427]: Enumeration completed Mar 20 18:04:47.932113 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 20 18:04:47.932834 systemd-networkd[1427]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 20 18:04:47.933348 systemd-networkd[1427]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 20 18:04:47.933460 systemd[1]: Reached target network.target - Network. Mar 20 18:04:47.935157 systemd-networkd[1427]: eth0: Link UP Mar 20 18:04:47.935165 systemd-networkd[1427]: eth0: Gained carrier Mar 20 18:04:47.935178 systemd-networkd[1427]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 20 18:04:47.936041 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 20 18:04:47.941403 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 20 18:04:47.953386 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 18:04:47.955246 systemd-networkd[1427]: eth0: DHCPv4 address 10.0.0.126/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 20 18:04:47.957700 systemd-timesyncd[1429]: Network configuration changed, trying to establish connection. Mar 20 18:04:49.430414 systemd-resolved[1342]: Clock change detected. Flushing caches. Mar 20 18:04:49.430542 systemd-timesyncd[1429]: Contacted time server 10.0.0.1:123 (10.0.0.1). Mar 20 18:04:49.430585 systemd-timesyncd[1429]: Initial clock synchronization to Thu 2025-03-20 18:04:49.430383 UTC. Mar 20 18:04:49.444921 kernel: mousedev: PS/2 mouse device common for all mice Mar 20 18:04:49.453238 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 20 18:04:49.454956 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 20 18:04:49.455218 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 18:04:49.480265 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 20 18:04:49.488141 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 18:04:49.509234 kernel: kvm_amd: TSC scaling supported Mar 20 18:04:49.509266 kernel: kvm_amd: Nested Virtualization enabled Mar 20 18:04:49.509280 kernel: kvm_amd: Nested Paging enabled Mar 20 18:04:49.509303 kernel: kvm_amd: LBR virtualization supported Mar 20 18:04:49.510382 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Mar 20 18:04:49.510404 kernel: kvm_amd: Virtual GIF supported Mar 20 18:04:49.531940 kernel: EDAC MC: Ver: 3.0.0 Mar 20 18:04:49.560848 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 20 18:04:49.562597 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 18:04:49.566775 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 20 18:04:49.590350 lvm[1479]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 20 18:04:49.621267 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 20 18:04:49.622805 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 20 18:04:49.623945 systemd[1]: Reached target sysinit.target - System Initialization. Mar 20 18:04:49.625131 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 20 18:04:49.626395 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 20 18:04:49.627837 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 20 18:04:49.629039 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 20 18:04:49.630296 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 20 18:04:49.631527 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 20 18:04:49.631560 systemd[1]: Reached target paths.target - Path Units. Mar 20 18:04:49.632474 systemd[1]: Reached target timers.target - Timer Units. Mar 20 18:04:49.634270 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 20 18:04:49.637364 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 20 18:04:49.640901 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 20 18:04:49.642373 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 20 18:04:49.643625 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 20 18:04:49.647418 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 20 18:04:49.648844 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 20 18:04:49.651220 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 20 18:04:49.652851 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 20 18:04:49.654082 systemd[1]: Reached target sockets.target - Socket Units. Mar 20 18:04:49.655067 systemd[1]: Reached target basic.target - Basic System. Mar 20 18:04:49.655511 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 20 18:04:49.655545 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 20 18:04:49.656647 systemd[1]: Starting containerd.service - containerd container runtime... Mar 20 18:04:49.667888 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 20 18:04:49.669856 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 20 18:04:49.671598 lvm[1483]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 20 18:04:49.672094 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 20 18:04:49.673185 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 20 18:04:49.677292 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 20 18:04:49.678210 jq[1486]: false Mar 20 18:04:49.679474 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 20 18:04:49.683080 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 20 18:04:49.692173 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 20 18:04:49.694084 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 20 18:04:49.694609 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 20 18:04:49.695219 systemd[1]: Starting update-engine.service - Update Engine... Mar 20 18:04:49.699012 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 20 18:04:49.701199 extend-filesystems[1487]: Found loop3 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found loop4 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found loop5 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found sr0 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found vda Mar 20 18:04:49.706146 extend-filesystems[1487]: Found vda1 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found vda2 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found vda3 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found usr Mar 20 18:04:49.706146 extend-filesystems[1487]: Found vda4 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found vda6 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found vda7 Mar 20 18:04:49.706146 extend-filesystems[1487]: Found vda9 Mar 20 18:04:49.706146 extend-filesystems[1487]: Checking size of /dev/vda9 Mar 20 18:04:49.705758 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 20 18:04:49.709079 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 20 18:04:49.709405 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 20 18:04:49.710162 systemd[1]: motdgen.service: Deactivated successfully. Mar 20 18:04:49.710399 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 20 18:04:49.712672 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 20 18:04:49.713021 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 20 18:04:49.730631 jq[1498]: true Mar 20 18:04:49.731270 (ntainerd)[1506]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 20 18:04:49.734133 dbus-daemon[1485]: [system] SELinux support is enabled Mar 20 18:04:49.738637 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 20 18:04:49.742475 extend-filesystems[1487]: Resized partition /dev/vda9 Mar 20 18:04:49.745293 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 20 18:04:49.745323 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 20 18:04:49.750935 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1395) Mar 20 18:04:49.747045 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 20 18:04:49.747079 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 20 18:04:49.752552 extend-filesystems[1517]: resize2fs 1.47.2 (1-Jan-2025) Mar 20 18:04:49.754506 update_engine[1496]: I20250320 18:04:49.752563 1496 main.cc:92] Flatcar Update Engine starting Mar 20 18:04:49.755278 update_engine[1496]: I20250320 18:04:49.755242 1496 update_check_scheduler.cc:74] Next update check in 4m50s Mar 20 18:04:49.755427 systemd[1]: Started update-engine.service - Update Engine. Mar 20 18:04:49.756925 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Mar 20 18:04:49.760000 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 20 18:04:49.777219 systemd-logind[1492]: Watching system buttons on /dev/input/event1 (Power Button) Mar 20 18:04:49.777248 systemd-logind[1492]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 20 18:04:49.783231 jq[1514]: true Mar 20 18:04:49.784426 systemd-logind[1492]: New seat seat0. Mar 20 18:04:49.798003 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Mar 20 18:04:49.801385 systemd[1]: Started systemd-logind.service - User Login Management. Mar 20 18:04:49.824698 locksmithd[1518]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 20 18:04:49.833802 extend-filesystems[1517]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Mar 20 18:04:49.833802 extend-filesystems[1517]: old_desc_blocks = 1, new_desc_blocks = 1 Mar 20 18:04:49.833802 extend-filesystems[1517]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Mar 20 18:04:49.837289 extend-filesystems[1487]: Resized filesystem in /dev/vda9 Mar 20 18:04:49.835656 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 20 18:04:49.836050 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 20 18:04:49.842773 bash[1540]: Updated "/home/core/.ssh/authorized_keys" Mar 20 18:04:49.844628 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 20 18:04:49.846808 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 20 18:04:49.939638 containerd[1506]: time="2025-03-20T18:04:49Z" 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 20 18:04:49.940397 containerd[1506]: time="2025-03-20T18:04:49.940367456Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 20 18:04:49.948636 containerd[1506]: time="2025-03-20T18:04:49.948607359Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.823µs" Mar 20 18:04:49.948636 containerd[1506]: time="2025-03-20T18:04:49.948630282Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 20 18:04:49.948690 containerd[1506]: time="2025-03-20T18:04:49.948648767Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 20 18:04:49.948833 containerd[1506]: time="2025-03-20T18:04:49.948807535Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 20 18:04:49.948867 containerd[1506]: time="2025-03-20T18:04:49.948835417Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 20 18:04:49.948867 containerd[1506]: time="2025-03-20T18:04:49.948862067Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 20 18:04:49.948990 containerd[1506]: time="2025-03-20T18:04:49.948957265Z" 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 20 18:04:49.948990 containerd[1506]: time="2025-03-20T18:04:49.948984947Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 20 18:04:49.949276 containerd[1506]: time="2025-03-20T18:04:49.949251217Z" 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 20 18:04:49.949298 containerd[1506]: time="2025-03-20T18:04:49.949273458Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 20 18:04:49.949298 containerd[1506]: time="2025-03-20T18:04:49.949287004Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 20 18:04:49.949343 containerd[1506]: time="2025-03-20T18:04:49.949297483Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 20 18:04:49.949437 containerd[1506]: time="2025-03-20T18:04:49.949415234Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 20 18:04:49.949723 containerd[1506]: time="2025-03-20T18:04:49.949698596Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 20 18:04:49.949763 containerd[1506]: time="2025-03-20T18:04:49.949742187Z" 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 20 18:04:49.949784 containerd[1506]: time="2025-03-20T18:04:49.949759440Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 20 18:04:49.949803 containerd[1506]: time="2025-03-20T18:04:49.949794526Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 20 18:04:49.950147 containerd[1506]: time="2025-03-20T18:04:49.950117611Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 20 18:04:49.950228 containerd[1506]: time="2025-03-20T18:04:49.950210426Z" level=info msg="metadata content store policy set" policy=shared Mar 20 18:04:49.957530 containerd[1506]: time="2025-03-20T18:04:49.957488505Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 20 18:04:49.957591 containerd[1506]: time="2025-03-20T18:04:49.957551392Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 20 18:04:49.957591 containerd[1506]: time="2025-03-20T18:04:49.957584344Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 20 18:04:49.957627 containerd[1506]: time="2025-03-20T18:04:49.957600825Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 20 18:04:49.957627 containerd[1506]: time="2025-03-20T18:04:49.957614651Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 20 18:04:49.957669 containerd[1506]: time="2025-03-20T18:04:49.957631783Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 20 18:04:49.957669 containerd[1506]: time="2025-03-20T18:04:49.957646972Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 20 18:04:49.957669 containerd[1506]: time="2025-03-20T18:04:49.957665046Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 20 18:04:49.957724 containerd[1506]: time="2025-03-20T18:04:49.957681386Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 20 18:04:49.957724 containerd[1506]: time="2025-03-20T18:04:49.957695683Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 20 18:04:49.957724 containerd[1506]: time="2025-03-20T18:04:49.957709058Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 20 18:04:49.957780 containerd[1506]: time="2025-03-20T18:04:49.957724026Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 20 18:04:49.957918 containerd[1506]: time="2025-03-20T18:04:49.957880229Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 20 18:04:49.957954 containerd[1506]: time="2025-03-20T18:04:49.957927658Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 20 18:04:49.957954 containerd[1506]: time="2025-03-20T18:04:49.957946473Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 20 18:04:49.958000 containerd[1506]: time="2025-03-20T18:04:49.957960440Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 20 18:04:49.958019 containerd[1506]: time="2025-03-20T18:04:49.958009191Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 20 18:04:49.958043 containerd[1506]: time="2025-03-20T18:04:49.958025752Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 20 18:04:49.958063 containerd[1506]: time="2025-03-20T18:04:49.958041752Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 20 18:04:49.958090 containerd[1506]: time="2025-03-20T18:04:49.958055197Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 20 18:04:49.958112 containerd[1506]: time="2025-03-20T18:04:49.958097046Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 20 18:04:49.958135 containerd[1506]: time="2025-03-20T18:04:49.958114018Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 20 18:04:49.958135 containerd[1506]: time="2025-03-20T18:04:49.958127423Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 20 18:04:49.958282 containerd[1506]: time="2025-03-20T18:04:49.958256996Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 20 18:04:49.958282 containerd[1506]: time="2025-03-20T18:04:49.958277634Z" level=info msg="Start snapshots syncer" Mar 20 18:04:49.958342 containerd[1506]: time="2025-03-20T18:04:49.958326606Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 20 18:04:49.959358 containerd[1506]: time="2025-03-20T18:04:49.959251501Z" 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 20 18:04:49.959771 containerd[1506]: time="2025-03-20T18:04:49.959741880Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 20 18:04:49.960200 containerd[1506]: time="2025-03-20T18:04:49.960138414Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 20 18:04:49.960499 containerd[1506]: time="2025-03-20T18:04:49.960453996Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 20 18:04:49.960540 containerd[1506]: time="2025-03-20T18:04:49.960515371Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 20 18:04:49.960540 containerd[1506]: time="2025-03-20T18:04:49.960535609Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 20 18:04:49.960592 containerd[1506]: time="2025-03-20T18:04:49.960552380Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 20 18:04:49.960638 containerd[1506]: time="2025-03-20T18:04:49.960596343Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 20 18:04:49.960638 containerd[1506]: time="2025-03-20T18:04:49.960616090Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 20 18:04:49.960638 containerd[1506]: time="2025-03-20T18:04:49.960630597Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 20 18:04:49.960712 containerd[1506]: time="2025-03-20T18:04:49.960682825Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 20 18:04:49.960740 containerd[1506]: time="2025-03-20T18:04:49.960714294Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 20 18:04:49.960768 containerd[1506]: time="2025-03-20T18:04:49.960729983Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 20 18:04:49.960821 containerd[1506]: time="2025-03-20T18:04:49.960797520Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 20 18:04:49.960852 containerd[1506]: time="2025-03-20T18:04:49.960840240Z" 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 20 18:04:49.960889 containerd[1506]: time="2025-03-20T18:04:49.960854858Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 20 18:04:49.960889 containerd[1506]: time="2025-03-20T18:04:49.960868413Z" 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 20 18:04:49.960889 containerd[1506]: time="2025-03-20T18:04:49.960879043Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 20 18:04:49.961010 containerd[1506]: time="2025-03-20T18:04:49.960892578Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 20 18:04:49.961010 containerd[1506]: time="2025-03-20T18:04:49.960935940Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 20 18:04:49.961010 containerd[1506]: time="2025-03-20T18:04:49.960953453Z" level=info msg="runtime interface created" Mar 20 18:04:49.961010 containerd[1506]: time="2025-03-20T18:04:49.960960125Z" level=info msg="created NRI interface" Mar 20 18:04:49.961010 containerd[1506]: time="2025-03-20T18:04:49.961005640Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 20 18:04:49.961129 containerd[1506]: time="2025-03-20T18:04:49.961022913Z" level=info msg="Connect containerd service" Mar 20 18:04:49.961129 containerd[1506]: time="2025-03-20T18:04:49.961059862Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 20 18:04:49.962286 containerd[1506]: time="2025-03-20T18:04:49.962235837Z" 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 20 18:04:50.205018 containerd[1506]: time="2025-03-20T18:04:50.204979228Z" level=info msg="Start subscribing containerd event" Mar 20 18:04:50.205119 containerd[1506]: time="2025-03-20T18:04:50.205037066Z" level=info msg="Start recovering state" Mar 20 18:04:50.205151 sshd_keygen[1503]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 20 18:04:50.205517 containerd[1506]: time="2025-03-20T18:04:50.205143296Z" level=info msg="Start event monitor" Mar 20 18:04:50.205517 containerd[1506]: time="2025-03-20T18:04:50.205162221Z" level=info msg="Start cni network conf syncer for default" Mar 20 18:04:50.205517 containerd[1506]: time="2025-03-20T18:04:50.205170757Z" level=info msg="Start streaming server" Mar 20 18:04:50.205517 containerd[1506]: time="2025-03-20T18:04:50.205187809Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 20 18:04:50.205517 containerd[1506]: time="2025-03-20T18:04:50.205196405Z" level=info msg="runtime interface starting up..." Mar 20 18:04:50.205517 containerd[1506]: time="2025-03-20T18:04:50.205203519Z" level=info msg="starting plugins..." Mar 20 18:04:50.205517 containerd[1506]: time="2025-03-20T18:04:50.205220921Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 20 18:04:50.205752 containerd[1506]: time="2025-03-20T18:04:50.205586707Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 20 18:04:50.205752 containerd[1506]: time="2025-03-20T18:04:50.205706512Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 20 18:04:50.205806 containerd[1506]: time="2025-03-20T18:04:50.205783216Z" level=info msg="containerd successfully booted in 0.266945s" Mar 20 18:04:50.205971 systemd[1]: Started containerd.service - containerd container runtime. Mar 20 18:04:50.233161 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 20 18:04:50.238358 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 20 18:04:50.261861 systemd[1]: issuegen.service: Deactivated successfully. Mar 20 18:04:50.262354 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 20 18:04:50.266827 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 20 18:04:50.302017 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 20 18:04:50.305639 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 20 18:04:50.309356 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 20 18:04:50.310787 systemd[1]: Reached target getty.target - Login Prompts. Mar 20 18:04:51.086158 systemd-networkd[1427]: eth0: Gained IPv6LL Mar 20 18:04:51.089637 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 20 18:04:51.091641 systemd[1]: Reached target network-online.target - Network is Online. Mar 20 18:04:51.094383 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 20 18:04:51.096995 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 20 18:04:51.100155 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 20 18:04:51.148354 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 20 18:04:51.148693 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 20 18:04:51.151652 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 20 18:04:51.167663 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 20 18:04:52.055680 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 20 18:04:52.057435 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 20 18:04:52.058952 systemd[1]: Startup finished in 708ms (kernel) + 5.139s (initrd) + 4.782s (userspace) = 10.631s. Mar 20 18:04:52.065219 (kubelet)[1606]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Mar 20 18:04:52.464309 kubelet[1606]: E0320 18:04:52.464191 1606 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 20 18:04:52.468231 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 20 18:04:52.468430 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 20 18:04:52.468796 systemd[1]: kubelet.service: Consumed 1.227s CPU time, 255.8M memory peak. Mar 20 18:04:54.749006 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 20 18:04:54.750227 systemd[1]: Started sshd@0-10.0.0.126:22-10.0.0.1:40272.service - OpenSSH per-connection server daemon (10.0.0.1:40272). Mar 20 18:04:54.806739 sshd[1619]: Accepted publickey for core from 10.0.0.1 port 40272 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:04:54.808428 sshd-session[1619]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:04:54.818852 systemd-logind[1492]: New session 1 of user core. Mar 20 18:04:54.820136 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 20 18:04:54.821351 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 20 18:04:54.849851 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 20 18:04:54.851781 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 20 18:04:54.879339 (systemd)[1623]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 20 18:04:54.881409 systemd-logind[1492]: New session c1 of user core. Mar 20 18:04:55.022551 systemd[1623]: Queued start job for default target default.target. Mar 20 18:04:55.035154 systemd[1623]: Created slice app.slice - User Application Slice. Mar 20 18:04:55.035180 systemd[1623]: Reached target paths.target - Paths. Mar 20 18:04:55.035218 systemd[1623]: Reached target timers.target - Timers. Mar 20 18:04:55.036627 systemd[1623]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 20 18:04:55.047390 systemd[1623]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 20 18:04:55.047543 systemd[1623]: Reached target sockets.target - Sockets. Mar 20 18:04:55.047597 systemd[1623]: Reached target basic.target - Basic System. Mar 20 18:04:55.047646 systemd[1623]: Reached target default.target - Main User Target. Mar 20 18:04:55.047688 systemd[1623]: Startup finished in 159ms. Mar 20 18:04:55.047992 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 20 18:04:55.049557 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 20 18:04:55.111193 systemd[1]: Started sshd@1-10.0.0.126:22-10.0.0.1:40276.service - OpenSSH per-connection server daemon (10.0.0.1:40276). Mar 20 18:04:55.150347 sshd[1634]: Accepted publickey for core from 10.0.0.1 port 40276 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:04:55.151872 sshd-session[1634]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:04:55.155934 systemd-logind[1492]: New session 2 of user core. Mar 20 18:04:55.166019 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 20 18:04:55.219345 sshd[1636]: Connection closed by 10.0.0.1 port 40276 Mar 20 18:04:55.219698 sshd-session[1634]: pam_unix(sshd:session): session closed for user core Mar 20 18:04:55.239581 systemd[1]: sshd@1-10.0.0.126:22-10.0.0.1:40276.service: Deactivated successfully. Mar 20 18:04:55.241379 systemd[1]: session-2.scope: Deactivated successfully. Mar 20 18:04:55.242938 systemd-logind[1492]: Session 2 logged out. Waiting for processes to exit. Mar 20 18:04:55.244150 systemd[1]: Started sshd@2-10.0.0.126:22-10.0.0.1:40288.service - OpenSSH per-connection server daemon (10.0.0.1:40288). Mar 20 18:04:55.244874 systemd-logind[1492]: Removed session 2. Mar 20 18:04:55.290767 sshd[1641]: Accepted publickey for core from 10.0.0.1 port 40288 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:04:55.292048 sshd-session[1641]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:04:55.295960 systemd-logind[1492]: New session 3 of user core. Mar 20 18:04:55.306015 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 20 18:04:55.354559 sshd[1644]: Connection closed by 10.0.0.1 port 40288 Mar 20 18:04:55.354876 sshd-session[1641]: pam_unix(sshd:session): session closed for user core Mar 20 18:04:55.367635 systemd[1]: sshd@2-10.0.0.126:22-10.0.0.1:40288.service: Deactivated successfully. Mar 20 18:04:55.369400 systemd[1]: session-3.scope: Deactivated successfully. Mar 20 18:04:55.371015 systemd-logind[1492]: Session 3 logged out. Waiting for processes to exit. Mar 20 18:04:55.372332 systemd[1]: Started sshd@3-10.0.0.126:22-10.0.0.1:40296.service - OpenSSH per-connection server daemon (10.0.0.1:40296). Mar 20 18:04:55.373028 systemd-logind[1492]: Removed session 3. Mar 20 18:04:55.434384 sshd[1649]: Accepted publickey for core from 10.0.0.1 port 40296 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:04:55.435757 sshd-session[1649]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:04:55.439880 systemd-logind[1492]: New session 4 of user core. Mar 20 18:04:55.457024 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 20 18:04:55.510557 sshd[1652]: Connection closed by 10.0.0.1 port 40296 Mar 20 18:04:55.510932 sshd-session[1649]: pam_unix(sshd:session): session closed for user core Mar 20 18:04:55.522580 systemd[1]: sshd@3-10.0.0.126:22-10.0.0.1:40296.service: Deactivated successfully. Mar 20 18:04:55.524354 systemd[1]: session-4.scope: Deactivated successfully. Mar 20 18:04:55.526066 systemd-logind[1492]: Session 4 logged out. Waiting for processes to exit. Mar 20 18:04:55.527246 systemd[1]: Started sshd@4-10.0.0.126:22-10.0.0.1:40308.service - OpenSSH per-connection server daemon (10.0.0.1:40308). Mar 20 18:04:55.527957 systemd-logind[1492]: Removed session 4. Mar 20 18:04:55.571463 sshd[1657]: Accepted publickey for core from 10.0.0.1 port 40308 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:04:55.572931 sshd-session[1657]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:04:55.576945 systemd-logind[1492]: New session 5 of user core. Mar 20 18:04:55.594152 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 20 18:04:55.650797 sudo[1661]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Mar 20 18:04:55.651148 sudo[1661]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 20 18:04:55.664999 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 20 18:04:55.697603 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 20 18:04:55.697917 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 20 18:04:56.131012 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 20 18:04:56.131290 systemd[1]: kubelet.service: Consumed 1.227s CPU time, 255.8M memory peak. Mar 20 18:04:56.134050 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 20 18:04:56.158184 systemd[1]: Reload requested from client PID 1704 ('systemctl') (unit session-5.scope)... Mar 20 18:04:56.158201 systemd[1]: Reloading... Mar 20 18:04:56.252959 zram_generator::config[1747]: No configuration found. Mar 20 18:04:56.522102 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 20 18:04:56.622084 systemd[1]: Reloading finished in 463 ms. Mar 20 18:04:56.677936 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Mar 20 18:04:56.678053 systemd[1]: kubelet.service: Failed with result 'signal'. Mar 20 18:04:56.678431 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 20 18:04:56.678480 systemd[1]: kubelet.service: Consumed 169ms CPU time, 91.8M memory peak. Mar 20 18:04:56.681720 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 20 18:04:56.871045 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 20 18:04:56.881406 (kubelet)[1796]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Mar 20 18:04:56.926517 kubelet[1796]: 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 20 18:04:56.926517 kubelet[1796]: 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 20 18:04:56.926517 kubelet[1796]: 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 20 18:04:56.927019 kubelet[1796]: I0320 18:04:56.926584 1796 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 20 18:04:57.234550 kubelet[1796]: I0320 18:04:57.234443 1796 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" Mar 20 18:04:57.234550 kubelet[1796]: I0320 18:04:57.234475 1796 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Mar 20 18:04:57.234740 kubelet[1796]: I0320 18:04:57.234718 1796 server.go:954] "Client rotation is on, will bootstrap in background" Mar 20 18:04:57.257702 kubelet[1796]: I0320 18:04:57.257658 1796 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Mar 20 18:04:57.263982 kubelet[1796]: I0320 18:04:57.263948 1796 server.go:1444] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" Mar 20 18:04:57.269833 kubelet[1796]: I0320 18:04:57.269809 1796 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Mar 20 18:04:57.270093 kubelet[1796]: I0320 18:04:57.270063 1796 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Mar 20 18:04:57.270244 kubelet[1796]: I0320 18:04:57.270090 1796 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.126","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":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.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 20 18:04:57.270325 kubelet[1796]: I0320 18:04:57.270247 1796 topology_manager.go:138] "Creating topology manager with none policy" Mar 20 18:04:57.270325 kubelet[1796]: I0320 18:04:57.270256 1796 container_manager_linux.go:304] "Creating device plugin manager" Mar 20 18:04:57.270854 kubelet[1796]: I0320 18:04:57.270837 1796 state_mem.go:36] "Initialized new in-memory state store" Mar 20 18:04:57.273391 kubelet[1796]: I0320 18:04:57.273359 1796 kubelet.go:446] "Attempting to sync node with API server" Mar 20 18:04:57.273391 kubelet[1796]: I0320 18:04:57.273380 1796 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Mar 20 18:04:57.273461 kubelet[1796]: I0320 18:04:57.273406 1796 kubelet.go:352] "Adding apiserver pod source" Mar 20 18:04:57.273488 kubelet[1796]: E0320 18:04:57.273461 1796 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:04:57.273532 kubelet[1796]: E0320 18:04:57.273503 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:04:57.274808 kubelet[1796]: I0320 18:04:57.274776 1796 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Mar 20 18:04:57.277049 kubelet[1796]: I0320 18:04:57.277029 1796 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v2.0.1" apiVersion="v1" Mar 20 18:04:57.277391 kubelet[1796]: I0320 18:04:57.277378 1796 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Mar 20 18:04:57.277445 kubelet[1796]: W0320 18:04:57.277431 1796 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Mar 20 18:04:57.279936 kubelet[1796]: I0320 18:04:57.279899 1796 watchdog_linux.go:99] "Systemd watchdog is not enabled" Mar 20 18:04:57.280001 kubelet[1796]: I0320 18:04:57.279948 1796 server.go:1287] "Started kubelet" Mar 20 18:04:57.281553 kubelet[1796]: I0320 18:04:57.280959 1796 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Mar 20 18:04:57.281553 kubelet[1796]: I0320 18:04:57.281283 1796 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Mar 20 18:04:57.281553 kubelet[1796]: I0320 18:04:57.281335 1796 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Mar 20 18:04:57.282229 kubelet[1796]: I0320 18:04:57.282061 1796 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Mar 20 18:04:57.282229 kubelet[1796]: I0320 18:04:57.282162 1796 server.go:490] "Adding debug handlers to kubelet server" Mar 20 18:04:57.285873 kubelet[1796]: I0320 18:04:57.284637 1796 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 20 18:04:57.285873 kubelet[1796]: E0320 18:04:57.284934 1796 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.126\" not found" Mar 20 18:04:57.285873 kubelet[1796]: I0320 18:04:57.284959 1796 volume_manager.go:297] "Starting Kubelet Volume Manager" Mar 20 18:04:57.285873 kubelet[1796]: I0320 18:04:57.285181 1796 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Mar 20 18:04:57.285873 kubelet[1796]: I0320 18:04:57.285252 1796 reconciler.go:26] "Reconciler: start to sync state" Mar 20 18:04:57.287111 kubelet[1796]: E0320 18:04:57.287061 1796 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Mar 20 18:04:57.287359 kubelet[1796]: I0320 18:04:57.287323 1796 factory.go:221] Registration of the systemd container factory successfully Mar 20 18:04:57.287359 kubelet[1796]: I0320 18:04:57.287424 1796 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 20 18:04:57.288665 kubelet[1796]: I0320 18:04:57.288635 1796 factory.go:221] Registration of the containerd container factory successfully Mar 20 18:04:57.299892 kubelet[1796]: E0320 18:04:57.299478 1796 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.126\" not found" node="10.0.0.126" Mar 20 18:04:57.300282 kubelet[1796]: I0320 18:04:57.300251 1796 cpu_manager.go:221] "Starting CPU manager" policy="none" Mar 20 18:04:57.300282 kubelet[1796]: I0320 18:04:57.300268 1796 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Mar 20 18:04:57.300354 kubelet[1796]: I0320 18:04:57.300285 1796 state_mem.go:36] "Initialized new in-memory state store" Mar 20 18:04:57.385163 kubelet[1796]: E0320 18:04:57.385123 1796 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.126\" not found" Mar 20 18:04:57.485610 kubelet[1796]: E0320 18:04:57.485484 1796 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.126\" not found" Mar 20 18:04:57.586611 kubelet[1796]: E0320 18:04:57.586562 1796 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.126\" not found" Mar 20 18:04:57.687044 kubelet[1796]: E0320 18:04:57.686993 1796 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.126\" not found" Mar 20 18:04:57.734385 kubelet[1796]: I0320 18:04:57.734352 1796 policy_none.go:49] "None policy: Start" Mar 20 18:04:57.734385 kubelet[1796]: I0320 18:04:57.734374 1796 memory_manager.go:186] "Starting memorymanager" policy="None" Mar 20 18:04:57.734385 kubelet[1796]: I0320 18:04:57.734387 1796 state_mem.go:35] "Initializing new in-memory state store" Mar 20 18:04:57.742881 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Mar 20 18:04:57.753488 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Mar 20 18:04:57.757021 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Mar 20 18:04:57.763349 kubelet[1796]: I0320 18:04:57.763305 1796 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Mar 20 18:04:57.764617 kubelet[1796]: I0320 18:04:57.764580 1796 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Mar 20 18:04:57.764655 kubelet[1796]: I0320 18:04:57.764622 1796 status_manager.go:227] "Starting to sync pod status with apiserver" Mar 20 18:04:57.764655 kubelet[1796]: I0320 18:04:57.764647 1796 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Mar 20 18:04:57.764655 kubelet[1796]: I0320 18:04:57.764655 1796 kubelet.go:2388] "Starting kubelet main sync loop" Mar 20 18:04:57.764750 kubelet[1796]: E0320 18:04:57.764718 1796 kubelet.go:2412] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" Mar 20 18:04:57.766009 kubelet[1796]: I0320 18:04:57.765977 1796 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Mar 20 18:04:57.766240 kubelet[1796]: I0320 18:04:57.766218 1796 eviction_manager.go:189] "Eviction manager: starting control loop" Mar 20 18:04:57.766285 kubelet[1796]: I0320 18:04:57.766238 1796 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Mar 20 18:04:57.766940 kubelet[1796]: I0320 18:04:57.766884 1796 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Mar 20 18:04:57.768298 kubelet[1796]: E0320 18:04:57.768249 1796 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Mar 20 18:04:57.768298 kubelet[1796]: E0320 18:04:57.768299 1796 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.126\" not found" Mar 20 18:04:57.808072 kubelet[1796]: E0320 18:04:57.808041 1796 csi_plugin.go:308] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "10.0.0.126" not found Mar 20 18:04:57.867216 kubelet[1796]: I0320 18:04:57.867158 1796 kubelet_node_status.go:76] "Attempting to register node" node="10.0.0.126" Mar 20 18:04:57.871208 kubelet[1796]: I0320 18:04:57.871168 1796 kubelet_node_status.go:79] "Successfully registered node" node="10.0.0.126" Mar 20 18:04:57.871208 kubelet[1796]: E0320 18:04:57.871196 1796 kubelet_node_status.go:549] "Error updating node status, will retry" err="error getting node \"10.0.0.126\": node \"10.0.0.126\" not found" Mar 20 18:04:57.976614 kubelet[1796]: I0320 18:04:57.976579 1796 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Mar 20 18:04:57.977126 kubelet[1796]: I0320 18:04:57.977045 1796 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Mar 20 18:04:57.977159 containerd[1506]: time="2025-03-20T18:04:57.976844459Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Mar 20 18:04:58.237290 kubelet[1796]: I0320 18:04:58.237157 1796 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Mar 20 18:04:58.237411 kubelet[1796]: W0320 18:04:58.237362 1796 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Mar 20 18:04:58.237411 kubelet[1796]: W0320 18:04:58.237392 1796 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Mar 20 18:04:58.237411 kubelet[1796]: W0320 18:04:58.237412 1796 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Mar 20 18:04:58.246680 sudo[1661]: pam_unix(sudo:session): session closed for user root Mar 20 18:04:58.248074 sshd[1660]: Connection closed by 10.0.0.1 port 40308 Mar 20 18:04:58.248317 sshd-session[1657]: pam_unix(sshd:session): session closed for user core Mar 20 18:04:58.251811 systemd[1]: sshd@4-10.0.0.126:22-10.0.0.1:40308.service: Deactivated successfully. Mar 20 18:04:58.253852 systemd[1]: session-5.scope: Deactivated successfully. Mar 20 18:04:58.254075 systemd[1]: session-5.scope: Consumed 490ms CPU time, 78.7M memory peak. Mar 20 18:04:58.255224 systemd-logind[1492]: Session 5 logged out. Waiting for processes to exit. Mar 20 18:04:58.256211 systemd-logind[1492]: Removed session 5. Mar 20 18:04:58.274237 kubelet[1796]: E0320 18:04:58.274193 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:04:58.275328 kubelet[1796]: I0320 18:04:58.275289 1796 apiserver.go:52] "Watching apiserver" Mar 20 18:04:58.285442 kubelet[1796]: I0320 18:04:58.285413 1796 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Mar 20 18:04:58.285584 systemd[1]: Created slice kubepods-burstable-poddcf4e280_69b2_43d3_81f2_e640d675a8c6.slice - libcontainer container kubepods-burstable-poddcf4e280_69b2_43d3_81f2_e640d675a8c6.slice. Mar 20 18:04:58.290095 kubelet[1796]: I0320 18:04:58.290055 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/dcf4e280-69b2-43d3-81f2-e640d675a8c6-run\") pod \"kube-flannel-ds-74wvs\" (UID: \"dcf4e280-69b2-43d3-81f2-e640d675a8c6\") " pod="kube-flannel/kube-flannel-ds-74wvs" Mar 20 18:04:58.290095 kubelet[1796]: I0320 18:04:58.290087 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/dcf4e280-69b2-43d3-81f2-e640d675a8c6-flannel-cfg\") pod \"kube-flannel-ds-74wvs\" (UID: \"dcf4e280-69b2-43d3-81f2-e640d675a8c6\") " pod="kube-flannel/kube-flannel-ds-74wvs" Mar 20 18:04:58.290237 kubelet[1796]: I0320 18:04:58.290106 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/dcf4e280-69b2-43d3-81f2-e640d675a8c6-xtables-lock\") pod \"kube-flannel-ds-74wvs\" (UID: \"dcf4e280-69b2-43d3-81f2-e640d675a8c6\") " pod="kube-flannel/kube-flannel-ds-74wvs" Mar 20 18:04:58.290237 kubelet[1796]: I0320 18:04:58.290124 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vs8v9\" (UniqueName: \"kubernetes.io/projected/dcf4e280-69b2-43d3-81f2-e640d675a8c6-kube-api-access-vs8v9\") pod \"kube-flannel-ds-74wvs\" (UID: \"dcf4e280-69b2-43d3-81f2-e640d675a8c6\") " pod="kube-flannel/kube-flannel-ds-74wvs" Mar 20 18:04:58.290237 kubelet[1796]: I0320 18:04:58.290140 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/dd6ad9dc-db72-4872-8f3d-e42da31e8f52-kube-proxy\") pod \"kube-proxy-5vp8q\" (UID: \"dd6ad9dc-db72-4872-8f3d-e42da31e8f52\") " pod="kube-system/kube-proxy-5vp8q" Mar 20 18:04:58.290237 kubelet[1796]: I0320 18:04:58.290156 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/dd6ad9dc-db72-4872-8f3d-e42da31e8f52-lib-modules\") pod \"kube-proxy-5vp8q\" (UID: \"dd6ad9dc-db72-4872-8f3d-e42da31e8f52\") " pod="kube-system/kube-proxy-5vp8q" Mar 20 18:04:58.290237 kubelet[1796]: I0320 18:04:58.290197 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-gcx7s\" (UniqueName: \"kubernetes.io/projected/dd6ad9dc-db72-4872-8f3d-e42da31e8f52-kube-api-access-gcx7s\") pod \"kube-proxy-5vp8q\" (UID: \"dd6ad9dc-db72-4872-8f3d-e42da31e8f52\") " pod="kube-system/kube-proxy-5vp8q" Mar 20 18:04:58.290343 kubelet[1796]: I0320 18:04:58.290244 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/dcf4e280-69b2-43d3-81f2-e640d675a8c6-cni-plugin\") pod \"kube-flannel-ds-74wvs\" (UID: \"dcf4e280-69b2-43d3-81f2-e640d675a8c6\") " pod="kube-flannel/kube-flannel-ds-74wvs" Mar 20 18:04:58.290343 kubelet[1796]: I0320 18:04:58.290280 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/dcf4e280-69b2-43d3-81f2-e640d675a8c6-cni\") pod \"kube-flannel-ds-74wvs\" (UID: \"dcf4e280-69b2-43d3-81f2-e640d675a8c6\") " pod="kube-flannel/kube-flannel-ds-74wvs" Mar 20 18:04:58.290343 kubelet[1796]: I0320 18:04:58.290333 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/dd6ad9dc-db72-4872-8f3d-e42da31e8f52-xtables-lock\") pod \"kube-proxy-5vp8q\" (UID: \"dd6ad9dc-db72-4872-8f3d-e42da31e8f52\") " pod="kube-system/kube-proxy-5vp8q" Mar 20 18:04:58.307816 systemd[1]: Created slice kubepods-besteffort-poddd6ad9dc_db72_4872_8f3d_e42da31e8f52.slice - libcontainer container kubepods-besteffort-poddd6ad9dc_db72_4872_8f3d_e42da31e8f52.slice. Mar 20 18:04:58.606183 containerd[1506]: time="2025-03-20T18:04:58.606061656Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-74wvs,Uid:dcf4e280-69b2-43d3-81f2-e640d675a8c6,Namespace:kube-flannel,Attempt:0,}" Mar 20 18:04:58.616479 containerd[1506]: time="2025-03-20T18:04:58.616374146Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5vp8q,Uid:dd6ad9dc-db72-4872-8f3d-e42da31e8f52,Namespace:kube-system,Attempt:0,}" Mar 20 18:04:59.167576 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1778876669.mount: Deactivated successfully. Mar 20 18:04:59.176088 containerd[1506]: time="2025-03-20T18:04:59.176039608Z" 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 20 18:04:59.177759 containerd[1506]: time="2025-03-20T18:04:59.177686888Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321138" Mar 20 18:04:59.178739 containerd[1506]: time="2025-03-20T18:04:59.178688656Z" 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 20 18:04:59.179611 containerd[1506]: time="2025-03-20T18:04:59.179571282Z" 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 20 18:04:59.180435 containerd[1506]: time="2025-03-20T18:04:59.180390388Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Mar 20 18:04:59.181840 containerd[1506]: time="2025-03-20T18:04:59.181810250Z" 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 20 18:04:59.182517 containerd[1506]: time="2025-03-20T18:04:59.182478724Z" 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 566.342224ms" Mar 20 18:04:59.183645 containerd[1506]: time="2025-03-20T18:04:59.183581562Z" 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 561.257949ms" Mar 20 18:04:59.210173 containerd[1506]: time="2025-03-20T18:04:59.210116745Z" level=info msg="connecting to shim 1a6c01135a4b0264914163be2a45093dd66a686934e62e564b6518a26eba4b9f" address="unix:///run/containerd/s/5c52149eff3680918c3883fe948da83bad9fe326d19b3526876bdd1952041eb3" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:04:59.210285 containerd[1506]: time="2025-03-20T18:04:59.210259413Z" level=info msg="connecting to shim 40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628" address="unix:///run/containerd/s/9578dab716bcfa2bd26bc2ac08544a0e040a56a8fcd2416b515cc64967bc277c" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:04:59.236033 systemd[1]: Started cri-containerd-1a6c01135a4b0264914163be2a45093dd66a686934e62e564b6518a26eba4b9f.scope - libcontainer container 1a6c01135a4b0264914163be2a45093dd66a686934e62e564b6518a26eba4b9f. Mar 20 18:04:59.237818 systemd[1]: Started cri-containerd-40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628.scope - libcontainer container 40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628. Mar 20 18:04:59.264893 containerd[1506]: time="2025-03-20T18:04:59.264784928Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-5vp8q,Uid:dd6ad9dc-db72-4872-8f3d-e42da31e8f52,Namespace:kube-system,Attempt:0,} returns sandbox id \"1a6c01135a4b0264914163be2a45093dd66a686934e62e564b6518a26eba4b9f\"" Mar 20 18:04:59.267185 containerd[1506]: time="2025-03-20T18:04:59.266993890Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.3\"" Mar 20 18:04:59.275077 kubelet[1796]: E0320 18:04:59.275040 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:04:59.279782 containerd[1506]: time="2025-03-20T18:04:59.279734353Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-74wvs,Uid:dcf4e280-69b2-43d3-81f2-e640d675a8c6,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628\"" Mar 20 18:05:00.195572 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2923034575.mount: Deactivated successfully. Mar 20 18:05:00.276146 kubelet[1796]: E0320 18:05:00.276111 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:00.761015 containerd[1506]: time="2025-03-20T18:05:00.760967133Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:00.761792 containerd[1506]: time="2025-03-20T18:05:00.761749621Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.3: active requests=0, bytes read=30918185" Mar 20 18:05:00.763039 containerd[1506]: time="2025-03-20T18:05:00.763008271Z" level=info msg="ImageCreate event name:\"sha256:a1ae78fd2f9d8fc345928378dc947c7f1e95f01c1a552781827071867a95d09c\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:00.764786 containerd[1506]: time="2025-03-20T18:05:00.764741602Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:5015269547a0b7dd2c062758e9a64467b58978ff2502cad4c3f5cdf4aa554ad3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:00.765232 containerd[1506]: time="2025-03-20T18:05:00.765193549Z" 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.498164763s" Mar 20 18:05:00.765232 containerd[1506]: time="2025-03-20T18:05:00.765229837Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.3\" returns image reference \"sha256:a1ae78fd2f9d8fc345928378dc947c7f1e95f01c1a552781827071867a95d09c\"" Mar 20 18:05:00.766124 containerd[1506]: time="2025-03-20T18:05:00.766102153Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Mar 20 18:05:00.768110 containerd[1506]: time="2025-03-20T18:05:00.767974635Z" level=info msg="CreateContainer within sandbox \"1a6c01135a4b0264914163be2a45093dd66a686934e62e564b6518a26eba4b9f\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Mar 20 18:05:00.780501 containerd[1506]: time="2025-03-20T18:05:00.780449950Z" level=info msg="Container 4b5ab054196168ed93757f7e652df1c226f2bffbe02a19665481ddfcdce8bcd2: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:05:00.789841 containerd[1506]: time="2025-03-20T18:05:00.789795146Z" level=info msg="CreateContainer within sandbox \"1a6c01135a4b0264914163be2a45093dd66a686934e62e564b6518a26eba4b9f\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"4b5ab054196168ed93757f7e652df1c226f2bffbe02a19665481ddfcdce8bcd2\"" Mar 20 18:05:00.790447 containerd[1506]: time="2025-03-20T18:05:00.790407855Z" level=info msg="StartContainer for \"4b5ab054196168ed93757f7e652df1c226f2bffbe02a19665481ddfcdce8bcd2\"" Mar 20 18:05:00.791798 containerd[1506]: time="2025-03-20T18:05:00.791773085Z" level=info msg="connecting to shim 4b5ab054196168ed93757f7e652df1c226f2bffbe02a19665481ddfcdce8bcd2" address="unix:///run/containerd/s/5c52149eff3680918c3883fe948da83bad9fe326d19b3526876bdd1952041eb3" protocol=ttrpc version=3 Mar 20 18:05:00.815046 systemd[1]: Started cri-containerd-4b5ab054196168ed93757f7e652df1c226f2bffbe02a19665481ddfcdce8bcd2.scope - libcontainer container 4b5ab054196168ed93757f7e652df1c226f2bffbe02a19665481ddfcdce8bcd2. Mar 20 18:05:00.864558 containerd[1506]: time="2025-03-20T18:05:00.864510354Z" level=info msg="StartContainer for \"4b5ab054196168ed93757f7e652df1c226f2bffbe02a19665481ddfcdce8bcd2\" returns successfully" Mar 20 18:05:01.277134 kubelet[1796]: E0320 18:05:01.277101 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:01.783482 kubelet[1796]: I0320 18:05:01.783423 1796 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-5vp8q" podStartSLOduration=3.283936932 podStartE2EDuration="4.783405871s" podCreationTimestamp="2025-03-20 18:04:57 +0000 UTC" firstStartedPulling="2025-03-20 18:04:59.266454579 +0000 UTC m=+2.380622548" lastFinishedPulling="2025-03-20 18:05:00.765923518 +0000 UTC m=+3.880091487" observedRunningTime="2025-03-20 18:05:01.78297867 +0000 UTC m=+4.897146639" watchObservedRunningTime="2025-03-20 18:05:01.783405871 +0000 UTC m=+4.897573840" Mar 20 18:05:02.278413 kubelet[1796]: E0320 18:05:02.278270 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:02.467324 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount970147751.mount: Deactivated successfully. Mar 20 18:05:02.508579 containerd[1506]: time="2025-03-20T18:05:02.508536960Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:02.509205 containerd[1506]: time="2025-03-20T18:05:02.509162684Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Mar 20 18:05:02.510720 containerd[1506]: time="2025-03-20T18:05:02.510658259Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:02.512481 containerd[1506]: time="2025-03-20T18:05:02.512439720Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:02.513104 containerd[1506]: time="2025-03-20T18:05:02.513064090Z" 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.746935538s" Mar 20 18:05:02.513104 containerd[1506]: time="2025-03-20T18:05:02.513094768Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Mar 20 18:05:02.514980 containerd[1506]: time="2025-03-20T18:05:02.514927325Z" level=info msg="CreateContainer within sandbox \"40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Mar 20 18:05:02.523225 containerd[1506]: time="2025-03-20T18:05:02.523175563Z" level=info msg="Container c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:05:02.530102 containerd[1506]: time="2025-03-20T18:05:02.530010381Z" level=info msg="CreateContainer within sandbox \"40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0\"" Mar 20 18:05:02.530515 containerd[1506]: time="2025-03-20T18:05:02.530465945Z" level=info msg="StartContainer for \"c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0\"" Mar 20 18:05:02.531310 containerd[1506]: time="2025-03-20T18:05:02.531283679Z" level=info msg="connecting to shim c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0" address="unix:///run/containerd/s/9578dab716bcfa2bd26bc2ac08544a0e040a56a8fcd2416b515cc64967bc277c" protocol=ttrpc version=3 Mar 20 18:05:02.555087 systemd[1]: Started cri-containerd-c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0.scope - libcontainer container c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0. Mar 20 18:05:02.581314 systemd[1]: cri-containerd-c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0.scope: Deactivated successfully. Mar 20 18:05:02.584089 containerd[1506]: time="2025-03-20T18:05:02.583990573Z" level=info msg="TaskExit event in podsandbox handler container_id:\"c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0\" id:\"c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0\" pid:2129 exited_at:{seconds:1742493902 nanos:582161613}" Mar 20 18:05:02.584089 containerd[1506]: time="2025-03-20T18:05:02.584032482Z" level=info msg="StartContainer for \"c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0\" returns successfully" Mar 20 18:05:02.584089 containerd[1506]: time="2025-03-20T18:05:02.584061676Z" level=info msg="received exit event container_id:\"c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0\" id:\"c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0\" pid:2129 exited_at:{seconds:1742493902 nanos:582161613}" Mar 20 18:05:02.779026 containerd[1506]: time="2025-03-20T18:05:02.778934052Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Mar 20 18:05:03.279443 kubelet[1796]: E0320 18:05:03.279391 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:03.399802 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-c6fd9eb792a2a98df5705197466048aa5a7e363d3905a40d71b15a408877f8c0-rootfs.mount: Deactivated successfully. Mar 20 18:05:04.279986 kubelet[1796]: E0320 18:05:04.279938 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:04.507209 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1670458251.mount: Deactivated successfully. Mar 20 18:05:05.188620 containerd[1506]: time="2025-03-20T18:05:05.188551194Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:05.189202 containerd[1506]: time="2025-03-20T18:05:05.189129568Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Mar 20 18:05:05.190444 containerd[1506]: time="2025-03-20T18:05:05.190401684Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:05.192960 containerd[1506]: time="2025-03-20T18:05:05.192918033Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:05.193733 containerd[1506]: time="2025-03-20T18:05:05.193692065Z" 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.414721124s" Mar 20 18:05:05.193733 containerd[1506]: time="2025-03-20T18:05:05.193729816Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Mar 20 18:05:05.195927 containerd[1506]: time="2025-03-20T18:05:05.195881150Z" level=info msg="CreateContainer within sandbox \"40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Mar 20 18:05:05.204121 containerd[1506]: time="2025-03-20T18:05:05.204089133Z" level=info msg="Container a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:05:05.215053 containerd[1506]: time="2025-03-20T18:05:05.215009513Z" level=info msg="CreateContainer within sandbox \"40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451\"" Mar 20 18:05:05.215599 containerd[1506]: time="2025-03-20T18:05:05.215568020Z" level=info msg="StartContainer for \"a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451\"" Mar 20 18:05:05.216484 containerd[1506]: time="2025-03-20T18:05:05.216443141Z" level=info msg="connecting to shim a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451" address="unix:///run/containerd/s/9578dab716bcfa2bd26bc2ac08544a0e040a56a8fcd2416b515cc64967bc277c" protocol=ttrpc version=3 Mar 20 18:05:05.257066 systemd[1]: Started cri-containerd-a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451.scope - libcontainer container a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451. Mar 20 18:05:05.280419 kubelet[1796]: E0320 18:05:05.280241 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:05.292087 systemd[1]: cri-containerd-a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451.scope: Deactivated successfully. Mar 20 18:05:05.292571 containerd[1506]: time="2025-03-20T18:05:05.292515183Z" level=info msg="TaskExit event in podsandbox handler container_id:\"a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451\" id:\"a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451\" pid:2204 exited_at:{seconds:1742493905 nanos:292302725}" Mar 20 18:05:05.295254 containerd[1506]: time="2025-03-20T18:05:05.295209717Z" level=info msg="received exit event container_id:\"a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451\" id:\"a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451\" pid:2204 exited_at:{seconds:1742493905 nanos:292302725}" Mar 20 18:05:05.296340 containerd[1506]: time="2025-03-20T18:05:05.296314819Z" level=info msg="StartContainer for \"a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451\" returns successfully" Mar 20 18:05:05.302902 kubelet[1796]: I0320 18:05:05.302879 1796 kubelet_node_status.go:502] "Fast updating node status as it just became ready" Mar 20 18:05:05.424756 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-a083a61620e4b3358c5c4849c6f1b2477790f15b8dbf75af14e52f2596e85451-rootfs.mount: Deactivated successfully. Mar 20 18:05:05.786201 containerd[1506]: time="2025-03-20T18:05:05.786150706Z" level=info msg="CreateContainer within sandbox \"40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Mar 20 18:05:05.795326 containerd[1506]: time="2025-03-20T18:05:05.795272413Z" level=info msg="Container 4cba9f508d85986c60a8ccf7f61648811e038d103cbea3ef884972a7477a357f: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:05:05.802818 containerd[1506]: time="2025-03-20T18:05:05.802779681Z" level=info msg="CreateContainer within sandbox \"40fe030144c2b43bef157b4cf6cd96846c399a52457aa6862626755231e20628\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"4cba9f508d85986c60a8ccf7f61648811e038d103cbea3ef884972a7477a357f\"" Mar 20 18:05:05.803280 containerd[1506]: time="2025-03-20T18:05:05.803238282Z" level=info msg="StartContainer for \"4cba9f508d85986c60a8ccf7f61648811e038d103cbea3ef884972a7477a357f\"" Mar 20 18:05:05.804168 containerd[1506]: time="2025-03-20T18:05:05.804137247Z" level=info msg="connecting to shim 4cba9f508d85986c60a8ccf7f61648811e038d103cbea3ef884972a7477a357f" address="unix:///run/containerd/s/9578dab716bcfa2bd26bc2ac08544a0e040a56a8fcd2416b515cc64967bc277c" protocol=ttrpc version=3 Mar 20 18:05:05.829043 systemd[1]: Started cri-containerd-4cba9f508d85986c60a8ccf7f61648811e038d103cbea3ef884972a7477a357f.scope - libcontainer container 4cba9f508d85986c60a8ccf7f61648811e038d103cbea3ef884972a7477a357f. Mar 20 18:05:05.859626 containerd[1506]: time="2025-03-20T18:05:05.859570895Z" level=info msg="StartContainer for \"4cba9f508d85986c60a8ccf7f61648811e038d103cbea3ef884972a7477a357f\" returns successfully" Mar 20 18:05:06.281305 kubelet[1796]: E0320 18:05:06.281240 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:06.800930 kubelet[1796]: I0320 18:05:06.800861 1796 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-74wvs" podStartSLOduration=3.8870475190000002 podStartE2EDuration="9.80083936s" podCreationTimestamp="2025-03-20 18:04:57 +0000 UTC" firstStartedPulling="2025-03-20 18:04:59.280775135 +0000 UTC m=+2.394943104" lastFinishedPulling="2025-03-20 18:05:05.194566976 +0000 UTC m=+8.308734945" observedRunningTime="2025-03-20 18:05:06.80070622 +0000 UTC m=+9.914874189" watchObservedRunningTime="2025-03-20 18:05:06.80083936 +0000 UTC m=+9.915007329" Mar 20 18:05:06.899531 systemd-networkd[1427]: flannel.1: Link UP Mar 20 18:05:06.899543 systemd-networkd[1427]: flannel.1: Gained carrier Mar 20 18:05:07.282197 kubelet[1796]: E0320 18:05:07.282145 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:08.046078 systemd-networkd[1427]: flannel.1: Gained IPv6LL Mar 20 18:05:08.283249 kubelet[1796]: E0320 18:05:08.283189 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:09.086531 systemd[1]: Created slice kubepods-besteffort-pod6ba6dbde_65d4_4feb_9f76_fc44fb700e2d.slice - libcontainer container kubepods-besteffort-pod6ba6dbde_65d4_4feb_9f76_fc44fb700e2d.slice. Mar 20 18:05:09.153225 kubelet[1796]: I0320 18:05:09.153186 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-tjsp2\" (UniqueName: \"kubernetes.io/projected/6ba6dbde-65d4-4feb-9f76-fc44fb700e2d-kube-api-access-tjsp2\") pod \"nginx-deployment-7fcdb87857-th9zc\" (UID: \"6ba6dbde-65d4-4feb-9f76-fc44fb700e2d\") " pod="default/nginx-deployment-7fcdb87857-th9zc" Mar 20 18:05:09.284233 kubelet[1796]: E0320 18:05:09.284201 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:09.389435 containerd[1506]: time="2025-03-20T18:05:09.389371722Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-th9zc,Uid:6ba6dbde-65d4-4feb-9f76-fc44fb700e2d,Namespace:default,Attempt:0,}" Mar 20 18:05:09.404119 systemd-networkd[1427]: cni0: Link UP Mar 20 18:05:09.404443 systemd-networkd[1427]: cni0: Gained carrier Mar 20 18:05:09.408273 systemd-networkd[1427]: cni0: Lost carrier Mar 20 18:05:09.414038 systemd-networkd[1427]: veth9c0a6f9b: Link UP Mar 20 18:05:09.415377 kernel: cni0: port 1(veth9c0a6f9b) entered blocking state Mar 20 18:05:09.415442 kernel: cni0: port 1(veth9c0a6f9b) entered disabled state Mar 20 18:05:09.415465 kernel: veth9c0a6f9b: entered allmulticast mode Mar 20 18:05:09.419089 kernel: veth9c0a6f9b: entered promiscuous mode Mar 20 18:05:09.419133 kernel: cni0: port 1(veth9c0a6f9b) entered blocking state Mar 20 18:05:09.419152 kernel: cni0: port 1(veth9c0a6f9b) entered forwarding state Mar 20 18:05:09.420988 kernel: cni0: port 1(veth9c0a6f9b) entered disabled state Mar 20 18:05:09.429249 kernel: cni0: port 1(veth9c0a6f9b) entered blocking state Mar 20 18:05:09.429310 kernel: cni0: port 1(veth9c0a6f9b) entered forwarding state Mar 20 18:05:09.430277 systemd-networkd[1427]: veth9c0a6f9b: Gained carrier Mar 20 18:05:09.430717 systemd-networkd[1427]: cni0: Gained carrier Mar 20 18:05:09.434900 containerd[1506]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00001e938), "name":"cbr0", "type":"bridge"} Mar 20 18:05:09.434900 containerd[1506]: delegateAdd: netconf sent to delegate plugin: Mar 20 18:05:09.472192 containerd[1506]: {"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-20T18:05:09.472151153Z" level=info msg="connecting to shim e84e097d6c1ccc1b2ee1738561ac65364304066ed980bf5cf57c0fdc6c2a6d38" address="unix:///run/containerd/s/e12a8525bf44155fc83a57bab270c3763e0fdfd5b49095b35c762aed5e1aff7f" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:05:09.507118 systemd[1]: Started cri-containerd-e84e097d6c1ccc1b2ee1738561ac65364304066ed980bf5cf57c0fdc6c2a6d38.scope - libcontainer container e84e097d6c1ccc1b2ee1738561ac65364304066ed980bf5cf57c0fdc6c2a6d38. Mar 20 18:05:09.526067 systemd-resolved[1342]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 20 18:05:09.581264 containerd[1506]: time="2025-03-20T18:05:09.581203044Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-th9zc,Uid:6ba6dbde-65d4-4feb-9f76-fc44fb700e2d,Namespace:default,Attempt:0,} returns sandbox id \"e84e097d6c1ccc1b2ee1738561ac65364304066ed980bf5cf57c0fdc6c2a6d38\"" Mar 20 18:05:09.582324 containerd[1506]: time="2025-03-20T18:05:09.582283661Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 20 18:05:10.284841 kubelet[1796]: E0320 18:05:10.284787 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:10.542113 systemd-networkd[1427]: cni0: Gained IPv6LL Mar 20 18:05:10.798057 systemd-networkd[1427]: veth9c0a6f9b: Gained IPv6LL Mar 20 18:05:11.285562 kubelet[1796]: E0320 18:05:11.285493 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:12.286346 kubelet[1796]: E0320 18:05:12.286295 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:12.643728 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1964834703.mount: Deactivated successfully. Mar 20 18:05:13.287231 kubelet[1796]: E0320 18:05:13.287175 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:13.418655 containerd[1506]: time="2025-03-20T18:05:13.418592980Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:13.419376 containerd[1506]: time="2025-03-20T18:05:13.419307800Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73060131" Mar 20 18:05:13.421048 containerd[1506]: time="2025-03-20T18:05:13.420978213Z" level=info msg="ImageCreate event name:\"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:13.636839 containerd[1506]: time="2025-03-20T18:05:13.636758769Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:b927c62cc716b99bce51774b46a63feb63f5414c6f985fb80cacd1933bbd0e06\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:13.637654 containerd[1506]: time="2025-03-20T18:05:13.637596540Z" 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 4.055282673s" Mar 20 18:05:13.637703 containerd[1506]: time="2025-03-20T18:05:13.637650651Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\"" Mar 20 18:05:13.639608 containerd[1506]: time="2025-03-20T18:05:13.639580741Z" level=info msg="CreateContainer within sandbox \"e84e097d6c1ccc1b2ee1738561ac65364304066ed980bf5cf57c0fdc6c2a6d38\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Mar 20 18:05:13.661134 containerd[1506]: time="2025-03-20T18:05:13.661069169Z" level=info msg="Container 2d9ace82ac347719e0fad3046dfa5031213145fd071a900800d4a07bf5c030ba: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:05:13.668079 containerd[1506]: time="2025-03-20T18:05:13.668042517Z" level=info msg="CreateContainer within sandbox \"e84e097d6c1ccc1b2ee1738561ac65364304066ed980bf5cf57c0fdc6c2a6d38\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"2d9ace82ac347719e0fad3046dfa5031213145fd071a900800d4a07bf5c030ba\"" Mar 20 18:05:13.668581 containerd[1506]: time="2025-03-20T18:05:13.668542124Z" level=info msg="StartContainer for \"2d9ace82ac347719e0fad3046dfa5031213145fd071a900800d4a07bf5c030ba\"" Mar 20 18:05:13.669511 containerd[1506]: time="2025-03-20T18:05:13.669479983Z" level=info msg="connecting to shim 2d9ace82ac347719e0fad3046dfa5031213145fd071a900800d4a07bf5c030ba" address="unix:///run/containerd/s/e12a8525bf44155fc83a57bab270c3763e0fdfd5b49095b35c762aed5e1aff7f" protocol=ttrpc version=3 Mar 20 18:05:13.693126 systemd[1]: Started cri-containerd-2d9ace82ac347719e0fad3046dfa5031213145fd071a900800d4a07bf5c030ba.scope - libcontainer container 2d9ace82ac347719e0fad3046dfa5031213145fd071a900800d4a07bf5c030ba. Mar 20 18:05:13.725247 containerd[1506]: time="2025-03-20T18:05:13.725214254Z" level=info msg="StartContainer for \"2d9ace82ac347719e0fad3046dfa5031213145fd071a900800d4a07bf5c030ba\" returns successfully" Mar 20 18:05:13.808357 kubelet[1796]: I0320 18:05:13.808263 1796 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-th9zc" podStartSLOduration=0.751794363 podStartE2EDuration="4.80824711s" podCreationTimestamp="2025-03-20 18:05:09 +0000 UTC" firstStartedPulling="2025-03-20 18:05:09.582058047 +0000 UTC m=+12.696226016" lastFinishedPulling="2025-03-20 18:05:13.638510794 +0000 UTC m=+16.752678763" observedRunningTime="2025-03-20 18:05:13.808046835 +0000 UTC m=+16.922214804" watchObservedRunningTime="2025-03-20 18:05:13.80824711 +0000 UTC m=+16.922415080" Mar 20 18:05:14.288067 kubelet[1796]: E0320 18:05:14.288008 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:15.288548 kubelet[1796]: E0320 18:05:15.288468 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:15.497637 systemd[1]: Created slice kubepods-besteffort-pod042e09b6_a23e_4b8d_a879_22d13733baa7.slice - libcontainer container kubepods-besteffort-pod042e09b6_a23e_4b8d_a879_22d13733baa7.slice. Mar 20 18:05:15.588745 kubelet[1796]: I0320 18:05:15.588627 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-55mv6\" (UniqueName: \"kubernetes.io/projected/042e09b6-a23e-4b8d-a879-22d13733baa7-kube-api-access-55mv6\") pod \"nfs-server-provisioner-0\" (UID: \"042e09b6-a23e-4b8d-a879-22d13733baa7\") " pod="default/nfs-server-provisioner-0" Mar 20 18:05:15.588745 kubelet[1796]: I0320 18:05:15.588664 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/042e09b6-a23e-4b8d-a879-22d13733baa7-data\") pod \"nfs-server-provisioner-0\" (UID: \"042e09b6-a23e-4b8d-a879-22d13733baa7\") " pod="default/nfs-server-provisioner-0" Mar 20 18:05:15.801113 containerd[1506]: time="2025-03-20T18:05:15.801058032Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:042e09b6-a23e-4b8d-a879-22d13733baa7,Namespace:default,Attempt:0,}" Mar 20 18:05:15.819924 systemd-networkd[1427]: veth1b7e92a6: Link UP Mar 20 18:05:15.821459 kernel: cni0: port 2(veth1b7e92a6) entered blocking state Mar 20 18:05:15.821598 kernel: cni0: port 2(veth1b7e92a6) entered disabled state Mar 20 18:05:15.821619 kernel: veth1b7e92a6: entered allmulticast mode Mar 20 18:05:15.822924 kernel: veth1b7e92a6: entered promiscuous mode Mar 20 18:05:15.842262 kernel: cni0: port 2(veth1b7e92a6) entered blocking state Mar 20 18:05:15.842360 kernel: cni0: port 2(veth1b7e92a6) entered forwarding state Mar 20 18:05:15.842362 systemd-networkd[1427]: veth1b7e92a6: Gained carrier Mar 20 18:05:15.844856 containerd[1506]: 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)(0xc00001a938), "name":"cbr0", "type":"bridge"} Mar 20 18:05:15.844856 containerd[1506]: delegateAdd: netconf sent to delegate plugin: Mar 20 18:05:15.871128 containerd[1506]: {"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-20T18:05:15.871072805Z" level=info msg="connecting to shim 8db82a91a03f0006ce9b4cf44a16730a69ba9c49d8dab201d57c2d9486383211" address="unix:///run/containerd/s/667a19d8ba4605192165e146145174446b3ca33eb98343f74e56d80cc5c7eadd" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:05:15.911025 systemd[1]: Started cri-containerd-8db82a91a03f0006ce9b4cf44a16730a69ba9c49d8dab201d57c2d9486383211.scope - libcontainer container 8db82a91a03f0006ce9b4cf44a16730a69ba9c49d8dab201d57c2d9486383211. Mar 20 18:05:15.924887 systemd-resolved[1342]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 20 18:05:15.986959 containerd[1506]: time="2025-03-20T18:05:15.986899662Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:042e09b6-a23e-4b8d-a879-22d13733baa7,Namespace:default,Attempt:0,} returns sandbox id \"8db82a91a03f0006ce9b4cf44a16730a69ba9c49d8dab201d57c2d9486383211\"" Mar 20 18:05:15.988635 containerd[1506]: time="2025-03-20T18:05:15.988595402Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Mar 20 18:05:16.289400 kubelet[1796]: E0320 18:05:16.289353 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:17.134120 systemd-networkd[1427]: veth1b7e92a6: Gained IPv6LL Mar 20 18:05:17.273818 kubelet[1796]: E0320 18:05:17.273661 1796 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:17.290298 kubelet[1796]: E0320 18:05:17.290272 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:18.291425 kubelet[1796]: E0320 18:05:18.291366 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:18.340828 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1640853007.mount: Deactivated successfully. Mar 20 18:05:19.291844 kubelet[1796]: E0320 18:05:19.291803 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:20.292545 kubelet[1796]: E0320 18:05:20.292472 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:20.803294 containerd[1506]: time="2025-03-20T18:05:20.803238907Z" 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 20 18:05:20.804067 containerd[1506]: time="2025-03-20T18:05:20.803997389Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" Mar 20 18:05:20.805244 containerd[1506]: time="2025-03-20T18:05:20.805198201Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:20.807525 containerd[1506]: time="2025-03-20T18:05:20.807482014Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:20.808260 containerd[1506]: time="2025-03-20T18:05:20.808227362Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 4.819585854s" Mar 20 18:05:20.808295 containerd[1506]: time="2025-03-20T18:05:20.808259001Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Mar 20 18:05:20.810337 containerd[1506]: time="2025-03-20T18:05:20.810304828Z" level=info msg="CreateContainer within sandbox \"8db82a91a03f0006ce9b4cf44a16730a69ba9c49d8dab201d57c2d9486383211\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Mar 20 18:05:20.818629 containerd[1506]: time="2025-03-20T18:05:20.818591268Z" level=info msg="Container 451abcad7dbcb8ab147a59a66fa6f1692e7a4519e56f4f4b73e8d02fd36cccd9: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:05:20.825709 containerd[1506]: time="2025-03-20T18:05:20.825660586Z" level=info msg="CreateContainer within sandbox \"8db82a91a03f0006ce9b4cf44a16730a69ba9c49d8dab201d57c2d9486383211\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"451abcad7dbcb8ab147a59a66fa6f1692e7a4519e56f4f4b73e8d02fd36cccd9\"" Mar 20 18:05:20.826093 containerd[1506]: time="2025-03-20T18:05:20.826051459Z" level=info msg="StartContainer for \"451abcad7dbcb8ab147a59a66fa6f1692e7a4519e56f4f4b73e8d02fd36cccd9\"" Mar 20 18:05:20.827118 containerd[1506]: time="2025-03-20T18:05:20.827078956Z" level=info msg="connecting to shim 451abcad7dbcb8ab147a59a66fa6f1692e7a4519e56f4f4b73e8d02fd36cccd9" address="unix:///run/containerd/s/667a19d8ba4605192165e146145174446b3ca33eb98343f74e56d80cc5c7eadd" protocol=ttrpc version=3 Mar 20 18:05:20.848037 systemd[1]: Started cri-containerd-451abcad7dbcb8ab147a59a66fa6f1692e7a4519e56f4f4b73e8d02fd36cccd9.scope - libcontainer container 451abcad7dbcb8ab147a59a66fa6f1692e7a4519e56f4f4b73e8d02fd36cccd9. Mar 20 18:05:20.877686 containerd[1506]: time="2025-03-20T18:05:20.877600632Z" level=info msg="StartContainer for \"451abcad7dbcb8ab147a59a66fa6f1692e7a4519e56f4f4b73e8d02fd36cccd9\" returns successfully" Mar 20 18:05:21.292710 kubelet[1796]: E0320 18:05:21.292641 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:21.824820 kubelet[1796]: I0320 18:05:21.824762 1796 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=2.004049722 podStartE2EDuration="6.824744014s" podCreationTimestamp="2025-03-20 18:05:15 +0000 UTC" firstStartedPulling="2025-03-20 18:05:15.988313794 +0000 UTC m=+19.102481763" lastFinishedPulling="2025-03-20 18:05:20.809008086 +0000 UTC m=+23.923176055" observedRunningTime="2025-03-20 18:05:21.824720459 +0000 UTC m=+24.938888429" watchObservedRunningTime="2025-03-20 18:05:21.824744014 +0000 UTC m=+24.938911983" Mar 20 18:05:22.293239 kubelet[1796]: E0320 18:05:22.293133 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:23.294090 kubelet[1796]: E0320 18:05:23.294020 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:24.294485 kubelet[1796]: E0320 18:05:24.294427 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:25.294593 kubelet[1796]: E0320 18:05:25.294542 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:26.295233 kubelet[1796]: E0320 18:05:26.295164 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:27.296234 kubelet[1796]: E0320 18:05:27.296189 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:28.297187 kubelet[1796]: E0320 18:05:28.297126 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:29.298291 kubelet[1796]: E0320 18:05:29.298231 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:30.298950 kubelet[1796]: E0320 18:05:30.298849 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:31.040671 systemd[1]: Created slice kubepods-besteffort-podfb520d76_5111_4a68_98f3_cf0b0337dcb4.slice - libcontainer container kubepods-besteffort-podfb520d76_5111_4a68_98f3_cf0b0337dcb4.slice. Mar 20 18:05:31.083917 kubelet[1796]: I0320 18:05:31.083849 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-4a30f240-6e23-4ad4-bd93-8f06d5568343\" (UniqueName: \"kubernetes.io/nfs/fb520d76-5111-4a68-98f3-cf0b0337dcb4-pvc-4a30f240-6e23-4ad4-bd93-8f06d5568343\") pod \"test-pod-1\" (UID: \"fb520d76-5111-4a68-98f3-cf0b0337dcb4\") " pod="default/test-pod-1" Mar 20 18:05:31.083917 kubelet[1796]: I0320 18:05:31.083886 1796 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fntwk\" (UniqueName: \"kubernetes.io/projected/fb520d76-5111-4a68-98f3-cf0b0337dcb4-kube-api-access-fntwk\") pod \"test-pod-1\" (UID: \"fb520d76-5111-4a68-98f3-cf0b0337dcb4\") " pod="default/test-pod-1" Mar 20 18:05:31.206960 kernel: FS-Cache: Loaded Mar 20 18:05:31.271288 kernel: RPC: Registered named UNIX socket transport module. Mar 20 18:05:31.271396 kernel: RPC: Registered udp transport module. Mar 20 18:05:31.271416 kernel: RPC: Registered tcp transport module. Mar 20 18:05:31.272413 kernel: RPC: Registered tcp-with-tls transport module. Mar 20 18:05:31.272441 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 20 18:05:31.299219 kubelet[1796]: E0320 18:05:31.299088 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:31.488968 kernel: NFS: Registering the id_resolver key type Mar 20 18:05:31.489103 kernel: Key type id_resolver registered Mar 20 18:05:31.489122 kernel: Key type id_legacy registered Mar 20 18:05:31.512877 nfsidmap[2812]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 20 18:05:31.514914 nfsidmap[2813]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 20 18:05:31.643809 containerd[1506]: time="2025-03-20T18:05:31.643742531Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:fb520d76-5111-4a68-98f3-cf0b0337dcb4,Namespace:default,Attempt:0,}" Mar 20 18:05:31.654901 systemd-networkd[1427]: vethc2b452e6: Link UP Mar 20 18:05:31.656504 kernel: cni0: port 3(vethc2b452e6) entered blocking state Mar 20 18:05:31.656553 kernel: cni0: port 3(vethc2b452e6) entered disabled state Mar 20 18:05:31.657184 kernel: vethc2b452e6: entered allmulticast mode Mar 20 18:05:31.657938 kernel: vethc2b452e6: entered promiscuous mode Mar 20 18:05:31.663643 kernel: cni0: port 3(vethc2b452e6) entered blocking state Mar 20 18:05:31.663721 kernel: cni0: port 3(vethc2b452e6) entered forwarding state Mar 20 18:05:31.663133 systemd-networkd[1427]: vethc2b452e6: Gained carrier Mar 20 18:05:31.665267 containerd[1506]: 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)(0xc00001a938), "name":"cbr0", "type":"bridge"} Mar 20 18:05:31.665267 containerd[1506]: delegateAdd: netconf sent to delegate plugin: Mar 20 18:05:31.687979 containerd[1506]: {"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-20T18:05:31.687935820Z" level=info msg="connecting to shim 79e4f28b68c6a931732a8f80a5334b8382019c6849d5b932d63dfb9acdb86129" address="unix:///run/containerd/s/494d013c76515f94bc6289da2df0a6ea322f9988b1e9b1e7d47182d4d7d60e8d" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:05:31.711043 systemd[1]: Started cri-containerd-79e4f28b68c6a931732a8f80a5334b8382019c6849d5b932d63dfb9acdb86129.scope - libcontainer container 79e4f28b68c6a931732a8f80a5334b8382019c6849d5b932d63dfb9acdb86129. Mar 20 18:05:31.722427 systemd-resolved[1342]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 20 18:05:31.750619 containerd[1506]: time="2025-03-20T18:05:31.750582193Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:fb520d76-5111-4a68-98f3-cf0b0337dcb4,Namespace:default,Attempt:0,} returns sandbox id \"79e4f28b68c6a931732a8f80a5334b8382019c6849d5b932d63dfb9acdb86129\"" Mar 20 18:05:31.751639 containerd[1506]: time="2025-03-20T18:05:31.751610043Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 20 18:05:32.151008 containerd[1506]: time="2025-03-20T18:05:32.150958177Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:05:32.151804 containerd[1506]: time="2025-03-20T18:05:32.151753021Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Mar 20 18:05:32.153974 containerd[1506]: time="2025-03-20T18:05:32.153866908Z" 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 402.209524ms" Mar 20 18:05:32.153974 containerd[1506]: time="2025-03-20T18:05:32.153972048Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\"" Mar 20 18:05:32.155859 containerd[1506]: time="2025-03-20T18:05:32.155834926Z" level=info msg="CreateContainer within sandbox \"79e4f28b68c6a931732a8f80a5334b8382019c6849d5b932d63dfb9acdb86129\" for container &ContainerMetadata{Name:test,Attempt:0,}" Mar 20 18:05:32.164006 containerd[1506]: time="2025-03-20T18:05:32.163973198Z" level=info msg="Container 8a3e7b86ccabbb5f3eecfa431a0e7d72b014c7226ecafb436367f333735bbfbf: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:05:32.171459 containerd[1506]: time="2025-03-20T18:05:32.171425724Z" level=info msg="CreateContainer within sandbox \"79e4f28b68c6a931732a8f80a5334b8382019c6849d5b932d63dfb9acdb86129\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"8a3e7b86ccabbb5f3eecfa431a0e7d72b014c7226ecafb436367f333735bbfbf\"" Mar 20 18:05:32.171867 containerd[1506]: time="2025-03-20T18:05:32.171841365Z" level=info msg="StartContainer for \"8a3e7b86ccabbb5f3eecfa431a0e7d72b014c7226ecafb436367f333735bbfbf\"" Mar 20 18:05:32.172681 containerd[1506]: time="2025-03-20T18:05:32.172647842Z" level=info msg="connecting to shim 8a3e7b86ccabbb5f3eecfa431a0e7d72b014c7226ecafb436367f333735bbfbf" address="unix:///run/containerd/s/494d013c76515f94bc6289da2df0a6ea322f9988b1e9b1e7d47182d4d7d60e8d" protocol=ttrpc version=3 Mar 20 18:05:32.193056 systemd[1]: Started cri-containerd-8a3e7b86ccabbb5f3eecfa431a0e7d72b014c7226ecafb436367f333735bbfbf.scope - libcontainer container 8a3e7b86ccabbb5f3eecfa431a0e7d72b014c7226ecafb436367f333735bbfbf. Mar 20 18:05:32.221721 containerd[1506]: time="2025-03-20T18:05:32.221672365Z" level=info msg="StartContainer for \"8a3e7b86ccabbb5f3eecfa431a0e7d72b014c7226ecafb436367f333735bbfbf\" returns successfully" Mar 20 18:05:32.300102 kubelet[1796]: E0320 18:05:32.300057 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:32.842661 kubelet[1796]: I0320 18:05:32.842598 1796 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=17.439252992 podStartE2EDuration="17.842580277s" podCreationTimestamp="2025-03-20 18:05:15 +0000 UTC" firstStartedPulling="2025-03-20 18:05:31.751370345 +0000 UTC m=+34.865538314" lastFinishedPulling="2025-03-20 18:05:32.15469763 +0000 UTC m=+35.268865599" observedRunningTime="2025-03-20 18:05:32.842390665 +0000 UTC m=+35.956558634" watchObservedRunningTime="2025-03-20 18:05:32.842580277 +0000 UTC m=+35.956748246" Mar 20 18:05:33.300370 kubelet[1796]: E0320 18:05:33.300303 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:33.710078 systemd-networkd[1427]: vethc2b452e6: Gained IPv6LL Mar 20 18:05:34.300734 kubelet[1796]: E0320 18:05:34.300677 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:35.301300 kubelet[1796]: E0320 18:05:35.301256 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:35.431236 update_engine[1496]: I20250320 18:05:35.431188 1496 update_attempter.cc:509] Updating boot flags... Mar 20 18:05:35.458934 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2977) Mar 20 18:05:35.492050 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2981) Mar 20 18:05:35.533447 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2981) Mar 20 18:05:36.302081 kubelet[1796]: E0320 18:05:36.302031 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:37.274052 kubelet[1796]: E0320 18:05:37.273987 1796 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:37.302636 kubelet[1796]: E0320 18:05:37.302590 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:05:38.303254 kubelet[1796]: E0320 18:05:38.303177 1796 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"