Mar 20 17:59:53.876201 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 17:59:53.876224 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 17:59:53.876235 kernel: BIOS-provided physical RAM map: Mar 20 17:59:53.876242 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 20 17:59:53.876248 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable Mar 20 17:59:53.876255 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Mar 20 17:59:53.876262 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable Mar 20 17:59:53.876269 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Mar 20 17:59:53.876276 kernel: BIOS-e820: [mem 0x000000000080c000-0x0000000000810fff] usable Mar 20 17:59:53.876283 kernel: BIOS-e820: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Mar 20 17:59:53.876289 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009bd3efff] usable Mar 20 17:59:53.876298 kernel: BIOS-e820: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Mar 20 17:59:53.876305 kernel: BIOS-e820: [mem 0x000000009be00000-0x000000009c8ecfff] usable Mar 20 17:59:53.876312 kernel: BIOS-e820: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Mar 20 17:59:53.876320 kernel: BIOS-e820: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Mar 20 17:59:53.876327 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Mar 20 17:59:53.876336 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009ce91fff] usable Mar 20 17:59:53.876343 kernel: BIOS-e820: [mem 0x000000009ce92000-0x000000009ce95fff] reserved Mar 20 17:59:53.876350 kernel: BIOS-e820: [mem 0x000000009ce96000-0x000000009ce97fff] ACPI NVS Mar 20 17:59:53.876357 kernel: BIOS-e820: [mem 0x000000009ce98000-0x000000009cedbfff] usable Mar 20 17:59:53.876364 kernel: BIOS-e820: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Mar 20 17:59:53.876371 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Mar 20 17:59:53.876378 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved Mar 20 17:59:53.876385 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 20 17:59:53.876393 kernel: BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Mar 20 17:59:53.876400 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 20 17:59:53.876407 kernel: NX (Execute Disable) protection: active Mar 20 17:59:53.876416 kernel: APIC: Static calls initialized Mar 20 17:59:53.876423 kernel: e820: update [mem 0x9b351018-0x9b35ac57] usable ==> usable Mar 20 17:59:53.876430 kernel: e820: update [mem 0x9b351018-0x9b35ac57] usable ==> usable Mar 20 17:59:53.876437 kernel: e820: update [mem 0x9b314018-0x9b350e57] usable ==> usable Mar 20 17:59:53.876444 kernel: e820: update [mem 0x9b314018-0x9b350e57] usable ==> usable Mar 20 17:59:53.876451 kernel: extended physical RAM map: Mar 20 17:59:53.876458 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable Mar 20 17:59:53.876465 kernel: reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable Mar 20 17:59:53.876472 kernel: reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS Mar 20 17:59:53.876480 kernel: reserve setup_data: [mem 0x0000000000808000-0x000000000080afff] usable Mar 20 17:59:53.876487 kernel: reserve setup_data: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS Mar 20 17:59:53.876494 kernel: reserve setup_data: [mem 0x000000000080c000-0x0000000000810fff] usable Mar 20 17:59:53.876503 kernel: reserve setup_data: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS Mar 20 17:59:53.876513 kernel: reserve setup_data: [mem 0x0000000000900000-0x000000009b314017] usable Mar 20 17:59:53.876521 kernel: reserve setup_data: [mem 0x000000009b314018-0x000000009b350e57] usable Mar 20 17:59:53.876528 kernel: reserve setup_data: [mem 0x000000009b350e58-0x000000009b351017] usable Mar 20 17:59:53.876535 kernel: reserve setup_data: [mem 0x000000009b351018-0x000000009b35ac57] usable Mar 20 17:59:53.876543 kernel: reserve setup_data: [mem 0x000000009b35ac58-0x000000009bd3efff] usable Mar 20 17:59:53.876552 kernel: reserve setup_data: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved Mar 20 17:59:53.876559 kernel: reserve setup_data: [mem 0x000000009be00000-0x000000009c8ecfff] usable Mar 20 17:59:53.876567 kernel: reserve setup_data: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved Mar 20 17:59:53.876574 kernel: reserve setup_data: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data Mar 20 17:59:53.876582 kernel: reserve setup_data: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS Mar 20 17:59:53.876589 kernel: reserve setup_data: [mem 0x000000009cbff000-0x000000009ce91fff] usable Mar 20 17:59:53.876596 kernel: reserve setup_data: [mem 0x000000009ce92000-0x000000009ce95fff] reserved Mar 20 17:59:53.876604 kernel: reserve setup_data: [mem 0x000000009ce96000-0x000000009ce97fff] ACPI NVS Mar 20 17:59:53.876611 kernel: reserve setup_data: [mem 0x000000009ce98000-0x000000009cedbfff] usable Mar 20 17:59:53.876618 kernel: reserve setup_data: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved Mar 20 17:59:53.876628 kernel: reserve setup_data: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS Mar 20 17:59:53.876635 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved Mar 20 17:59:53.876643 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved Mar 20 17:59:53.876650 kernel: reserve setup_data: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved Mar 20 17:59:53.876657 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved Mar 20 17:59:53.876664 kernel: efi: EFI v2.7 by EDK II Mar 20 17:59:53.876683 kernel: efi: SMBIOS=0x9c988000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9ba0d198 RNG=0x9cb73018 Mar 20 17:59:53.876690 kernel: random: crng init done Mar 20 17:59:53.876698 kernel: efi: Remove mem142: MMIO range=[0xffc00000-0xffffffff] (4MB) from e820 map Mar 20 17:59:53.876705 kernel: e820: remove [mem 0xffc00000-0xffffffff] reserved Mar 20 17:59:53.876713 kernel: secureboot: Secure boot disabled Mar 20 17:59:53.876722 kernel: SMBIOS 2.8 present. Mar 20 17:59:53.876730 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 Mar 20 17:59:53.876737 kernel: Hypervisor detected: KVM Mar 20 17:59:53.876744 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Mar 20 17:59:53.876752 kernel: kvm-clock: using sched offset of 2736479611 cycles Mar 20 17:59:53.876759 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Mar 20 17:59:53.876767 kernel: tsc: Detected 2794.748 MHz processor Mar 20 17:59:53.876775 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Mar 20 17:59:53.876783 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Mar 20 17:59:53.876790 kernel: last_pfn = 0x9cedc max_arch_pfn = 0x400000000 Mar 20 17:59:53.876800 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Mar 20 17:59:53.876808 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Mar 20 17:59:53.876815 kernel: Using GB pages for direct mapping Mar 20 17:59:53.876823 kernel: ACPI: Early table checksum verification disabled Mar 20 17:59:53.876831 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) Mar 20 17:59:53.876838 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) Mar 20 17:59:53.876846 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 17:59:53.876853 kernel: ACPI: DSDT 0x000000009CB7A000 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 17:59:53.876861 kernel: ACPI: FACS 0x000000009CBDD000 000040 Mar 20 17:59:53.876871 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 17:59:53.876879 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 17:59:53.876889 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 17:59:53.876899 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 20 17:59:53.876909 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) Mar 20 17:59:53.876916 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] Mar 20 17:59:53.876924 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1a7] Mar 20 17:59:53.876931 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] Mar 20 17:59:53.876939 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] Mar 20 17:59:53.876949 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] Mar 20 17:59:53.876956 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] Mar 20 17:59:53.876972 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] Mar 20 17:59:53.876979 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] Mar 20 17:59:53.876987 kernel: No NUMA configuration found Mar 20 17:59:53.876994 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cedbfff] Mar 20 17:59:53.877001 kernel: NODE_DATA(0) allocated [mem 0x9ce3a000-0x9ce3ffff] Mar 20 17:59:53.877009 kernel: Zone ranges: Mar 20 17:59:53.877017 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Mar 20 17:59:53.877026 kernel: DMA32 [mem 0x0000000001000000-0x000000009cedbfff] Mar 20 17:59:53.877034 kernel: Normal empty Mar 20 17:59:53.877041 kernel: Movable zone start for each node Mar 20 17:59:53.877049 kernel: Early memory node ranges Mar 20 17:59:53.877056 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Mar 20 17:59:53.877064 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] Mar 20 17:59:53.877071 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] Mar 20 17:59:53.877079 kernel: node 0: [mem 0x000000000080c000-0x0000000000810fff] Mar 20 17:59:53.877086 kernel: node 0: [mem 0x0000000000900000-0x000000009bd3efff] Mar 20 17:59:53.877094 kernel: node 0: [mem 0x000000009be00000-0x000000009c8ecfff] Mar 20 17:59:53.877104 kernel: node 0: [mem 0x000000009cbff000-0x000000009ce91fff] Mar 20 17:59:53.877111 kernel: node 0: [mem 0x000000009ce98000-0x000000009cedbfff] Mar 20 17:59:53.877121 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cedbfff] Mar 20 17:59:53.877129 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 20 17:59:53.877138 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Mar 20 17:59:53.877153 kernel: On node 0, zone DMA: 8 pages in unavailable ranges Mar 20 17:59:53.877163 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Mar 20 17:59:53.877171 kernel: On node 0, zone DMA: 239 pages in unavailable ranges Mar 20 17:59:53.877179 kernel: On node 0, zone DMA32: 193 pages in unavailable ranges Mar 20 17:59:53.877187 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Mar 20 17:59:53.877194 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges Mar 20 17:59:53.877202 kernel: On node 0, zone DMA32: 12580 pages in unavailable ranges Mar 20 17:59:53.877212 kernel: ACPI: PM-Timer IO Port: 0x608 Mar 20 17:59:53.877220 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Mar 20 17:59:53.877228 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Mar 20 17:59:53.877236 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Mar 20 17:59:53.877243 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Mar 20 17:59:53.877253 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Mar 20 17:59:53.877261 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Mar 20 17:59:53.877269 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Mar 20 17:59:53.877277 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Mar 20 17:59:53.877284 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 Mar 20 17:59:53.877292 kernel: TSC deadline timer available Mar 20 17:59:53.877300 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs Mar 20 17:59:53.877308 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Mar 20 17:59:53.877315 kernel: kvm-guest: KVM setup pv remote TLB flush Mar 20 17:59:53.877325 kernel: kvm-guest: setup PV sched yield Mar 20 17:59:53.877333 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices Mar 20 17:59:53.877341 kernel: Booting paravirtualized kernel on KVM Mar 20 17:59:53.877349 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Mar 20 17:59:53.877357 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 Mar 20 17:59:53.877365 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u524288 Mar 20 17:59:53.877373 kernel: pcpu-alloc: s197032 r8192 d32344 u524288 alloc=1*2097152 Mar 20 17:59:53.877380 kernel: pcpu-alloc: [0] 0 1 2 3 Mar 20 17:59:53.877388 kernel: kvm-guest: PV spinlocks enabled Mar 20 17:59:53.877398 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Mar 20 17:59:53.877407 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 17:59:53.877415 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 20 17:59:53.877423 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 20 17:59:53.877431 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 20 17:59:53.877439 kernel: Fallback order for Node 0: 0 Mar 20 17:59:53.877446 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629460 Mar 20 17:59:53.877454 kernel: Policy zone: DMA32 Mar 20 17:59:53.877464 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 20 17:59:53.877472 kernel: Memory: 2385672K/2565800K available (14336K kernel code, 2304K rwdata, 25060K rodata, 43592K init, 1472K bss, 179872K reserved, 0K cma-reserved) Mar 20 17:59:53.877480 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 20 17:59:53.877488 kernel: ftrace: allocating 37985 entries in 149 pages Mar 20 17:59:53.877496 kernel: ftrace: allocated 149 pages with 4 groups Mar 20 17:59:53.877504 kernel: Dynamic Preempt: voluntary Mar 20 17:59:53.877512 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 20 17:59:53.877520 kernel: rcu: RCU event tracing is enabled. Mar 20 17:59:53.877528 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 20 17:59:53.877538 kernel: Trampoline variant of Tasks RCU enabled. Mar 20 17:59:53.877546 kernel: Rude variant of Tasks RCU enabled. Mar 20 17:59:53.877554 kernel: Tracing variant of Tasks RCU enabled. Mar 20 17:59:53.877562 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 20 17:59:53.877569 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 20 17:59:53.877577 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 Mar 20 17:59:53.877585 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 20 17:59:53.877593 kernel: Console: colour dummy device 80x25 Mar 20 17:59:53.877600 kernel: printk: console [ttyS0] enabled Mar 20 17:59:53.877608 kernel: ACPI: Core revision 20230628 Mar 20 17:59:53.877618 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns Mar 20 17:59:53.877626 kernel: APIC: Switch to symmetric I/O mode setup Mar 20 17:59:53.877634 kernel: x2apic enabled Mar 20 17:59:53.877642 kernel: APIC: Switched APIC routing to: physical x2apic Mar 20 17:59:53.877650 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() Mar 20 17:59:53.877658 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() Mar 20 17:59:53.877665 kernel: kvm-guest: setup PV IPIs Mar 20 17:59:53.877684 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Mar 20 17:59:53.877692 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Mar 20 17:59:53.877702 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) Mar 20 17:59:53.877710 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Mar 20 17:59:53.877718 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 Mar 20 17:59:53.877726 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 Mar 20 17:59:53.877734 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Mar 20 17:59:53.877742 kernel: Spectre V2 : Mitigation: Retpolines Mar 20 17:59:53.877750 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Mar 20 17:59:53.877757 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Mar 20 17:59:53.877765 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls Mar 20 17:59:53.877775 kernel: RETBleed: Mitigation: untrained return thunk Mar 20 17:59:53.877783 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Mar 20 17:59:53.877791 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Mar 20 17:59:53.877799 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! Mar 20 17:59:53.877807 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. Mar 20 17:59:53.877815 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode Mar 20 17:59:53.877823 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Mar 20 17:59:53.877831 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Mar 20 17:59:53.877841 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Mar 20 17:59:53.877849 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Mar 20 17:59:53.877857 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. Mar 20 17:59:53.877865 kernel: Freeing SMP alternatives memory: 32K Mar 20 17:59:53.877873 kernel: pid_max: default: 32768 minimum: 301 Mar 20 17:59:53.877881 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 20 17:59:53.877888 kernel: landlock: Up and running. Mar 20 17:59:53.877896 kernel: SELinux: Initializing. Mar 20 17:59:53.877904 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 20 17:59:53.877914 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 20 17:59:53.877922 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) Mar 20 17:59:53.877930 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 20 17:59:53.877938 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 20 17:59:53.877946 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Mar 20 17:59:53.877954 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. Mar 20 17:59:53.877962 kernel: ... version: 0 Mar 20 17:59:53.877975 kernel: ... bit width: 48 Mar 20 17:59:53.877983 kernel: ... generic registers: 6 Mar 20 17:59:53.877993 kernel: ... value mask: 0000ffffffffffff Mar 20 17:59:53.878001 kernel: ... max period: 00007fffffffffff Mar 20 17:59:53.878009 kernel: ... fixed-purpose events: 0 Mar 20 17:59:53.878017 kernel: ... event mask: 000000000000003f Mar 20 17:59:53.878024 kernel: signal: max sigframe size: 1776 Mar 20 17:59:53.878033 kernel: rcu: Hierarchical SRCU implementation. Mar 20 17:59:53.878041 kernel: rcu: Max phase no-delay instances is 400. Mar 20 17:59:53.878049 kernel: smp: Bringing up secondary CPUs ... Mar 20 17:59:53.878059 kernel: smpboot: x86: Booting SMP configuration: Mar 20 17:59:53.878069 kernel: .... node #0, CPUs: #1 #2 #3 Mar 20 17:59:53.878079 kernel: smp: Brought up 1 node, 4 CPUs Mar 20 17:59:53.878087 kernel: smpboot: Max logical packages: 1 Mar 20 17:59:53.878095 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) Mar 20 17:59:53.878103 kernel: devtmpfs: initialized Mar 20 17:59:53.878110 kernel: x86/mm: Memory block size: 128MB Mar 20 17:59:53.878118 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) Mar 20 17:59:53.878126 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) Mar 20 17:59:53.878134 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00811000-0x008fffff] (978944 bytes) Mar 20 17:59:53.878144 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) Mar 20 17:59:53.878152 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9ce96000-0x9ce97fff] (8192 bytes) Mar 20 17:59:53.878160 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) Mar 20 17:59:53.878168 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 20 17:59:53.878176 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 20 17:59:53.878184 kernel: pinctrl core: initialized pinctrl subsystem Mar 20 17:59:53.878192 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 20 17:59:53.878200 kernel: audit: initializing netlink subsys (disabled) Mar 20 17:59:53.878208 kernel: audit: type=2000 audit(1742493594.333:1): state=initialized audit_enabled=0 res=1 Mar 20 17:59:53.878217 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 20 17:59:53.878225 kernel: thermal_sys: Registered thermal governor 'user_space' Mar 20 17:59:53.878233 kernel: cpuidle: using governor menu Mar 20 17:59:53.878241 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 20 17:59:53.878249 kernel: dca service started, version 1.12.1 Mar 20 17:59:53.878257 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) Mar 20 17:59:53.878264 kernel: PCI: Using configuration type 1 for base access Mar 20 17:59:53.878272 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Mar 20 17:59:53.878280 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 20 17:59:53.878290 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Mar 20 17:59:53.878298 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 20 17:59:53.878306 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Mar 20 17:59:53.878314 kernel: ACPI: Added _OSI(Module Device) Mar 20 17:59:53.878321 kernel: ACPI: Added _OSI(Processor Device) Mar 20 17:59:53.878329 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 20 17:59:53.878337 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 20 17:59:53.878345 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 20 17:59:53.878352 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Mar 20 17:59:53.878363 kernel: ACPI: Interpreter enabled Mar 20 17:59:53.878370 kernel: ACPI: PM: (supports S0 S3 S5) Mar 20 17:59:53.878378 kernel: ACPI: Using IOAPIC for interrupt routing Mar 20 17:59:53.878386 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Mar 20 17:59:53.878394 kernel: PCI: Using E820 reservations for host bridge windows Mar 20 17:59:53.878402 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Mar 20 17:59:53.878409 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 20 17:59:53.878593 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 20 17:59:53.878756 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] Mar 20 17:59:53.878881 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] Mar 20 17:59:53.878892 kernel: PCI host bridge to bus 0000:00 Mar 20 17:59:53.879028 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Mar 20 17:59:53.879144 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Mar 20 17:59:53.879257 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Mar 20 17:59:53.879369 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] Mar 20 17:59:53.879487 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] Mar 20 17:59:53.879599 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] Mar 20 17:59:53.879731 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 20 17:59:53.879871 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Mar 20 17:59:53.880012 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 Mar 20 17:59:53.880137 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] Mar 20 17:59:53.880265 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xc1044000-0xc1044fff] Mar 20 17:59:53.880387 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] Mar 20 17:59:53.880510 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb Mar 20 17:59:53.880632 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Mar 20 17:59:53.880837 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 Mar 20 17:59:53.880973 kernel: pci 0000:00:02.0: reg 0x10: [io 0x6100-0x611f] Mar 20 17:59:53.881098 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xc1043000-0xc1043fff] Mar 20 17:59:53.881272 kernel: pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] Mar 20 17:59:53.881450 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 Mar 20 17:59:53.881577 kernel: pci 0000:00:03.0: reg 0x10: [io 0x6000-0x607f] Mar 20 17:59:53.881769 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff] Mar 20 17:59:53.881898 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] Mar 20 17:59:53.882044 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 Mar 20 17:59:53.882173 kernel: pci 0000:00:04.0: reg 0x10: [io 0x60e0-0x60ff] Mar 20 17:59:53.882294 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff] Mar 20 17:59:53.882428 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] Mar 20 17:59:53.882579 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] Mar 20 17:59:53.882727 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Mar 20 17:59:53.882851 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Mar 20 17:59:53.882989 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Mar 20 17:59:53.883117 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x60c0-0x60df] Mar 20 17:59:53.883239 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xc1040000-0xc1040fff] Mar 20 17:59:53.883368 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Mar 20 17:59:53.883490 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6080-0x60bf] Mar 20 17:59:53.883501 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Mar 20 17:59:53.883509 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Mar 20 17:59:53.883517 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Mar 20 17:59:53.883524 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Mar 20 17:59:53.883536 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Mar 20 17:59:53.883544 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Mar 20 17:59:53.883551 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Mar 20 17:59:53.883565 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Mar 20 17:59:53.883576 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Mar 20 17:59:53.883591 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Mar 20 17:59:53.883609 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Mar 20 17:59:53.883623 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Mar 20 17:59:53.883641 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Mar 20 17:59:53.883661 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Mar 20 17:59:53.883669 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Mar 20 17:59:53.883688 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Mar 20 17:59:53.883696 kernel: iommu: Default domain type: Translated Mar 20 17:59:53.883704 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Mar 20 17:59:53.883712 kernel: efivars: Registered efivars operations Mar 20 17:59:53.883720 kernel: PCI: Using ACPI for IRQ routing Mar 20 17:59:53.883728 kernel: PCI: pci_cache_line_size set to 64 bytes Mar 20 17:59:53.883735 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] Mar 20 17:59:53.883745 kernel: e820: reserve RAM buffer [mem 0x00811000-0x008fffff] Mar 20 17:59:53.883753 kernel: e820: reserve RAM buffer [mem 0x9b314018-0x9bffffff] Mar 20 17:59:53.883761 kernel: e820: reserve RAM buffer [mem 0x9b351018-0x9bffffff] Mar 20 17:59:53.883769 kernel: e820: reserve RAM buffer [mem 0x9bd3f000-0x9bffffff] Mar 20 17:59:53.883776 kernel: e820: reserve RAM buffer [mem 0x9c8ed000-0x9fffffff] Mar 20 17:59:53.883784 kernel: e820: reserve RAM buffer [mem 0x9ce92000-0x9fffffff] Mar 20 17:59:53.883792 kernel: e820: reserve RAM buffer [mem 0x9cedc000-0x9fffffff] Mar 20 17:59:53.883960 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Mar 20 17:59:53.884102 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Mar 20 17:59:53.884225 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Mar 20 17:59:53.884236 kernel: vgaarb: loaded Mar 20 17:59:53.884244 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 Mar 20 17:59:53.884252 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter Mar 20 17:59:53.884260 kernel: clocksource: Switched to clocksource kvm-clock Mar 20 17:59:53.884268 kernel: VFS: Disk quotas dquot_6.6.0 Mar 20 17:59:53.884276 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 20 17:59:53.884284 kernel: pnp: PnP ACPI init Mar 20 17:59:53.884421 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved Mar 20 17:59:53.884433 kernel: pnp: PnP ACPI: found 6 devices Mar 20 17:59:53.884441 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Mar 20 17:59:53.884449 kernel: NET: Registered PF_INET protocol family Mar 20 17:59:53.884473 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 20 17:59:53.884483 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 20 17:59:53.884492 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 20 17:59:53.884500 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 20 17:59:53.884511 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 20 17:59:53.884519 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 20 17:59:53.884527 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 20 17:59:53.884535 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 20 17:59:53.884543 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 20 17:59:53.884552 kernel: NET: Registered PF_XDP protocol family Mar 20 17:59:53.884718 kernel: pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window Mar 20 17:59:53.884846 kernel: pci 0000:00:04.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref] Mar 20 17:59:53.884973 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Mar 20 17:59:53.885103 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Mar 20 17:59:53.885216 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Mar 20 17:59:53.885327 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] Mar 20 17:59:53.885438 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] Mar 20 17:59:53.885550 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] Mar 20 17:59:53.885560 kernel: PCI: CLS 0 bytes, default 64 Mar 20 17:59:53.885569 kernel: Initialise system trusted keyrings Mar 20 17:59:53.885577 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 20 17:59:53.885590 kernel: Key type asymmetric registered Mar 20 17:59:53.885598 kernel: Asymmetric key parser 'x509' registered Mar 20 17:59:53.885606 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 20 17:59:53.885614 kernel: io scheduler mq-deadline registered Mar 20 17:59:53.885622 kernel: io scheduler kyber registered Mar 20 17:59:53.885630 kernel: io scheduler bfq registered Mar 20 17:59:53.885641 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Mar 20 17:59:53.885650 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Mar 20 17:59:53.885658 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Mar 20 17:59:53.885669 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Mar 20 17:59:53.885689 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 20 17:59:53.885698 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Mar 20 17:59:53.885706 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Mar 20 17:59:53.885715 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Mar 20 17:59:53.885725 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Mar 20 17:59:53.885734 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Mar 20 17:59:53.885862 kernel: rtc_cmos 00:04: RTC can wake from S4 Mar 20 17:59:53.886023 kernel: rtc_cmos 00:04: registered as rtc0 Mar 20 17:59:53.886174 kernel: rtc_cmos 00:04: setting system clock to 2025-03-20T17:59:53 UTC (1742493593) Mar 20 17:59:53.886293 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Mar 20 17:59:53.886304 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Mar 20 17:59:53.886312 kernel: efifb: probing for efifb Mar 20 17:59:53.886325 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k Mar 20 17:59:53.886333 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 Mar 20 17:59:53.886344 kernel: efifb: scrolling: redraw Mar 20 17:59:53.886352 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 20 17:59:53.886360 kernel: Console: switching to colour frame buffer device 160x50 Mar 20 17:59:53.886368 kernel: fb0: EFI VGA frame buffer device Mar 20 17:59:53.886376 kernel: pstore: Using crash dump compression: deflate Mar 20 17:59:53.886385 kernel: pstore: Registered efi_pstore as persistent store backend Mar 20 17:59:53.886393 kernel: NET: Registered PF_INET6 protocol family Mar 20 17:59:53.886404 kernel: Segment Routing with IPv6 Mar 20 17:59:53.886412 kernel: In-situ OAM (IOAM) with IPv6 Mar 20 17:59:53.886420 kernel: NET: Registered PF_PACKET protocol family Mar 20 17:59:53.886428 kernel: Key type dns_resolver registered Mar 20 17:59:53.886436 kernel: IPI shorthand broadcast: enabled Mar 20 17:59:53.886444 kernel: sched_clock: Marking stable (571003291, 150836113)->(766407587, -44568183) Mar 20 17:59:53.886452 kernel: registered taskstats version 1 Mar 20 17:59:53.886461 kernel: Loading compiled-in X.509 certificates Mar 20 17:59:53.886469 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: 2c0605e0441a1fddfb1f70673dce1f0d470be9b5' Mar 20 17:59:53.886479 kernel: Key type .fscrypt registered Mar 20 17:59:53.886487 kernel: Key type fscrypt-provisioning registered Mar 20 17:59:53.886496 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 20 17:59:53.886504 kernel: ima: Allocated hash algorithm: sha1 Mar 20 17:59:53.886512 kernel: ima: No architecture policies found Mar 20 17:59:53.886521 kernel: clk: Disabling unused clocks Mar 20 17:59:53.886529 kernel: Freeing unused kernel image (initmem) memory: 43592K Mar 20 17:59:53.886537 kernel: Write protecting the kernel read-only data: 40960k Mar 20 17:59:53.886545 kernel: Freeing unused kernel image (rodata/data gap) memory: 1564K Mar 20 17:59:53.886555 kernel: Run /init as init process Mar 20 17:59:53.886564 kernel: with arguments: Mar 20 17:59:53.886572 kernel: /init Mar 20 17:59:53.886580 kernel: with environment: Mar 20 17:59:53.886588 kernel: HOME=/ Mar 20 17:59:53.886596 kernel: TERM=linux Mar 20 17:59:53.886604 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 20 17:59:53.886613 systemd[1]: Successfully made /usr/ read-only. Mar 20 17:59:53.886625 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 17:59:53.886637 systemd[1]: Detected virtualization kvm. Mar 20 17:59:53.886645 systemd[1]: Detected architecture x86-64. Mar 20 17:59:53.886654 systemd[1]: Running in initrd. Mar 20 17:59:53.886662 systemd[1]: No hostname configured, using default hostname. Mar 20 17:59:53.886683 systemd[1]: Hostname set to . Mar 20 17:59:53.886692 systemd[1]: Initializing machine ID from VM UUID. Mar 20 17:59:53.886700 systemd[1]: Queued start job for default target initrd.target. Mar 20 17:59:53.886714 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 20 17:59:53.886723 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 20 17:59:53.886732 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 20 17:59:53.886741 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 20 17:59:53.886750 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 20 17:59:53.886760 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 20 17:59:53.886770 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 17:59:53.886781 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 20 17:59:53.886790 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 20 17:59:53.886799 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 20 17:59:53.886808 systemd[1]: Reached target paths.target - Path Units. Mar 20 17:59:53.886816 systemd[1]: Reached target slices.target - Slice Units. Mar 20 17:59:53.886825 systemd[1]: Reached target swap.target - Swaps. Mar 20 17:59:53.886834 systemd[1]: Reached target timers.target - Timer Units. Mar 20 17:59:53.886842 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 20 17:59:53.886851 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 20 17:59:53.886862 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 20 17:59:53.886871 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 20 17:59:53.886880 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 20 17:59:53.886888 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 20 17:59:53.886897 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 20 17:59:53.886906 systemd[1]: Reached target sockets.target - Socket Units. Mar 20 17:59:53.886914 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 20 17:59:53.886923 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 20 17:59:53.886934 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 20 17:59:53.886943 systemd[1]: Starting systemd-fsck-usr.service... Mar 20 17:59:53.886952 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 20 17:59:53.886960 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 20 17:59:53.886983 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 17:59:53.886992 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 20 17:59:53.887001 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 20 17:59:53.887049 systemd-journald[191]: Collecting audit messages is disabled. Mar 20 17:59:53.887074 systemd[1]: Finished systemd-fsck-usr.service. Mar 20 17:59:53.887083 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 20 17:59:53.887092 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 17:59:53.887104 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 20 17:59:53.887113 systemd-journald[191]: Journal started Mar 20 17:59:53.887132 systemd-journald[191]: Runtime Journal (/run/log/journal/ca276d10d9da445ba49abca7e5cee1f4) is 6M, max 48.2M, 42.2M free. Mar 20 17:59:53.883963 systemd-modules-load[195]: Inserted module 'overlay' Mar 20 17:59:53.894516 systemd[1]: Started systemd-journald.service - Journal Service. Mar 20 17:59:53.896456 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 20 17:59:53.897225 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 20 17:59:53.898657 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 20 17:59:53.911289 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 20 17:59:53.913089 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 17:59:53.916743 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 20 17:59:53.919653 kernel: Bridge firewalling registered Mar 20 17:59:53.918622 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 20 17:59:53.919643 systemd-modules-load[195]: Inserted module 'br_netfilter' Mar 20 17:59:53.920873 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 20 17:59:53.922412 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 20 17:59:53.936089 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 20 17:59:53.944736 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 20 17:59:53.946708 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 20 17:59:53.952480 dracut-cmdline[225]: dracut-dracut-053 Mar 20 17:59:53.957465 dracut-cmdline[225]: 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 17:59:53.992825 systemd-resolved[238]: Positive Trust Anchors: Mar 20 17:59:53.992839 systemd-resolved[238]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 20 17:59:53.992870 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 17:59:53.995294 systemd-resolved[238]: Defaulting to hostname 'linux'. Mar 20 17:59:53.996355 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 20 17:59:54.001751 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 20 17:59:54.049714 kernel: SCSI subsystem initialized Mar 20 17:59:54.058706 kernel: Loading iSCSI transport class v2.0-870. Mar 20 17:59:54.069710 kernel: iscsi: registered transport (tcp) Mar 20 17:59:54.090701 kernel: iscsi: registered transport (qla4xxx) Mar 20 17:59:54.090725 kernel: QLogic iSCSI HBA Driver Mar 20 17:59:54.133998 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 20 17:59:54.136176 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 20 17:59:54.182707 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 20 17:59:54.182735 kernel: device-mapper: uevent: version 1.0.3 Mar 20 17:59:54.184699 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 20 17:59:54.223695 kernel: raid6: avx2x4 gen() 30272 MB/s Mar 20 17:59:54.240691 kernel: raid6: avx2x2 gen() 30487 MB/s Mar 20 17:59:54.257775 kernel: raid6: avx2x1 gen() 25817 MB/s Mar 20 17:59:54.257796 kernel: raid6: using algorithm avx2x2 gen() 30487 MB/s Mar 20 17:59:54.275779 kernel: raid6: .... xor() 19949 MB/s, rmw enabled Mar 20 17:59:54.275794 kernel: raid6: using avx2x2 recovery algorithm Mar 20 17:59:54.295694 kernel: xor: automatically using best checksumming function avx Mar 20 17:59:54.440703 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 20 17:59:54.453572 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 20 17:59:54.455376 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 20 17:59:54.484661 systemd-udevd[417]: Using default interface naming scheme 'v255'. Mar 20 17:59:54.489889 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 20 17:59:54.492467 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 20 17:59:54.514793 dracut-pre-trigger[421]: rd.md=0: removing MD RAID activation Mar 20 17:59:54.546892 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 20 17:59:54.550444 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 20 17:59:54.624055 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 20 17:59:54.629830 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 20 17:59:54.653595 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 20 17:59:54.657204 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 20 17:59:54.660002 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 20 17:59:54.662472 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 20 17:59:54.667774 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 20 17:59:54.676752 kernel: cryptd: max_cpu_qlen set to 1000 Mar 20 17:59:54.676779 kernel: libata version 3.00 loaded. Mar 20 17:59:54.682697 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues Mar 20 17:59:54.704152 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) Mar 20 17:59:54.704308 kernel: ahci 0000:00:1f.2: version 3.0 Mar 20 17:59:54.739771 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Mar 20 17:59:54.739788 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Mar 20 17:59:54.739966 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Mar 20 17:59:54.740119 kernel: AVX2 version of gcm_enc/dec engaged. Mar 20 17:59:54.740131 kernel: AES CTR mode by8 optimization enabled Mar 20 17:59:54.740142 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Mar 20 17:59:54.740153 kernel: GPT:9289727 != 19775487 Mar 20 17:59:54.740164 kernel: GPT:Alternate GPT header not at the end of the disk. Mar 20 17:59:54.740174 kernel: GPT:9289727 != 19775487 Mar 20 17:59:54.740184 kernel: GPT: Use GNU Parted to correct GPT errors. Mar 20 17:59:54.740195 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 20 17:59:54.740209 kernel: scsi host0: ahci Mar 20 17:59:54.740370 kernel: scsi host1: ahci Mar 20 17:59:54.740527 kernel: scsi host2: ahci Mar 20 17:59:54.740697 kernel: scsi host3: ahci Mar 20 17:59:54.740851 kernel: scsi host4: ahci Mar 20 17:59:54.741011 kernel: scsi host5: ahci Mar 20 17:59:54.741165 kernel: BTRFS: device fsid 5af3bf9c-0d36-4793-88d6-028c3ca48c10 devid 1 transid 38 /dev/vda3 scanned by (udev-worker) (466) Mar 20 17:59:54.741177 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by (udev-worker) (467) Mar 20 17:59:54.741188 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 Mar 20 17:59:54.741199 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 Mar 20 17:59:54.741377 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 Mar 20 17:59:54.741396 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 Mar 20 17:59:54.741406 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 Mar 20 17:59:54.741417 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 Mar 20 17:59:54.690959 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 20 17:59:54.701774 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 20 17:59:54.703116 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 20 17:59:54.708304 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 20 17:59:54.709584 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 20 17:59:54.709886 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 17:59:54.711440 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 17:59:54.714397 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 17:59:54.753460 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Mar 20 17:59:54.755935 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 17:59:54.756531 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 17:59:54.768529 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 20 17:59:54.783196 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Mar 20 17:59:54.792075 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Mar 20 17:59:54.793263 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 20 17:59:54.796706 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 20 17:59:54.816876 disk-uuid[562]: Primary Header is updated. Mar 20 17:59:54.816876 disk-uuid[562]: Secondary Entries is updated. Mar 20 17:59:54.816876 disk-uuid[562]: Secondary Header is updated. Mar 20 17:59:54.820700 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 20 17:59:54.820782 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 20 17:59:55.041508 kernel: ata5: SATA link down (SStatus 0 SControl 300) Mar 20 17:59:55.041573 kernel: ata4: SATA link down (SStatus 0 SControl 300) Mar 20 17:59:55.041584 kernel: ata1: SATA link down (SStatus 0 SControl 300) Mar 20 17:59:55.041595 kernel: ata6: SATA link down (SStatus 0 SControl 300) Mar 20 17:59:55.042702 kernel: ata2: SATA link down (SStatus 0 SControl 300) Mar 20 17:59:55.043706 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Mar 20 17:59:55.044975 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 Mar 20 17:59:55.045018 kernel: ata3.00: applying bridge limits Mar 20 17:59:55.045029 kernel: ata3.00: configured for UDMA/100 Mar 20 17:59:55.047695 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 Mar 20 17:59:55.097259 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray Mar 20 17:59:55.109530 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 20 17:59:55.109550 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Mar 20 17:59:55.829710 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 20 17:59:55.830246 disk-uuid[570]: The operation has completed successfully. Mar 20 17:59:55.855666 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 20 17:59:55.855888 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 20 17:59:55.900848 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 20 17:59:55.917283 sh[595]: Success Mar 20 17:59:55.929745 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" Mar 20 17:59:55.967333 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 20 17:59:55.971962 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 20 17:59:55.988172 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 20 17:59:55.994667 kernel: BTRFS info (device dm-0): first mount of filesystem 5af3bf9c-0d36-4793-88d6-028c3ca48c10 Mar 20 17:59:55.994718 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Mar 20 17:59:55.994730 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 20 17:59:55.994740 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 20 17:59:55.996072 kernel: BTRFS info (device dm-0): using free space tree Mar 20 17:59:56.000566 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 20 17:59:56.003266 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 20 17:59:56.006360 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 20 17:59:56.009323 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 20 17:59:56.039882 kernel: BTRFS info (device vda6): first mount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 17:59:56.039942 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 20 17:59:56.039958 kernel: BTRFS info (device vda6): using free space tree Mar 20 17:59:56.042711 kernel: BTRFS info (device vda6): auto enabling async discard Mar 20 17:59:56.047700 kernel: BTRFS info (device vda6): last unmount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 17:59:56.053248 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 20 17:59:56.056567 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 20 17:59:56.115478 ignition[688]: Ignition 2.20.0 Mar 20 17:59:56.115489 ignition[688]: Stage: fetch-offline Mar 20 17:59:56.115525 ignition[688]: no configs at "/usr/lib/ignition/base.d" Mar 20 17:59:56.115535 ignition[688]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 17:59:56.115620 ignition[688]: parsed url from cmdline: "" Mar 20 17:59:56.115624 ignition[688]: no config URL provided Mar 20 17:59:56.115629 ignition[688]: reading system config file "/usr/lib/ignition/user.ign" Mar 20 17:59:56.115638 ignition[688]: no config at "/usr/lib/ignition/user.ign" Mar 20 17:59:56.115668 ignition[688]: op(1): [started] loading QEMU firmware config module Mar 20 17:59:56.115687 ignition[688]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 20 17:59:56.122986 ignition[688]: op(1): [finished] loading QEMU firmware config module Mar 20 17:59:56.124065 ignition[688]: parsing config with SHA512: 7e519623d2be4d4acd73418434adbb13e77e2a9c87e00212aa3cb0646ea229aa398dfd893e04668cf70734ee6fd792e0a8d005d75c4961de5597d47dcb8bbc28 Mar 20 17:59:56.127728 unknown[688]: fetched base config from "system" Mar 20 17:59:56.128729 unknown[688]: fetched user config from "qemu" Mar 20 17:59:56.129136 ignition[688]: fetch-offline: fetch-offline passed Mar 20 17:59:56.129249 ignition[688]: Ignition finished successfully Mar 20 17:59:56.131757 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 20 17:59:56.145015 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 20 17:59:56.146911 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 20 17:59:56.193592 systemd-networkd[783]: lo: Link UP Mar 20 17:59:56.193604 systemd-networkd[783]: lo: Gained carrier Mar 20 17:59:56.195258 systemd-networkd[783]: Enumeration completed Mar 20 17:59:56.195333 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 20 17:59:56.195614 systemd-networkd[783]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 20 17:59:56.195618 systemd-networkd[783]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 20 17:59:56.196547 systemd-networkd[783]: eth0: Link UP Mar 20 17:59:56.196551 systemd-networkd[783]: eth0: Gained carrier Mar 20 17:59:56.196557 systemd-networkd[783]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 20 17:59:56.196946 systemd[1]: Reached target network.target - Network. Mar 20 17:59:56.198466 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Mar 20 17:59:56.199217 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 20 17:59:56.220716 systemd-networkd[783]: eth0: DHCPv4 address 10.0.0.79/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 20 17:59:56.229875 ignition[786]: Ignition 2.20.0 Mar 20 17:59:56.229889 ignition[786]: Stage: kargs Mar 20 17:59:56.230091 ignition[786]: no configs at "/usr/lib/ignition/base.d" Mar 20 17:59:56.230106 ignition[786]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 17:59:56.230927 ignition[786]: kargs: kargs passed Mar 20 17:59:56.230983 ignition[786]: Ignition finished successfully Mar 20 17:59:56.234614 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 20 17:59:56.236825 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 20 17:59:56.265800 ignition[796]: Ignition 2.20.0 Mar 20 17:59:56.265814 ignition[796]: Stage: disks Mar 20 17:59:56.265984 ignition[796]: no configs at "/usr/lib/ignition/base.d" Mar 20 17:59:56.265995 ignition[796]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 17:59:56.269017 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 20 17:59:56.266615 ignition[796]: disks: disks passed Mar 20 17:59:56.271743 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 20 17:59:56.266659 ignition[796]: Ignition finished successfully Mar 20 17:59:56.274157 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 20 17:59:56.275508 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 20 17:59:56.275931 systemd[1]: Reached target sysinit.target - System Initialization. Mar 20 17:59:56.276298 systemd[1]: Reached target basic.target - Basic System. Mar 20 17:59:56.277345 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 20 17:59:56.303506 systemd-fsck[807]: ROOT: clean, 14/553520 files, 52654/553472 blocks Mar 20 17:59:56.310526 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 20 17:59:56.314657 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 20 17:59:56.418698 kernel: EXT4-fs (vda9): mounted filesystem bf9c440e-9fee-4e54-8539-b83f5a9eea2f r/w with ordered data mode. Quota mode: none. Mar 20 17:59:56.418883 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 20 17:59:56.419789 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 20 17:59:56.422875 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 20 17:59:56.425371 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 20 17:59:56.426468 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Mar 20 17:59:56.426507 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 20 17:59:56.426531 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 20 17:59:56.440762 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 20 17:59:56.444380 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 20 17:59:56.449936 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (815) Mar 20 17:59:56.449976 kernel: BTRFS info (device vda6): first mount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 17:59:56.449993 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 20 17:59:56.450007 kernel: BTRFS info (device vda6): using free space tree Mar 20 17:59:56.451707 kernel: BTRFS info (device vda6): auto enabling async discard Mar 20 17:59:56.453009 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 20 17:59:56.500119 initrd-setup-root[839]: cut: /sysroot/etc/passwd: No such file or directory Mar 20 17:59:56.505766 initrd-setup-root[846]: cut: /sysroot/etc/group: No such file or directory Mar 20 17:59:56.511033 initrd-setup-root[853]: cut: /sysroot/etc/shadow: No such file or directory Mar 20 17:59:56.514547 initrd-setup-root[860]: cut: /sysroot/etc/gshadow: No such file or directory Mar 20 17:59:56.603002 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 20 17:59:56.604272 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 20 17:59:56.607326 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 20 17:59:56.621714 kernel: BTRFS info (device vda6): last unmount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 17:59:56.635961 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 20 17:59:56.647501 ignition[929]: INFO : Ignition 2.20.0 Mar 20 17:59:56.647501 ignition[929]: INFO : Stage: mount Mar 20 17:59:56.649381 ignition[929]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 20 17:59:56.649381 ignition[929]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 17:59:56.649381 ignition[929]: INFO : mount: mount passed Mar 20 17:59:56.649381 ignition[929]: INFO : Ignition finished successfully Mar 20 17:59:56.653655 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 20 17:59:56.657240 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 20 17:59:56.993055 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 20 17:59:56.995551 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 20 17:59:57.015220 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/vda6 scanned by mount (941) Mar 20 17:59:57.015253 kernel: BTRFS info (device vda6): first mount of filesystem d877ba4c-bfdd-4ad4-94ef-51dbb6b505e4 Mar 20 17:59:57.015268 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Mar 20 17:59:57.016728 kernel: BTRFS info (device vda6): using free space tree Mar 20 17:59:57.019695 kernel: BTRFS info (device vda6): auto enabling async discard Mar 20 17:59:57.020851 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 20 17:59:57.045995 ignition[958]: INFO : Ignition 2.20.0 Mar 20 17:59:57.045995 ignition[958]: INFO : Stage: files Mar 20 17:59:57.047781 ignition[958]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 20 17:59:57.047781 ignition[958]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 17:59:57.050290 ignition[958]: DEBUG : files: compiled without relabeling support, skipping Mar 20 17:59:57.051653 ignition[958]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 20 17:59:57.051653 ignition[958]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 20 17:59:57.056831 ignition[958]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 20 17:59:57.058415 ignition[958]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 20 17:59:57.060151 unknown[958]: wrote ssh authorized keys file for user: core Mar 20 17:59:57.061360 ignition[958]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 20 17:59:57.062784 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Mar 20 17:59:57.062784 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Mar 20 17:59:57.062784 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 20 17:59:57.062784 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 20 17:59:57.062784 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw" Mar 20 17:59:57.062784 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw" Mar 20 17:59:57.062784 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw" Mar 20 17:59:57.062784 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.30.1-x86-64.raw: attempt #1 Mar 20 17:59:57.415343 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Mar 20 17:59:57.689809 systemd-networkd[783]: eth0: Gained IPv6LL Mar 20 17:59:57.842458 ignition[958]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.30.1-x86-64.raw" Mar 20 17:59:57.842458 ignition[958]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" Mar 20 17:59:57.846078 ignition[958]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 20 17:59:57.848141 ignition[958]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Mar 20 17:59:57.848141 ignition[958]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" Mar 20 17:59:57.848141 ignition[958]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Mar 20 17:59:57.864519 ignition[958]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Mar 20 17:59:57.868351 ignition[958]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Mar 20 17:59:57.869895 ignition[958]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Mar 20 17:59:57.869895 ignition[958]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 20 17:59:57.869895 ignition[958]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 20 17:59:57.869895 ignition[958]: INFO : files: files passed Mar 20 17:59:57.869895 ignition[958]: INFO : Ignition finished successfully Mar 20 17:59:57.871485 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 20 17:59:57.875196 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 20 17:59:57.877588 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 20 17:59:57.890522 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 20 17:59:57.890630 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 20 17:59:57.893820 initrd-setup-root-after-ignition[986]: grep: /sysroot/oem/oem-release: No such file or directory Mar 20 17:59:57.897251 initrd-setup-root-after-ignition[990]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 20 17:59:57.898893 initrd-setup-root-after-ignition[990]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 20 17:59:57.901077 initrd-setup-root-after-ignition[994]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 20 17:59:57.904095 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 20 17:59:57.904551 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 20 17:59:57.907592 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 20 17:59:57.952599 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 20 17:59:57.952773 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 20 17:59:57.953429 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 20 17:59:57.956287 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 20 17:59:57.956640 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 20 17:59:57.957370 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 20 17:59:57.976443 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 20 17:59:57.978114 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 20 17:59:58.001147 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 20 17:59:58.001635 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 20 17:59:58.002154 systemd[1]: Stopped target timers.target - Timer Units. Mar 20 17:59:58.002474 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 20 17:59:58.002572 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 20 17:59:58.007749 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 20 17:59:58.008247 systemd[1]: Stopped target basic.target - Basic System. Mar 20 17:59:58.008575 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 20 17:59:58.009078 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 20 17:59:58.009405 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 20 17:59:58.009752 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 20 17:59:58.010240 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 20 17:59:58.010578 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 20 17:59:58.011253 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 20 17:59:58.011569 systemd[1]: Stopped target swap.target - Swaps. Mar 20 17:59:58.012050 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 20 17:59:58.012153 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 20 17:59:58.012742 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 20 17:59:58.013248 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 20 17:59:58.013542 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 20 17:59:58.013647 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 20 17:59:58.014074 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 20 17:59:58.014182 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 20 17:59:58.037619 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 20 17:59:58.037747 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 20 17:59:58.038200 systemd[1]: Stopped target paths.target - Path Units. Mar 20 17:59:58.038451 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 20 17:59:58.039718 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 20 17:59:58.044101 systemd[1]: Stopped target slices.target - Slice Units. Mar 20 17:59:58.044421 systemd[1]: Stopped target sockets.target - Socket Units. Mar 20 17:59:58.044787 systemd[1]: iscsid.socket: Deactivated successfully. Mar 20 17:59:58.044880 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 20 17:59:58.045273 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 20 17:59:58.045350 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 20 17:59:58.051691 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 20 17:59:58.051799 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 20 17:59:58.053594 systemd[1]: ignition-files.service: Deactivated successfully. Mar 20 17:59:58.053710 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 20 17:59:58.056349 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 20 17:59:58.057740 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 20 17:59:58.057860 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 20 17:59:58.059004 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 20 17:59:58.059428 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 20 17:59:58.059528 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 20 17:59:58.060297 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 20 17:59:58.060394 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 20 17:59:58.070950 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 20 17:59:58.071055 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 20 17:59:58.088306 ignition[1015]: INFO : Ignition 2.20.0 Mar 20 17:59:58.088306 ignition[1015]: INFO : Stage: umount Mar 20 17:59:58.090016 ignition[1015]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 20 17:59:58.090016 ignition[1015]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Mar 20 17:59:58.090016 ignition[1015]: INFO : umount: umount passed Mar 20 17:59:58.090016 ignition[1015]: INFO : Ignition finished successfully Mar 20 17:59:58.092135 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 20 17:59:58.092748 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 20 17:59:58.092889 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 20 17:59:58.094487 systemd[1]: Stopped target network.target - Network. Mar 20 17:59:58.096093 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 20 17:59:58.096147 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 20 17:59:58.098055 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 20 17:59:58.098105 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 20 17:59:58.099981 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 20 17:59:58.100029 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 20 17:59:58.101863 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 20 17:59:58.101908 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 20 17:59:58.103776 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 20 17:59:58.105741 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 20 17:59:58.111299 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 20 17:59:58.111430 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 20 17:59:58.114597 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 20 17:59:58.115641 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 20 17:59:58.115782 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 20 17:59:58.118903 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 20 17:59:58.119201 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 20 17:59:58.119240 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 20 17:59:58.121765 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 20 17:59:58.123155 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 20 17:59:58.123209 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 20 17:59:58.125421 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 20 17:59:58.125468 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 20 17:59:58.127654 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 20 17:59:58.127717 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 20 17:59:58.129953 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 20 17:59:58.130000 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 20 17:59:58.132168 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 20 17:59:58.135310 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 20 17:59:58.135374 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 20 17:59:58.143625 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 20 17:59:58.143758 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 20 17:59:58.160539 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 20 17:59:58.160747 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 20 17:59:58.163086 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 20 17:59:58.163139 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 20 17:59:58.165068 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 20 17:59:58.165110 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 20 17:59:58.167083 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 20 17:59:58.167136 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 20 17:59:58.169244 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 20 17:59:58.169291 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 20 17:59:58.171401 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 20 17:59:58.171451 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 20 17:59:58.174203 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 20 17:59:58.175335 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 20 17:59:58.175389 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 20 17:59:58.177646 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 20 17:59:58.177714 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 17:59:58.180634 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 20 17:59:58.180715 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 20 17:59:58.196751 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 20 17:59:58.196873 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 20 17:59:58.247380 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 20 17:59:58.247510 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 20 17:59:58.249799 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 20 17:59:58.251171 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 20 17:59:58.251232 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 20 17:59:58.253951 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 20 17:59:58.275334 systemd[1]: Switching root. Mar 20 17:59:58.309804 systemd-journald[191]: Journal stopped Mar 20 17:59:59.403039 systemd-journald[191]: Received SIGTERM from PID 1 (systemd). Mar 20 17:59:59.403099 kernel: SELinux: policy capability network_peer_controls=1 Mar 20 17:59:59.403114 kernel: SELinux: policy capability open_perms=1 Mar 20 17:59:59.403125 kernel: SELinux: policy capability extended_socket_class=1 Mar 20 17:59:59.403137 kernel: SELinux: policy capability always_check_network=0 Mar 20 17:59:59.403151 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 20 17:59:59.403163 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 20 17:59:59.403174 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 20 17:59:59.403185 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 20 17:59:59.403197 kernel: audit: type=1403 audit(1742493598.631:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 20 17:59:59.403222 systemd[1]: Successfully loaded SELinux policy in 38.621ms. Mar 20 17:59:59.403236 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 12.858ms. Mar 20 17:59:59.403250 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 17:59:59.403262 systemd[1]: Detected virtualization kvm. Mar 20 17:59:59.403279 systemd[1]: Detected architecture x86-64. Mar 20 17:59:59.403292 systemd[1]: Detected first boot. Mar 20 17:59:59.403309 systemd[1]: Initializing machine ID from VM UUID. Mar 20 17:59:59.403323 zram_generator::config[1063]: No configuration found. Mar 20 17:59:59.403336 kernel: Guest personality initialized and is inactive Mar 20 17:59:59.403351 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Mar 20 17:59:59.403368 kernel: Initialized host personality Mar 20 17:59:59.403379 kernel: NET: Registered PF_VSOCK protocol family Mar 20 17:59:59.403395 systemd[1]: Populated /etc with preset unit settings. Mar 20 17:59:59.403408 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 20 17:59:59.403421 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 20 17:59:59.403433 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 20 17:59:59.403445 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 20 17:59:59.403460 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 20 17:59:59.403472 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 20 17:59:59.403484 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 20 17:59:59.403496 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 20 17:59:59.403508 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 20 17:59:59.403525 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 20 17:59:59.403538 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 20 17:59:59.403550 systemd[1]: Created slice user.slice - User and Session Slice. Mar 20 17:59:59.403562 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 20 17:59:59.403577 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 20 17:59:59.403589 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 20 17:59:59.403603 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 20 17:59:59.403616 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 20 17:59:59.403629 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 20 17:59:59.403641 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Mar 20 17:59:59.403653 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 20 17:59:59.403665 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 20 17:59:59.403697 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 20 17:59:59.403710 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 20 17:59:59.403722 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 20 17:59:59.403734 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 20 17:59:59.403747 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 20 17:59:59.403759 systemd[1]: Reached target slices.target - Slice Units. Mar 20 17:59:59.403771 systemd[1]: Reached target swap.target - Swaps. Mar 20 17:59:59.403787 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 20 17:59:59.403800 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 20 17:59:59.403823 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 20 17:59:59.403836 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 20 17:59:59.403848 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 20 17:59:59.403860 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 20 17:59:59.403872 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 20 17:59:59.403884 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 20 17:59:59.403898 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 20 17:59:59.403911 systemd[1]: Mounting media.mount - External Media Directory... Mar 20 17:59:59.403924 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 17:59:59.403939 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 20 17:59:59.403952 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 20 17:59:59.403965 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 20 17:59:59.403978 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 17:59:59.403990 systemd[1]: Reached target machines.target - Containers. Mar 20 17:59:59.404002 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 20 17:59:59.404015 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 20 17:59:59.404027 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 20 17:59:59.404042 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 20 17:59:59.404054 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 20 17:59:59.404066 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 20 17:59:59.404078 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 20 17:59:59.404091 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 20 17:59:59.404103 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 20 17:59:59.404116 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 20 17:59:59.404128 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 20 17:59:59.404142 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 20 17:59:59.404154 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 20 17:59:59.404168 systemd[1]: Stopped systemd-fsck-usr.service. Mar 20 17:59:59.404181 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 17:59:59.404193 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 20 17:59:59.404206 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 20 17:59:59.404217 kernel: fuse: init (API version 7.39) Mar 20 17:59:59.404229 kernel: loop: module loaded Mar 20 17:59:59.404241 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 20 17:59:59.404256 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 20 17:59:59.404272 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 20 17:59:59.404288 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 20 17:59:59.404314 systemd[1]: verity-setup.service: Deactivated successfully. Mar 20 17:59:59.404336 systemd[1]: Stopped verity-setup.service. Mar 20 17:59:59.404373 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 17:59:59.404395 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 20 17:59:59.404421 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 20 17:59:59.404444 systemd[1]: Mounted media.mount - External Media Directory. Mar 20 17:59:59.404463 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 20 17:59:59.404482 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 20 17:59:59.404553 systemd-journald[1141]: Collecting audit messages is disabled. Mar 20 17:59:59.404627 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 20 17:59:59.404654 systemd-journald[1141]: Journal started Mar 20 17:59:59.404706 systemd-journald[1141]: Runtime Journal (/run/log/journal/ca276d10d9da445ba49abca7e5cee1f4) is 6M, max 48.2M, 42.2M free. Mar 20 17:59:59.176006 systemd[1]: Queued start job for default target multi-user.target. Mar 20 17:59:59.193591 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Mar 20 17:59:59.194087 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 20 17:59:59.407330 kernel: ACPI: bus type drm_connector registered Mar 20 17:59:59.407360 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 20 17:59:59.410271 systemd[1]: Started systemd-journald.service - Journal Service. Mar 20 17:59:59.411417 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 20 17:59:59.412945 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 20 17:59:59.413156 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 20 17:59:59.414637 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 20 17:59:59.414875 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 20 17:59:59.416272 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 20 17:59:59.416481 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 20 17:59:59.417839 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 20 17:59:59.418049 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 20 17:59:59.419525 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 20 17:59:59.419744 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 20 17:59:59.421100 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 20 17:59:59.421308 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 20 17:59:59.422817 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 20 17:59:59.424222 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 20 17:59:59.426040 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 20 17:59:59.427696 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 20 17:59:59.443737 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 20 17:59:59.446405 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 20 17:59:59.448646 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 20 17:59:59.450014 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 20 17:59:59.450046 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 20 17:59:59.452034 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 20 17:59:59.461713 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 20 17:59:59.465122 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 20 17:59:59.466279 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 20 17:59:59.468874 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 20 17:59:59.471014 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 20 17:59:59.472258 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 20 17:59:59.475917 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 20 17:59:59.477199 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 20 17:59:59.484647 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 20 17:59:59.489457 systemd-journald[1141]: Time spent on flushing to /var/log/journal/ca276d10d9da445ba49abca7e5cee1f4 is 39.739ms for 1033 entries. Mar 20 17:59:59.489457 systemd-journald[1141]: System Journal (/var/log/journal/ca276d10d9da445ba49abca7e5cee1f4) is 8M, max 195.6M, 187.6M free. Mar 20 17:59:59.589645 systemd-journald[1141]: Received client request to flush runtime journal. Mar 20 17:59:59.589720 kernel: loop0: detected capacity change from 0 to 109808 Mar 20 17:59:59.589747 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 20 17:59:59.488460 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 20 17:59:59.492017 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 20 17:59:59.497526 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 20 17:59:59.499566 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 20 17:59:59.504177 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 20 17:59:59.505819 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 20 17:59:59.510856 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 20 17:59:59.515141 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 20 17:59:59.520496 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 20 17:59:59.529511 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 20 17:59:59.591530 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 20 17:59:59.595734 udevadm[1193]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Mar 20 17:59:59.602247 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 20 17:59:59.612599 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 20 17:59:59.614101 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 20 17:59:59.618384 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 20 17:59:59.628899 kernel: loop1: detected capacity change from 0 to 210664 Mar 20 17:59:59.650927 systemd-tmpfiles[1202]: ACLs are not supported, ignoring. Mar 20 17:59:59.650946 systemd-tmpfiles[1202]: ACLs are not supported, ignoring. Mar 20 17:59:59.658778 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 20 17:59:59.669698 kernel: loop2: detected capacity change from 0 to 151640 Mar 20 17:59:59.744702 kernel: loop3: detected capacity change from 0 to 109808 Mar 20 17:59:59.753697 kernel: loop4: detected capacity change from 0 to 210664 Mar 20 17:59:59.764736 kernel: loop5: detected capacity change from 0 to 151640 Mar 20 17:59:59.780733 (sd-merge)[1207]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. Mar 20 17:59:59.781588 (sd-merge)[1207]: Merged extensions into '/usr'. Mar 20 17:59:59.787459 systemd[1]: Reload requested from client PID 1183 ('systemd-sysext') (unit systemd-sysext.service)... Mar 20 17:59:59.787475 systemd[1]: Reloading... Mar 20 17:59:59.878737 zram_generator::config[1235]: No configuration found. Mar 20 17:59:59.955888 ldconfig[1178]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 20 18:00:00.009362 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:00:00.075180 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 20 18:00:00.075759 systemd[1]: Reloading finished in 287 ms. Mar 20 18:00:00.102541 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 20 18:00:00.105654 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 20 18:00:00.121107 systemd[1]: Starting ensure-sysext.service... Mar 20 18:00:00.122938 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 20 18:00:00.135822 systemd[1]: Reload requested from client PID 1272 ('systemctl') (unit ensure-sysext.service)... Mar 20 18:00:00.135840 systemd[1]: Reloading... Mar 20 18:00:00.154896 systemd-tmpfiles[1273]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 20 18:00:00.155180 systemd-tmpfiles[1273]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 20 18:00:00.156212 systemd-tmpfiles[1273]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 20 18:00:00.156488 systemd-tmpfiles[1273]: ACLs are not supported, ignoring. Mar 20 18:00:00.156567 systemd-tmpfiles[1273]: ACLs are not supported, ignoring. Mar 20 18:00:00.160394 systemd-tmpfiles[1273]: Detected autofs mount point /boot during canonicalization of boot. Mar 20 18:00:00.160407 systemd-tmpfiles[1273]: Skipping /boot Mar 20 18:00:00.176551 systemd-tmpfiles[1273]: Detected autofs mount point /boot during canonicalization of boot. Mar 20 18:00:00.177932 systemd-tmpfiles[1273]: Skipping /boot Mar 20 18:00:00.227690 zram_generator::config[1308]: No configuration found. Mar 20 18:00:00.329384 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:00:00.393541 systemd[1]: Reloading finished in 257 ms. Mar 20 18:00:00.408431 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 20 18:00:00.425467 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 20 18:00:00.434409 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 20 18:00:00.436813 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 20 18:00:00.445454 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 20 18:00:00.449407 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 20 18:00:00.455085 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 20 18:00:00.458802 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 20 18:00:00.474972 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 20 18:00:00.480178 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 20 18:00:00.482284 systemd[1]: Finished ensure-sysext.service. Mar 20 18:00:00.487196 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Mar 20 18:00:00.487379 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 20 18:00:00.489378 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 20 18:00:00.492488 systemd-udevd[1349]: Using default interface naming scheme 'v255'. Mar 20 18:00:00.492737 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 20 18:00:00.495710 augenrules[1370]: No rules Mar 20 18:00:00.495857 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 20 18:00:00.501907 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 20 18:00:00.503158 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 20 18:00:00.503200 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:00:00.505056 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Mar 20 18:00:00.507414 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 20 18:00:00.510059 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 20 18:00:00.512189 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:00:00.513052 systemd[1]: audit-rules.service: Deactivated successfully. Mar 20 18:00:00.513309 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 20 18:00:00.514783 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 20 18:00:00.515012 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 20 18:00:00.516598 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 20 18:00:00.516853 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 20 18:00:00.518420 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 20 18:00:00.525858 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 20 18:00:00.527429 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 20 18:00:00.529135 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 20 18:00:00.531086 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 20 18:00:00.531310 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 20 18:00:00.532931 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 20 18:00:00.548582 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 20 18:00:00.550047 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 20 18:00:00.550117 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 20 18:00:00.550139 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:00:00.570446 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Mar 20 18:00:00.570816 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 20 18:00:00.644888 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1386) Mar 20 18:00:00.676701 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Mar 20 18:00:00.691831 kernel: ACPI: button: Power Button [PWRF] Mar 20 18:00:00.704919 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Mar 20 18:00:00.707405 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 20 18:00:00.718697 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Mar 20 18:00:00.730916 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 20 18:00:00.731893 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device Mar 20 18:00:00.739132 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Mar 20 18:00:00.740613 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Mar 20 18:00:00.740855 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Mar 20 18:00:00.734832 systemd-resolved[1345]: Positive Trust Anchors: Mar 20 18:00:00.734841 systemd-resolved[1345]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 20 18:00:00.734873 systemd-resolved[1345]: 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:00:00.736281 systemd-networkd[1407]: lo: Link UP Mar 20 18:00:00.736286 systemd-networkd[1407]: lo: Gained carrier Mar 20 18:00:00.736377 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Mar 20 18:00:00.737776 systemd[1]: Reached target time-set.target - System Time Set. Mar 20 18:00:00.738070 systemd-networkd[1407]: Enumeration completed Mar 20 18:00:00.738308 systemd-resolved[1345]: Defaulting to hostname 'linux'. Mar 20 18:00:00.738415 systemd-networkd[1407]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 20 18:00:00.738419 systemd-networkd[1407]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 20 18:00:00.739030 systemd-networkd[1407]: eth0: Link UP Mar 20 18:00:00.739035 systemd-networkd[1407]: eth0: Gained carrier Mar 20 18:00:00.739049 systemd-networkd[1407]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 20 18:00:00.739551 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 20 18:00:00.740965 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 20 18:00:00.742309 systemd[1]: Reached target network.target - Network. Mar 20 18:00:00.743322 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 20 18:00:00.747805 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 20 18:00:00.750101 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 20 18:00:00.750774 systemd-networkd[1407]: eth0: DHCPv4 address 10.0.0.79/16, gateway 10.0.0.1 acquired from 10.0.0.1 Mar 20 18:00:00.751787 systemd-timesyncd[1378]: Network configuration changed, trying to establish connection. Mar 20 18:00:02.213411 systemd-timesyncd[1378]: Contacted time server 10.0.0.1:123 (10.0.0.1). Mar 20 18:00:02.213459 systemd-timesyncd[1378]: Initial clock synchronization to Thu 2025-03-20 18:00:02.213334 UTC. Mar 20 18:00:02.214174 systemd-resolved[1345]: Clock change detected. Flushing caches. Mar 20 18:00:02.225216 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 18:00:02.240220 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 20 18:00:02.240698 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 18:00:02.248798 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 20 18:00:02.261980 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 20 18:00:02.317904 kernel: mousedev: PS/2 mouse device common for all mice Mar 20 18:00:02.318472 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 20 18:00:02.328393 kernel: kvm_amd: TSC scaling supported Mar 20 18:00:02.328469 kernel: kvm_amd: Nested Virtualization enabled Mar 20 18:00:02.328492 kernel: kvm_amd: Nested Paging enabled Mar 20 18:00:02.329376 kernel: kvm_amd: LBR virtualization supported Mar 20 18:00:02.329417 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported Mar 20 18:00:02.329923 kernel: kvm_amd: Virtual GIF supported Mar 20 18:00:02.346916 kernel: EDAC MC: Ver: 3.0.0 Mar 20 18:00:02.381153 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 20 18:00:02.384160 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 20 18:00:02.405656 lvm[1446]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 20 18:00:02.442096 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 20 18:00:02.443630 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 20 18:00:02.444769 systemd[1]: Reached target sysinit.target - System Initialization. Mar 20 18:00:02.445947 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 20 18:00:02.447223 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:00:02.448658 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 20 18:00:02.449844 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 20 18:00:02.451120 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 20 18:00:02.452361 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 20 18:00:02.452385 systemd[1]: Reached target paths.target - Path Units. Mar 20 18:00:02.453301 systemd[1]: Reached target timers.target - Timer Units. Mar 20 18:00:02.455245 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 20 18:00:02.457930 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 20 18:00:02.461288 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 20 18:00:02.462707 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 20 18:00:02.464000 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 20 18:00:02.474382 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 20 18:00:02.475897 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 20 18:00:02.478318 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 20 18:00:02.479985 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 20 18:00:02.481139 systemd[1]: Reached target sockets.target - Socket Units. Mar 20 18:00:02.482101 systemd[1]: Reached target basic.target - Basic System. Mar 20 18:00:02.483077 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 20 18:00:02.483107 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 20 18:00:02.484038 systemd[1]: Starting containerd.service - containerd container runtime... Mar 20 18:00:02.486033 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 20 18:00:02.490605 lvm[1450]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 20 18:00:02.491039 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 20 18:00:02.493340 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 20 18:00:02.494504 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:00:02.497070 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 20 18:00:02.497452 jq[1453]: false Mar 20 18:00:02.502095 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 20 18:00:02.504542 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 20 18:00:02.510367 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 20 18:00:02.512682 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 20 18:00:02.514337 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 20 18:00:02.515192 systemd[1]: Starting update-engine.service - Update Engine... Mar 20 18:00:02.519498 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 20 18:00:02.520668 extend-filesystems[1454]: Found loop3 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found loop4 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found loop5 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found sr0 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found vda Mar 20 18:00:02.530291 extend-filesystems[1454]: Found vda1 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found vda2 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found vda3 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found usr Mar 20 18:00:02.530291 extend-filesystems[1454]: Found vda4 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found vda6 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found vda7 Mar 20 18:00:02.530291 extend-filesystems[1454]: Found vda9 Mar 20 18:00:02.530291 extend-filesystems[1454]: Checking size of /dev/vda9 Mar 20 18:00:02.581658 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks Mar 20 18:00:02.581697 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1406) Mar 20 18:00:02.525099 dbus-daemon[1452]: [system] SELinux support is enabled Mar 20 18:00:02.527063 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 20 18:00:02.582251 extend-filesystems[1454]: Resized partition /dev/vda9 Mar 20 18:00:02.534922 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 20 18:00:02.583473 extend-filesystems[1475]: resize2fs 1.47.2 (1-Jan-2025) Mar 20 18:00:02.537925 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 20 18:00:02.584640 jq[1467]: true Mar 20 18:00:02.538177 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 20 18:00:02.538496 systemd[1]: motdgen.service: Deactivated successfully. Mar 20 18:00:02.585918 update_engine[1465]: I20250320 18:00:02.548763 1465 main.cc:92] Flatcar Update Engine starting Mar 20 18:00:02.585918 update_engine[1465]: I20250320 18:00:02.550257 1465 update_check_scheduler.cc:74] Next update check in 10m31s Mar 20 18:00:02.538729 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 20 18:00:02.541461 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 20 18:00:02.586315 jq[1476]: true Mar 20 18:00:02.541700 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 20 18:00:02.558693 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:00:02.558716 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 20 18:00:02.565728 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:00:02.565745 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 20 18:00:02.566366 (ntainerd)[1480]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 20 18:00:02.584630 systemd[1]: Started update-engine.service - Update Engine. Mar 20 18:00:02.608907 kernel: EXT4-fs (vda9): resized filesystem to 1864699 Mar 20 18:00:02.619124 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 20 18:00:02.648171 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 20 18:00:02.648958 extend-filesystems[1475]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Mar 20 18:00:02.648958 extend-filesystems[1475]: old_desc_blocks = 1, new_desc_blocks = 1 Mar 20 18:00:02.648958 extend-filesystems[1475]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. Mar 20 18:00:02.648437 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 20 18:00:02.654841 extend-filesystems[1454]: Resized filesystem in /dev/vda9 Mar 20 18:00:02.657235 systemd-logind[1460]: Watching system buttons on /dev/input/event1 (Power Button) Mar 20 18:00:02.657279 systemd-logind[1460]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 20 18:00:02.660287 systemd-logind[1460]: New seat seat0. Mar 20 18:00:02.669522 systemd[1]: Started systemd-logind.service - User Login Management. Mar 20 18:00:02.678317 bash[1502]: Updated "/home/core/.ssh/authorized_keys" Mar 20 18:00:02.680861 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 20 18:00:02.682077 locksmithd[1488]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 20 18:00:02.683325 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 20 18:00:02.694448 sshd_keygen[1470]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 20 18:00:02.724689 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 20 18:00:02.728493 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 20 18:00:02.804155 systemd[1]: issuegen.service: Deactivated successfully. Mar 20 18:00:02.804428 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 20 18:00:02.807188 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 20 18:00:02.836040 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 20 18:00:02.840104 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 20 18:00:02.842450 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Mar 20 18:00:02.843867 systemd[1]: Reached target getty.target - Login Prompts. Mar 20 18:00:03.017346 containerd[1480]: time="2025-03-20T18:00:03Z" 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:00:03.019104 containerd[1480]: time="2025-03-20T18:00:03.019060996Z" level=info msg="starting containerd" revision=88aa2f531d6c2922003cc7929e51daf1c14caa0a version=v2.0.1 Mar 20 18:00:03.031213 containerd[1480]: time="2025-03-20T18:00:03.031139778Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="47.69µs" Mar 20 18:00:03.031265 containerd[1480]: time="2025-03-20T18:00:03.031207134Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Mar 20 18:00:03.031265 containerd[1480]: time="2025-03-20T18:00:03.031237501Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Mar 20 18:00:03.031507 containerd[1480]: time="2025-03-20T18:00:03.031474586Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Mar 20 18:00:03.031507 containerd[1480]: time="2025-03-20T18:00:03.031496978Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Mar 20 18:00:03.031578 containerd[1480]: time="2025-03-20T18:00:03.031522916Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Mar 20 18:00:03.031625 containerd[1480]: time="2025-03-20T18:00:03.031604018Z" 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:00:03.031625 containerd[1480]: time="2025-03-20T18:00:03.031619337Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Mar 20 18:00:03.031971 containerd[1480]: time="2025-03-20T18:00:03.031934869Z" 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:00:03.031971 containerd[1480]: time="2025-03-20T18:00:03.031959044Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Mar 20 18:00:03.031971 containerd[1480]: time="2025-03-20T18:00:03.031970205Z" 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:00:03.032159 containerd[1480]: time="2025-03-20T18:00:03.031978541Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Mar 20 18:00:03.032159 containerd[1480]: time="2025-03-20T18:00:03.032080101Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Mar 20 18:00:03.032399 containerd[1480]: time="2025-03-20T18:00:03.032371818Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Mar 20 18:00:03.032423 containerd[1480]: time="2025-03-20T18:00:03.032406123Z" 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:00:03.032423 containerd[1480]: time="2025-03-20T18:00:03.032416242Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Mar 20 18:00:03.032461 containerd[1480]: time="2025-03-20T18:00:03.032442130Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Mar 20 18:00:03.032675 containerd[1480]: time="2025-03-20T18:00:03.032650571Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Mar 20 18:00:03.032742 containerd[1480]: time="2025-03-20T18:00:03.032725061Z" level=info msg="metadata content store policy set" policy=shared Mar 20 18:00:03.037813 containerd[1480]: time="2025-03-20T18:00:03.037778508Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Mar 20 18:00:03.037852 containerd[1480]: time="2025-03-20T18:00:03.037817852Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Mar 20 18:00:03.037852 containerd[1480]: time="2025-03-20T18:00:03.037830435Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Mar 20 18:00:03.037852 containerd[1480]: time="2025-03-20T18:00:03.037842007Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Mar 20 18:00:03.037924 containerd[1480]: time="2025-03-20T18:00:03.037856825Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Mar 20 18:00:03.037924 containerd[1480]: time="2025-03-20T18:00:03.037871031Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Mar 20 18:00:03.037924 containerd[1480]: time="2025-03-20T18:00:03.037883004Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Mar 20 18:00:03.037924 containerd[1480]: time="2025-03-20T18:00:03.037909844Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Mar 20 18:00:03.038014 containerd[1480]: time="2025-03-20T18:00:03.037958565Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Mar 20 18:00:03.038014 containerd[1480]: time="2025-03-20T18:00:03.037971770Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Mar 20 18:00:03.038014 containerd[1480]: time="2025-03-20T18:00:03.037980577Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Mar 20 18:00:03.038014 containerd[1480]: time="2025-03-20T18:00:03.037991106Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Mar 20 18:00:03.038129 containerd[1480]: time="2025-03-20T18:00:03.038103738Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Mar 20 18:00:03.038129 containerd[1480]: time="2025-03-20T18:00:03.038126230Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Mar 20 18:00:03.038172 containerd[1480]: time="2025-03-20T18:00:03.038137251Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Mar 20 18:00:03.038172 containerd[1480]: time="2025-03-20T18:00:03.038146949Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Mar 20 18:00:03.038213 containerd[1480]: time="2025-03-20T18:00:03.038171785Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Mar 20 18:00:03.038213 containerd[1480]: time="2025-03-20T18:00:03.038184459Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Mar 20 18:00:03.038213 containerd[1480]: time="2025-03-20T18:00:03.038195670Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Mar 20 18:00:03.038213 containerd[1480]: time="2025-03-20T18:00:03.038205779Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Mar 20 18:00:03.038291 containerd[1480]: time="2025-03-20T18:00:03.038216078Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Mar 20 18:00:03.038291 containerd[1480]: time="2025-03-20T18:00:03.038226849Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Mar 20 18:00:03.038291 containerd[1480]: time="2025-03-20T18:00:03.038237118Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Mar 20 18:00:03.038347 containerd[1480]: time="2025-03-20T18:00:03.038317148Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Mar 20 18:00:03.038347 containerd[1480]: time="2025-03-20T18:00:03.038332016Z" level=info msg="Start snapshots syncer" Mar 20 18:00:03.038382 containerd[1480]: time="2025-03-20T18:00:03.038354518Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Mar 20 18:00:03.038637 containerd[1480]: time="2025-03-20T18:00:03.038599097Z" 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:00:03.038792 containerd[1480]: time="2025-03-20T18:00:03.038651174Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Mar 20 18:00:03.038792 containerd[1480]: time="2025-03-20T18:00:03.038714914Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Mar 20 18:00:03.038835 containerd[1480]: time="2025-03-20T18:00:03.038812317Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Mar 20 18:00:03.038835 containerd[1480]: time="2025-03-20T18:00:03.038831022Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Mar 20 18:00:03.038870 containerd[1480]: time="2025-03-20T18:00:03.038841411Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Mar 20 18:00:03.038870 containerd[1480]: time="2025-03-20T18:00:03.038851550Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Mar 20 18:00:03.038870 containerd[1480]: time="2025-03-20T18:00:03.038863943Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Mar 20 18:00:03.038946 containerd[1480]: time="2025-03-20T18:00:03.038875174Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Mar 20 18:00:03.038946 containerd[1480]: time="2025-03-20T18:00:03.038900642Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Mar 20 18:00:03.038946 containerd[1480]: time="2025-03-20T18:00:03.038922072Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Mar 20 18:00:03.038946 containerd[1480]: time="2025-03-20T18:00:03.038932893Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Mar 20 18:00:03.039023 containerd[1480]: time="2025-03-20T18:00:03.038941749Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Mar 20 18:00:03.039023 containerd[1480]: time="2025-03-20T18:00:03.039012071Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Mar 20 18:00:03.039064 containerd[1480]: time="2025-03-20T18:00:03.039025406Z" 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:00:03.039064 containerd[1480]: time="2025-03-20T18:00:03.039033982Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Mar 20 18:00:03.039064 containerd[1480]: time="2025-03-20T18:00:03.039043941Z" 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:00:03.039064 containerd[1480]: time="2025-03-20T18:00:03.039051645Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Mar 20 18:00:03.039064 containerd[1480]: time="2025-03-20T18:00:03.039061664Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Mar 20 18:00:03.039154 containerd[1480]: time="2025-03-20T18:00:03.039071673Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Mar 20 18:00:03.039154 containerd[1480]: time="2025-03-20T18:00:03.039083846Z" level=info msg="runtime interface created" Mar 20 18:00:03.039154 containerd[1480]: time="2025-03-20T18:00:03.039089436Z" level=info msg="created NRI interface" Mar 20 18:00:03.039154 containerd[1480]: time="2025-03-20T18:00:03.039097672Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Mar 20 18:00:03.039154 containerd[1480]: time="2025-03-20T18:00:03.039110816Z" level=info msg="Connect containerd service" Mar 20 18:00:03.039154 containerd[1480]: time="2025-03-20T18:00:03.039134601Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 20 18:00:03.039870 containerd[1480]: time="2025-03-20T18:00:03.039840685Z" 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:00:03.180369 containerd[1480]: time="2025-03-20T18:00:03.180314574Z" level=info msg="Start subscribing containerd event" Mar 20 18:00:03.180487 containerd[1480]: time="2025-03-20T18:00:03.180390446Z" level=info msg="Start recovering state" Mar 20 18:00:03.180574 containerd[1480]: time="2025-03-20T18:00:03.180549194Z" level=info msg="Start event monitor" Mar 20 18:00:03.180610 containerd[1480]: time="2025-03-20T18:00:03.180582917Z" level=info msg="Start cni network conf syncer for default" Mar 20 18:00:03.180610 containerd[1480]: time="2025-03-20T18:00:03.180594950Z" level=info msg="Start streaming server" Mar 20 18:00:03.180658 containerd[1480]: time="2025-03-20T18:00:03.180552350Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 20 18:00:03.180715 containerd[1480]: time="2025-03-20T18:00:03.180618975Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Mar 20 18:00:03.180715 containerd[1480]: time="2025-03-20T18:00:03.180712690Z" level=info msg="runtime interface starting up..." Mar 20 18:00:03.180799 containerd[1480]: time="2025-03-20T18:00:03.180722348Z" level=info msg="starting plugins..." Mar 20 18:00:03.180799 containerd[1480]: time="2025-03-20T18:00:03.180714193Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 20 18:00:03.180799 containerd[1480]: time="2025-03-20T18:00:03.180747816Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Mar 20 18:00:03.180930 containerd[1480]: time="2025-03-20T18:00:03.180912004Z" level=info msg="containerd successfully booted in 0.164210s" Mar 20 18:00:03.181014 systemd[1]: Started containerd.service - containerd container runtime. Mar 20 18:00:03.566094 systemd-networkd[1407]: eth0: Gained IPv6LL Mar 20 18:00:03.569737 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 20 18:00:03.571780 systemd[1]: Reached target network-online.target - Network is Online. Mar 20 18:00:03.574808 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 20 18:00:03.577640 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 20 18:00:03.593195 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 20 18:00:03.612951 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 20 18:00:03.613329 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 20 18:00:03.615151 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 20 18:00:03.625679 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 20 18:00:04.979717 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 20 18:00:04.981530 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 20 18:00:04.983053 systemd[1]: Startup finished in 703ms (kernel) + 4.931s (initrd) + 4.928s (userspace) = 10.563s. Mar 20 18:00:04.998399 (kubelet)[1570]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Mar 20 18:00:05.815262 kubelet[1570]: E0320 18:00:05.815207 1570 run.go:74] "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:00:05.819934 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Mar 20 18:00:05.820128 systemd[1]: kubelet.service: Failed with result 'exit-code'. Mar 20 18:00:05.820485 systemd[1]: kubelet.service: Consumed 2.093s CPU time, 246.4M memory peak. Mar 20 18:00:08.668247 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 20 18:00:08.669524 systemd[1]: Started sshd@0-10.0.0.79:22-10.0.0.1:50030.service - OpenSSH per-connection server daemon (10.0.0.1:50030). Mar 20 18:00:08.726663 sshd[1585]: Accepted publickey for core from 10.0.0.1 port 50030 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:00:08.728974 sshd-session[1585]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:00:08.741413 systemd-logind[1460]: New session 1 of user core. Mar 20 18:00:08.742700 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 20 18:00:08.744189 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 20 18:00:08.770824 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 20 18:00:08.773698 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 20 18:00:08.792374 (systemd)[1589]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 20 18:00:08.795367 systemd-logind[1460]: New session c1 of user core. Mar 20 18:00:08.945637 systemd[1589]: Queued start job for default target default.target. Mar 20 18:00:08.955418 systemd[1589]: Created slice app.slice - User Application Slice. Mar 20 18:00:08.955454 systemd[1589]: Reached target paths.target - Paths. Mar 20 18:00:08.955509 systemd[1589]: Reached target timers.target - Timers. Mar 20 18:00:08.957206 systemd[1589]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 20 18:00:08.968603 systemd[1589]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 20 18:00:08.968764 systemd[1589]: Reached target sockets.target - Sockets. Mar 20 18:00:08.968832 systemd[1589]: Reached target basic.target - Basic System. Mar 20 18:00:08.968911 systemd[1589]: Reached target default.target - Main User Target. Mar 20 18:00:08.968955 systemd[1589]: Startup finished in 165ms. Mar 20 18:00:08.969343 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 20 18:00:08.971419 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 20 18:00:09.039710 systemd[1]: Started sshd@1-10.0.0.79:22-10.0.0.1:50042.service - OpenSSH per-connection server daemon (10.0.0.1:50042). Mar 20 18:00:09.092295 sshd[1600]: Accepted publickey for core from 10.0.0.1 port 50042 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:00:09.093627 sshd-session[1600]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:00:09.097547 systemd-logind[1460]: New session 2 of user core. Mar 20 18:00:09.107012 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 20 18:00:09.159719 sshd[1602]: Connection closed by 10.0.0.1 port 50042 Mar 20 18:00:09.160028 sshd-session[1600]: pam_unix(sshd:session): session closed for user core Mar 20 18:00:09.170556 systemd[1]: sshd@1-10.0.0.79:22-10.0.0.1:50042.service: Deactivated successfully. Mar 20 18:00:09.172243 systemd[1]: session-2.scope: Deactivated successfully. Mar 20 18:00:09.173618 systemd-logind[1460]: Session 2 logged out. Waiting for processes to exit. Mar 20 18:00:09.174950 systemd[1]: Started sshd@2-10.0.0.79:22-10.0.0.1:50044.service - OpenSSH per-connection server daemon (10.0.0.1:50044). Mar 20 18:00:09.175704 systemd-logind[1460]: Removed session 2. Mar 20 18:00:09.222012 sshd[1607]: Accepted publickey for core from 10.0.0.1 port 50044 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:00:09.223213 sshd-session[1607]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:00:09.227349 systemd-logind[1460]: New session 3 of user core. Mar 20 18:00:09.237009 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 20 18:00:09.285923 sshd[1610]: Connection closed by 10.0.0.1 port 50044 Mar 20 18:00:09.286277 sshd-session[1607]: pam_unix(sshd:session): session closed for user core Mar 20 18:00:09.297423 systemd[1]: sshd@2-10.0.0.79:22-10.0.0.1:50044.service: Deactivated successfully. Mar 20 18:00:09.299050 systemd[1]: session-3.scope: Deactivated successfully. Mar 20 18:00:09.300765 systemd-logind[1460]: Session 3 logged out. Waiting for processes to exit. Mar 20 18:00:09.302190 systemd[1]: Started sshd@3-10.0.0.79:22-10.0.0.1:50048.service - OpenSSH per-connection server daemon (10.0.0.1:50048). Mar 20 18:00:09.303051 systemd-logind[1460]: Removed session 3. Mar 20 18:00:09.344783 sshd[1615]: Accepted publickey for core from 10.0.0.1 port 50048 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:00:09.346079 sshd-session[1615]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:00:09.350189 systemd-logind[1460]: New session 4 of user core. Mar 20 18:00:09.360004 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 20 18:00:09.413625 sshd[1618]: Connection closed by 10.0.0.1 port 50048 Mar 20 18:00:09.413908 sshd-session[1615]: pam_unix(sshd:session): session closed for user core Mar 20 18:00:09.428516 systemd[1]: sshd@3-10.0.0.79:22-10.0.0.1:50048.service: Deactivated successfully. Mar 20 18:00:09.430333 systemd[1]: session-4.scope: Deactivated successfully. Mar 20 18:00:09.431598 systemd-logind[1460]: Session 4 logged out. Waiting for processes to exit. Mar 20 18:00:09.432811 systemd[1]: Started sshd@4-10.0.0.79:22-10.0.0.1:50058.service - OpenSSH per-connection server daemon (10.0.0.1:50058). Mar 20 18:00:09.433473 systemd-logind[1460]: Removed session 4. Mar 20 18:00:09.489022 sshd[1623]: Accepted publickey for core from 10.0.0.1 port 50058 ssh2: RSA SHA256:ETYqVjpewoN1zuqu9nZJodzFhOQ8IYamyUm19O815ZM Mar 20 18:00:09.490403 sshd-session[1623]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 20 18:00:09.494646 systemd-logind[1460]: New session 5 of user core. Mar 20 18:00:09.504019 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 20 18:00:09.563362 sudo[1627]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Mar 20 18:00:09.563790 sudo[1627]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 20 18:00:09.577278 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Mar 20 18:00:09.614990 systemd[1]: coreos-metadata.service: Deactivated successfully. Mar 20 18:00:09.615292 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Mar 20 18:00:10.421683 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 20 18:00:10.421945 systemd[1]: kubelet.service: Consumed 2.093s CPU time, 246.4M memory peak. Mar 20 18:00:10.424511 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 20 18:00:10.465981 systemd[1]: Reload requested from client PID 1677 ('systemctl') (unit session-5.scope)... Mar 20 18:00:10.466000 systemd[1]: Reloading... Mar 20 18:00:10.559910 zram_generator::config[1722]: No configuration found. Mar 20 18:00:11.692314 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:00:11.791707 systemd[1]: Reloading finished in 1325 ms. Mar 20 18:00:11.852021 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Mar 20 18:00:11.853862 systemd[1]: kubelet.service: Deactivated successfully. Mar 20 18:00:11.854164 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Mar 20 18:00:11.854201 systemd[1]: kubelet.service: Consumed 183ms CPU time, 83.6M memory peak. Mar 20 18:00:11.855702 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Mar 20 18:00:12.036702 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Mar 20 18:00:12.042116 (kubelet)[1769]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Mar 20 18:00:12.083614 kubelet[1769]: 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:00:12.084049 kubelet[1769]: Flag --pod-infra-container-image has been deprecated, will be removed in a future release. Image garbage collector will get sandbox image information from CRI. Mar 20 18:00:12.084049 kubelet[1769]: 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:00:12.084925 kubelet[1769]: I0320 18:00:12.084873 1769 server.go:205] "--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:00:12.379196 kubelet[1769]: I0320 18:00:12.379061 1769 server.go:484] "Kubelet version" kubeletVersion="v1.30.1" Mar 20 18:00:12.379196 kubelet[1769]: I0320 18:00:12.379096 1769 server.go:486] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Mar 20 18:00:12.379365 kubelet[1769]: I0320 18:00:12.379344 1769 server.go:927] "Client rotation is on, will bootstrap in background" Mar 20 18:00:12.394390 kubelet[1769]: I0320 18:00:12.393839 1769 dynamic_cafile_content.go:157] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Mar 20 18:00:12.407699 kubelet[1769]: I0320 18:00:12.407660 1769 server.go:742] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Mar 20 18:00:12.408853 kubelet[1769]: I0320 18:00:12.408807 1769 container_manager_linux.go:265] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Mar 20 18:00:12.409093 kubelet[1769]: I0320 18:00:12.408849 1769 container_manager_linux.go:270] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.79","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null} Mar 20 18:00:12.409208 kubelet[1769]: I0320 18:00:12.409109 1769 topology_manager.go:138] "Creating topology manager with none policy" Mar 20 18:00:12.409208 kubelet[1769]: I0320 18:00:12.409117 1769 container_manager_linux.go:301] "Creating device plugin manager" Mar 20 18:00:12.409310 kubelet[1769]: I0320 18:00:12.409285 1769 state_mem.go:36] "Initialized new in-memory state store" Mar 20 18:00:12.409931 kubelet[1769]: I0320 18:00:12.409908 1769 kubelet.go:400] "Attempting to sync node with API server" Mar 20 18:00:12.409931 kubelet[1769]: I0320 18:00:12.409925 1769 kubelet.go:301] "Adding static pod path" path="/etc/kubernetes/manifests" Mar 20 18:00:12.410013 kubelet[1769]: I0320 18:00:12.409945 1769 kubelet.go:312] "Adding apiserver pod source" Mar 20 18:00:12.410013 kubelet[1769]: I0320 18:00:12.409968 1769 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Mar 20 18:00:12.410229 kubelet[1769]: E0320 18:00:12.410139 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:12.410229 kubelet[1769]: E0320 18:00:12.410228 1769 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:12.414239 kubelet[1769]: I0320 18:00:12.414202 1769 kuberuntime_manager.go:261] "Container runtime initialized" containerRuntime="containerd" version="v2.0.1" apiVersion="v1" Mar 20 18:00:12.414541 kubelet[1769]: W0320 18:00:12.414519 1769 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.79" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Mar 20 18:00:12.414652 kubelet[1769]: W0320 18:00:12.414552 1769 reflector.go:547] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Mar 20 18:00:12.414652 kubelet[1769]: E0320 18:00:12.414588 1769 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope Mar 20 18:00:12.414652 kubelet[1769]: E0320 18:00:12.414558 1769 reflector.go:150] k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes "10.0.0.79" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope Mar 20 18:00:12.415497 kubelet[1769]: I0320 18:00:12.415473 1769 kubelet.go:815] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Mar 20 18:00:12.415553 kubelet[1769]: W0320 18:00:12.415538 1769 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Mar 20 18:00:12.416242 kubelet[1769]: I0320 18:00:12.416220 1769 server.go:1264] "Started kubelet" Mar 20 18:00:12.416834 kubelet[1769]: I0320 18:00:12.416298 1769 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 Mar 20 18:00:12.416834 kubelet[1769]: I0320 18:00:12.416350 1769 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Mar 20 18:00:12.416834 kubelet[1769]: I0320 18:00:12.416703 1769 server.go:227] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Mar 20 18:00:12.419272 kubelet[1769]: I0320 18:00:12.418392 1769 server.go:455] "Adding debug handlers to kubelet server" Mar 20 18:00:12.419272 kubelet[1769]: I0320 18:00:12.419022 1769 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Mar 20 18:00:12.420288 kubelet[1769]: I0320 18:00:12.419898 1769 volume_manager.go:291] "Starting Kubelet Volume Manager" Mar 20 18:00:12.420288 kubelet[1769]: I0320 18:00:12.420014 1769 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Mar 20 18:00:12.420288 kubelet[1769]: I0320 18:00:12.420116 1769 reconciler.go:26] "Reconciler: start to sync state" Mar 20 18:00:12.421485 kubelet[1769]: I0320 18:00:12.421465 1769 factory.go:221] Registration of the systemd container factory successfully Mar 20 18:00:12.421690 kubelet[1769]: I0320 18:00:12.421659 1769 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:00:12.422225 kubelet[1769]: E0320 18:00:12.422198 1769 kubelet.go:1467] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Mar 20 18:00:12.422724 kubelet[1769]: I0320 18:00:12.422705 1769 factory.go:221] Registration of the containerd container factory successfully Mar 20 18:00:12.432802 kubelet[1769]: E0320 18:00:12.432759 1769 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.79\" not found" node="10.0.0.79" Mar 20 18:00:12.438442 kubelet[1769]: I0320 18:00:12.438416 1769 cpu_manager.go:214] "Starting CPU manager" policy="none" Mar 20 18:00:12.438600 kubelet[1769]: I0320 18:00:12.438586 1769 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" Mar 20 18:00:12.438713 kubelet[1769]: I0320 18:00:12.438699 1769 state_mem.go:36] "Initialized new in-memory state store" Mar 20 18:00:12.521425 kubelet[1769]: I0320 18:00:12.521382 1769 kubelet_node_status.go:73] "Attempting to register node" node="10.0.0.79" Mar 20 18:00:12.606758 kubelet[1769]: I0320 18:00:12.606710 1769 kubelet_node_status.go:76] "Successfully registered node" node="10.0.0.79" Mar 20 18:00:12.704649 kubelet[1769]: E0320 18:00:12.704522 1769 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.79\" not found" Mar 20 18:00:12.775485 kubelet[1769]: I0320 18:00:12.775449 1769 policy_none.go:49] "None policy: Start" Mar 20 18:00:12.776427 kubelet[1769]: I0320 18:00:12.776402 1769 memory_manager.go:170] "Starting memorymanager" policy="None" Mar 20 18:00:12.776488 kubelet[1769]: I0320 18:00:12.776448 1769 state_mem.go:35] "Initializing new in-memory state store" Mar 20 18:00:12.784636 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Mar 20 18:00:12.785794 sudo[1627]: pam_unix(sudo:session): session closed for user root Mar 20 18:00:12.788049 sshd-session[1623]: pam_unix(sshd:session): session closed for user core Mar 20 18:00:12.791871 sshd[1626]: Connection closed by 10.0.0.1 port 50058 Mar 20 18:00:12.794248 systemd[1]: sshd@4-10.0.0.79:22-10.0.0.1:50058.service: Deactivated successfully. Mar 20 18:00:12.796861 systemd[1]: session-5.scope: Deactivated successfully. Mar 20 18:00:12.797164 systemd[1]: session-5.scope: Consumed 652ms CPU time, 102M memory peak. Mar 20 18:00:12.798568 systemd-logind[1460]: Session 5 logged out. Waiting for processes to exit. Mar 20 18:00:12.802781 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Mar 20 18:00:12.803525 systemd-logind[1460]: Removed session 5. Mar 20 18:00:12.805072 kubelet[1769]: E0320 18:00:12.805040 1769 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.79\" not found" Mar 20 18:00:12.807810 kubelet[1769]: I0320 18:00:12.807762 1769 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Mar 20 18:00:12.807999 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Mar 20 18:00:12.809184 kubelet[1769]: I0320 18:00:12.809154 1769 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Mar 20 18:00:12.809236 kubelet[1769]: I0320 18:00:12.809194 1769 status_manager.go:217] "Starting to sync pod status with apiserver" Mar 20 18:00:12.809236 kubelet[1769]: I0320 18:00:12.809215 1769 kubelet.go:2337] "Starting kubelet main sync loop" Mar 20 18:00:12.809281 kubelet[1769]: E0320 18:00:12.809257 1769 kubelet.go:2361] "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:00:12.815877 kubelet[1769]: I0320 18:00:12.815846 1769 manager.go:479] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Mar 20 18:00:12.816215 kubelet[1769]: I0320 18:00:12.816154 1769 container_log_manager.go:186] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Mar 20 18:00:12.816333 kubelet[1769]: I0320 18:00:12.816316 1769 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Mar 20 18:00:12.817267 kubelet[1769]: E0320 18:00:12.817236 1769 eviction_manager.go:282] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.79\" not found" Mar 20 18:00:12.906113 kubelet[1769]: E0320 18:00:12.906060 1769 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.79\" not found" Mar 20 18:00:13.007074 kubelet[1769]: E0320 18:00:13.006944 1769 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.79\" not found" Mar 20 18:00:13.107561 kubelet[1769]: E0320 18:00:13.107509 1769 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.79\" not found" Mar 20 18:00:13.208134 kubelet[1769]: E0320 18:00:13.208064 1769 kubelet_node_status.go:462] "Error getting the current node from lister" err="node \"10.0.0.79\" not found" Mar 20 18:00:13.309529 kubelet[1769]: I0320 18:00:13.309412 1769 kuberuntime_manager.go:1523] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Mar 20 18:00:13.309779 containerd[1480]: time="2025-03-20T18:00:13.309725665Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Mar 20 18:00:13.310456 kubelet[1769]: I0320 18:00:13.310415 1769 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Mar 20 18:00:13.381408 kubelet[1769]: I0320 18:00:13.381357 1769 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Mar 20 18:00:13.381594 kubelet[1769]: W0320 18:00:13.381472 1769 reflector.go:470] k8s.io/client-go/informers/factory.go:160: watch of *v1.Node 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:00:13.381594 kubelet[1769]: W0320 18:00:13.381513 1769 reflector.go:470] 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:00:13.381594 kubelet[1769]: W0320 18:00:13.381512 1769 reflector.go:470] 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:00:13.410850 kubelet[1769]: I0320 18:00:13.410803 1769 apiserver.go:52] "Watching apiserver" Mar 20 18:00:13.410978 kubelet[1769]: E0320 18:00:13.410802 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:13.418834 kubelet[1769]: I0320 18:00:13.418779 1769 topology_manager.go:215] "Topology Admit Handler" podUID="49aa0be4-9414-4e26-8590-d666003ed096" podNamespace="kube-flannel" podName="kube-flannel-ds-7ctfz" Mar 20 18:00:13.418965 kubelet[1769]: I0320 18:00:13.418935 1769 topology_manager.go:215] "Topology Admit Handler" podUID="f768d132-4806-45bb-a314-3e0d7b101079" podNamespace="kube-system" podName="kube-proxy-xhs4c" Mar 20 18:00:13.420515 kubelet[1769]: I0320 18:00:13.420474 1769 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Mar 20 18:00:13.424567 kubelet[1769]: I0320 18:00:13.424535 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/49aa0be4-9414-4e26-8590-d666003ed096-run\") pod \"kube-flannel-ds-7ctfz\" (UID: \"49aa0be4-9414-4e26-8590-d666003ed096\") " pod="kube-flannel/kube-flannel-ds-7ctfz" Mar 20 18:00:13.424636 kubelet[1769]: I0320 18:00:13.424571 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/49aa0be4-9414-4e26-8590-d666003ed096-xtables-lock\") pod \"kube-flannel-ds-7ctfz\" (UID: \"49aa0be4-9414-4e26-8590-d666003ed096\") " pod="kube-flannel/kube-flannel-ds-7ctfz" Mar 20 18:00:13.424636 kubelet[1769]: I0320 18:00:13.424595 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vs5l9\" (UniqueName: \"kubernetes.io/projected/49aa0be4-9414-4e26-8590-d666003ed096-kube-api-access-vs5l9\") pod \"kube-flannel-ds-7ctfz\" (UID: \"49aa0be4-9414-4e26-8590-d666003ed096\") " pod="kube-flannel/kube-flannel-ds-7ctfz" Mar 20 18:00:13.424636 kubelet[1769]: I0320 18:00:13.424615 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-rc2k5\" (UniqueName: \"kubernetes.io/projected/f768d132-4806-45bb-a314-3e0d7b101079-kube-api-access-rc2k5\") pod \"kube-proxy-xhs4c\" (UID: \"f768d132-4806-45bb-a314-3e0d7b101079\") " pod="kube-system/kube-proxy-xhs4c" Mar 20 18:00:13.424752 kubelet[1769]: I0320 18:00:13.424635 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/49aa0be4-9414-4e26-8590-d666003ed096-cni-plugin\") pod \"kube-flannel-ds-7ctfz\" (UID: \"49aa0be4-9414-4e26-8590-d666003ed096\") " pod="kube-flannel/kube-flannel-ds-7ctfz" Mar 20 18:00:13.424752 kubelet[1769]: I0320 18:00:13.424663 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/49aa0be4-9414-4e26-8590-d666003ed096-cni\") pod \"kube-flannel-ds-7ctfz\" (UID: \"49aa0be4-9414-4e26-8590-d666003ed096\") " pod="kube-flannel/kube-flannel-ds-7ctfz" Mar 20 18:00:13.424752 kubelet[1769]: I0320 18:00:13.424682 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/49aa0be4-9414-4e26-8590-d666003ed096-flannel-cfg\") pod \"kube-flannel-ds-7ctfz\" (UID: \"49aa0be4-9414-4e26-8590-d666003ed096\") " pod="kube-flannel/kube-flannel-ds-7ctfz" Mar 20 18:00:13.424752 kubelet[1769]: I0320 18:00:13.424702 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/f768d132-4806-45bb-a314-3e0d7b101079-kube-proxy\") pod \"kube-proxy-xhs4c\" (UID: \"f768d132-4806-45bb-a314-3e0d7b101079\") " pod="kube-system/kube-proxy-xhs4c" Mar 20 18:00:13.424752 kubelet[1769]: I0320 18:00:13.424725 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/f768d132-4806-45bb-a314-3e0d7b101079-xtables-lock\") pod \"kube-proxy-xhs4c\" (UID: \"f768d132-4806-45bb-a314-3e0d7b101079\") " pod="kube-system/kube-proxy-xhs4c" Mar 20 18:00:13.424906 kubelet[1769]: I0320 18:00:13.424745 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/f768d132-4806-45bb-a314-3e0d7b101079-lib-modules\") pod \"kube-proxy-xhs4c\" (UID: \"f768d132-4806-45bb-a314-3e0d7b101079\") " pod="kube-system/kube-proxy-xhs4c" Mar 20 18:00:13.425730 systemd[1]: Created slice kubepods-burstable-pod49aa0be4_9414_4e26_8590_d666003ed096.slice - libcontainer container kubepods-burstable-pod49aa0be4_9414_4e26_8590_d666003ed096.slice. Mar 20 18:00:13.445055 systemd[1]: Created slice kubepods-besteffort-podf768d132_4806_45bb_a314_3e0d7b101079.slice - libcontainer container kubepods-besteffort-podf768d132_4806_45bb_a314_3e0d7b101079.slice. Mar 20 18:00:13.743410 containerd[1480]: time="2025-03-20T18:00:13.743251481Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7ctfz,Uid:49aa0be4-9414-4e26-8590-d666003ed096,Namespace:kube-flannel,Attempt:0,}" Mar 20 18:00:13.758996 containerd[1480]: time="2025-03-20T18:00:13.758951274Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-xhs4c,Uid:f768d132-4806-45bb-a314-3e0d7b101079,Namespace:kube-system,Attempt:0,}" Mar 20 18:00:14.411692 kubelet[1769]: E0320 18:00:14.411611 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:14.610464 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1021562069.mount: Deactivated successfully. Mar 20 18:00:14.620440 containerd[1480]: time="2025-03-20T18:00:14.620368078Z" 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:00:14.622363 containerd[1480]: time="2025-03-20T18:00:14.622297867Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321138" Mar 20 18:00:14.623258 containerd[1480]: time="2025-03-20T18:00:14.623218553Z" 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:00:14.624325 containerd[1480]: time="2025-03-20T18:00:14.624263553Z" 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:00:14.624951 containerd[1480]: time="2025-03-20T18:00:14.624909725Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" Mar 20 18:00:14.626858 containerd[1480]: time="2025-03-20T18:00:14.626818855Z" 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:00:14.627604 containerd[1480]: time="2025-03-20T18:00:14.627573390Z" 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 862.221834ms" Mar 20 18:00:14.628947 containerd[1480]: time="2025-03-20T18:00:14.628911149Z" 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 874.77185ms" Mar 20 18:00:14.651988 containerd[1480]: time="2025-03-20T18:00:14.651300076Z" level=info msg="connecting to shim a66a403dad122352c2bd53981b38a8e7a5f739e212011ea6638f70553d849d19" address="unix:///run/containerd/s/04bec48da6acfb9ad07186178620bb60539c4065a06583ab17ebaeec7612f2b4" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:00:14.654546 containerd[1480]: time="2025-03-20T18:00:14.654493535Z" level=info msg="connecting to shim 1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c" address="unix:///run/containerd/s/45a7cfa6f25e2ef3ae8911e042574331b38dbcc107c1ae9eb0dd682ae2b8fcc0" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:00:14.682154 systemd[1]: Started cri-containerd-a66a403dad122352c2bd53981b38a8e7a5f739e212011ea6638f70553d849d19.scope - libcontainer container a66a403dad122352c2bd53981b38a8e7a5f739e212011ea6638f70553d849d19. Mar 20 18:00:14.686478 systemd[1]: Started cri-containerd-1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c.scope - libcontainer container 1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c. Mar 20 18:00:14.711958 containerd[1480]: time="2025-03-20T18:00:14.711421916Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-xhs4c,Uid:f768d132-4806-45bb-a314-3e0d7b101079,Namespace:kube-system,Attempt:0,} returns sandbox id \"a66a403dad122352c2bd53981b38a8e7a5f739e212011ea6638f70553d849d19\"" Mar 20 18:00:14.713942 containerd[1480]: time="2025-03-20T18:00:14.713919129Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.11\"" Mar 20 18:00:14.762264 containerd[1480]: time="2025-03-20T18:00:14.762221814Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-7ctfz,Uid:49aa0be4-9414-4e26-8590-d666003ed096,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c\"" Mar 20 18:00:15.412396 kubelet[1769]: E0320 18:00:15.412318 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:16.041720 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1951283634.mount: Deactivated successfully. Mar 20 18:00:16.290215 containerd[1480]: time="2025-03-20T18:00:16.290155746Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.30.11\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:16.291020 containerd[1480]: time="2025-03-20T18:00:16.290981133Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.30.11: active requests=0, bytes read=29185372" Mar 20 18:00:16.292247 containerd[1480]: time="2025-03-20T18:00:16.292171406Z" level=info msg="ImageCreate event name:\"sha256:01045f200a8856c3f5ccfa7be03d72274f1f16fc7a047659e709d603d5c019dc\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:16.294063 containerd[1480]: time="2025-03-20T18:00:16.294022507Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:ea4da798040a18ed3f302e8d5f67307c7275a2a53bcf3d51bcec223acda84a55\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:16.294531 containerd[1480]: time="2025-03-20T18:00:16.294497448Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.30.11\" with image id \"sha256:01045f200a8856c3f5ccfa7be03d72274f1f16fc7a047659e709d603d5c019dc\", repo tag \"registry.k8s.io/kube-proxy:v1.30.11\", repo digest \"registry.k8s.io/kube-proxy@sha256:ea4da798040a18ed3f302e8d5f67307c7275a2a53bcf3d51bcec223acda84a55\", size \"29184391\" in 1.580551799s" Mar 20 18:00:16.294531 containerd[1480]: time="2025-03-20T18:00:16.294528256Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.30.11\" returns image reference \"sha256:01045f200a8856c3f5ccfa7be03d72274f1f16fc7a047659e709d603d5c019dc\"" Mar 20 18:00:16.295641 containerd[1480]: time="2025-03-20T18:00:16.295622448Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Mar 20 18:00:16.296783 containerd[1480]: time="2025-03-20T18:00:16.296742688Z" level=info msg="CreateContainer within sandbox \"a66a403dad122352c2bd53981b38a8e7a5f739e212011ea6638f70553d849d19\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Mar 20 18:00:16.304650 containerd[1480]: time="2025-03-20T18:00:16.304604171Z" level=info msg="Container 59356da129dc3e2b70b9d175210182b329d938ebccc778fc6eb8e6b24507c10f: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:00:16.307994 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2296391597.mount: Deactivated successfully. Mar 20 18:00:16.314633 containerd[1480]: time="2025-03-20T18:00:16.314590840Z" level=info msg="CreateContainer within sandbox \"a66a403dad122352c2bd53981b38a8e7a5f739e212011ea6638f70553d849d19\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"59356da129dc3e2b70b9d175210182b329d938ebccc778fc6eb8e6b24507c10f\"" Mar 20 18:00:16.315323 containerd[1480]: time="2025-03-20T18:00:16.315288819Z" level=info msg="StartContainer for \"59356da129dc3e2b70b9d175210182b329d938ebccc778fc6eb8e6b24507c10f\"" Mar 20 18:00:16.316754 containerd[1480]: time="2025-03-20T18:00:16.316722297Z" level=info msg="connecting to shim 59356da129dc3e2b70b9d175210182b329d938ebccc778fc6eb8e6b24507c10f" address="unix:///run/containerd/s/04bec48da6acfb9ad07186178620bb60539c4065a06583ab17ebaeec7612f2b4" protocol=ttrpc version=3 Mar 20 18:00:16.339035 systemd[1]: Started cri-containerd-59356da129dc3e2b70b9d175210182b329d938ebccc778fc6eb8e6b24507c10f.scope - libcontainer container 59356da129dc3e2b70b9d175210182b329d938ebccc778fc6eb8e6b24507c10f. Mar 20 18:00:16.380123 containerd[1480]: time="2025-03-20T18:00:16.380000797Z" level=info msg="StartContainer for \"59356da129dc3e2b70b9d175210182b329d938ebccc778fc6eb8e6b24507c10f\" returns successfully" Mar 20 18:00:16.412945 kubelet[1769]: E0320 18:00:16.412863 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:17.413798 kubelet[1769]: E0320 18:00:17.413720 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:18.014692 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount96032314.mount: Deactivated successfully. Mar 20 18:00:18.053691 containerd[1480]: time="2025-03-20T18:00:18.053629659Z" 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:00:18.054421 containerd[1480]: time="2025-03-20T18:00:18.054382530Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Mar 20 18:00:18.055723 containerd[1480]: time="2025-03-20T18:00:18.055655909Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:18.058061 containerd[1480]: time="2025-03-20T18:00:18.058015564Z" 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:00:18.060859 containerd[1480]: time="2025-03-20T18:00:18.059491351Z" 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.763731696s" Mar 20 18:00:18.060859 containerd[1480]: time="2025-03-20T18:00:18.059541195Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Mar 20 18:00:18.063222 containerd[1480]: time="2025-03-20T18:00:18.063173537Z" level=info msg="CreateContainer within sandbox \"1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Mar 20 18:00:18.071628 containerd[1480]: time="2025-03-20T18:00:18.071576044Z" level=info msg="Container f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:00:18.075898 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1284874.mount: Deactivated successfully. Mar 20 18:00:18.079465 containerd[1480]: time="2025-03-20T18:00:18.079425124Z" level=info msg="CreateContainer within sandbox \"1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613\"" Mar 20 18:00:18.080044 containerd[1480]: time="2025-03-20T18:00:18.080012716Z" level=info msg="StartContainer for \"f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613\"" Mar 20 18:00:18.080873 containerd[1480]: time="2025-03-20T18:00:18.080843414Z" level=info msg="connecting to shim f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613" address="unix:///run/containerd/s/45a7cfa6f25e2ef3ae8911e042574331b38dbcc107c1ae9eb0dd682ae2b8fcc0" protocol=ttrpc version=3 Mar 20 18:00:18.122138 systemd[1]: Started cri-containerd-f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613.scope - libcontainer container f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613. Mar 20 18:00:18.161315 systemd[1]: cri-containerd-f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613.scope: Deactivated successfully. Mar 20 18:00:18.162719 containerd[1480]: time="2025-03-20T18:00:18.162661753Z" level=info msg="TaskExit event in podsandbox handler container_id:\"f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613\" id:\"f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613\" pid:2089 exited_at:{seconds:1742493618 nanos:162178587}" Mar 20 18:00:18.163045 containerd[1480]: time="2025-03-20T18:00:18.163001600Z" level=info msg="received exit event container_id:\"f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613\" id:\"f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613\" pid:2089 exited_at:{seconds:1742493618 nanos:162178587}" Mar 20 18:00:18.164016 containerd[1480]: time="2025-03-20T18:00:18.163993150Z" level=info msg="StartContainer for \"f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613\" returns successfully" Mar 20 18:00:18.414225 kubelet[1769]: E0320 18:00:18.414095 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:18.822684 containerd[1480]: time="2025-03-20T18:00:18.822545891Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Mar 20 18:00:18.830570 kubelet[1769]: I0320 18:00:18.830499 1769 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-xhs4c" podStartSLOduration=5.248656927 podStartE2EDuration="6.830467597s" podCreationTimestamp="2025-03-20 18:00:12 +0000 UTC" firstStartedPulling="2025-03-20 18:00:14.713561188 +0000 UTC m=+2.667210533" lastFinishedPulling="2025-03-20 18:00:16.295371858 +0000 UTC m=+4.249021203" observedRunningTime="2025-03-20 18:00:16.828445431 +0000 UTC m=+4.782094776" watchObservedRunningTime="2025-03-20 18:00:18.830467597 +0000 UTC m=+6.784116942" Mar 20 18:00:18.944363 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-f64be7d0117c1cca23c728de3b3a473fc9bdc1c223857c2d50ae49ed6016d613-rootfs.mount: Deactivated successfully. Mar 20 18:00:19.415051 kubelet[1769]: E0320 18:00:19.415001 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:20.415184 kubelet[1769]: E0320 18:00:20.415120 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:20.660251 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1108871801.mount: Deactivated successfully. Mar 20 18:00:21.290141 containerd[1480]: time="2025-03-20T18:00:21.290086751Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:21.290749 containerd[1480]: time="2025-03-20T18:00:21.290714107Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Mar 20 18:00:21.291967 containerd[1480]: time="2025-03-20T18:00:21.291940638Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:21.294243 containerd[1480]: time="2025-03-20T18:00:21.294197801Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:21.295099 containerd[1480]: time="2025-03-20T18:00:21.295044238Z" 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.472456198s" Mar 20 18:00:21.295099 containerd[1480]: time="2025-03-20T18:00:21.295094973Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Mar 20 18:00:21.297144 containerd[1480]: time="2025-03-20T18:00:21.297108289Z" level=info msg="CreateContainer within sandbox \"1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Mar 20 18:00:21.305820 containerd[1480]: time="2025-03-20T18:00:21.305787265Z" level=info msg="Container 3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:00:21.312680 containerd[1480]: time="2025-03-20T18:00:21.312632843Z" level=info msg="CreateContainer within sandbox \"1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661\"" Mar 20 18:00:21.313239 containerd[1480]: time="2025-03-20T18:00:21.313208252Z" level=info msg="StartContainer for \"3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661\"" Mar 20 18:00:21.314224 containerd[1480]: time="2025-03-20T18:00:21.314193440Z" level=info msg="connecting to shim 3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661" address="unix:///run/containerd/s/45a7cfa6f25e2ef3ae8911e042574331b38dbcc107c1ae9eb0dd682ae2b8fcc0" protocol=ttrpc version=3 Mar 20 18:00:21.354048 systemd[1]: Started cri-containerd-3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661.scope - libcontainer container 3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661. Mar 20 18:00:21.390101 systemd[1]: cri-containerd-3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661.scope: Deactivated successfully. Mar 20 18:00:21.391089 containerd[1480]: time="2025-03-20T18:00:21.391055964Z" level=info msg="TaskExit event in podsandbox handler container_id:\"3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661\" id:\"3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661\" pid:2160 exited_at:{seconds:1742493621 nanos:390502015}" Mar 20 18:00:21.392012 containerd[1480]: time="2025-03-20T18:00:21.391989745Z" level=info msg="received exit event container_id:\"3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661\" id:\"3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661\" pid:2160 exited_at:{seconds:1742493621 nanos:390502015}" Mar 20 18:00:21.393288 containerd[1480]: time="2025-03-20T18:00:21.393252383Z" level=info msg="StartContainer for \"3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661\" returns successfully" Mar 20 18:00:21.415627 kubelet[1769]: E0320 18:00:21.415552 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:21.456263 kubelet[1769]: I0320 18:00:21.456216 1769 kubelet_node_status.go:497] "Fast updating node status as it just became ready" Mar 20 18:00:21.579582 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-3e957246b977b6a61b60de4dc3fa1d910e082c72e16484e8bcf12a22571b2661-rootfs.mount: Deactivated successfully. Mar 20 18:00:21.835991 containerd[1480]: time="2025-03-20T18:00:21.835840594Z" level=info msg="CreateContainer within sandbox \"1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Mar 20 18:00:21.844965 containerd[1480]: time="2025-03-20T18:00:21.844912668Z" level=info msg="Container bddcb66b69d3922874120c08b5a3355181519b2fa442870ffbc8362217a5244e: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:00:21.852222 containerd[1480]: time="2025-03-20T18:00:21.852182601Z" level=info msg="CreateContainer within sandbox \"1846731dabc0fd1e2004fb6a329e2b1571ebbb492343d5ed07a30e387606484c\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"bddcb66b69d3922874120c08b5a3355181519b2fa442870ffbc8362217a5244e\"" Mar 20 18:00:21.852989 containerd[1480]: time="2025-03-20T18:00:21.852709509Z" level=info msg="StartContainer for \"bddcb66b69d3922874120c08b5a3355181519b2fa442870ffbc8362217a5244e\"" Mar 20 18:00:21.853682 containerd[1480]: time="2025-03-20T18:00:21.853654822Z" level=info msg="connecting to shim bddcb66b69d3922874120c08b5a3355181519b2fa442870ffbc8362217a5244e" address="unix:///run/containerd/s/45a7cfa6f25e2ef3ae8911e042574331b38dbcc107c1ae9eb0dd682ae2b8fcc0" protocol=ttrpc version=3 Mar 20 18:00:21.878071 systemd[1]: Started cri-containerd-bddcb66b69d3922874120c08b5a3355181519b2fa442870ffbc8362217a5244e.scope - libcontainer container bddcb66b69d3922874120c08b5a3355181519b2fa442870ffbc8362217a5244e. Mar 20 18:00:21.921687 containerd[1480]: time="2025-03-20T18:00:21.921643535Z" level=info msg="StartContainer for \"bddcb66b69d3922874120c08b5a3355181519b2fa442870ffbc8362217a5244e\" returns successfully" Mar 20 18:00:22.415791 kubelet[1769]: E0320 18:00:22.415722 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:22.850010 kubelet[1769]: I0320 18:00:22.849846 1769 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-7ctfz" podStartSLOduration=4.317277849 podStartE2EDuration="10.849823013s" podCreationTimestamp="2025-03-20 18:00:12 +0000 UTC" firstStartedPulling="2025-03-20 18:00:14.763218323 +0000 UTC m=+2.716867668" lastFinishedPulling="2025-03-20 18:00:21.295763487 +0000 UTC m=+9.249412832" observedRunningTime="2025-03-20 18:00:22.849730349 +0000 UTC m=+10.803379704" watchObservedRunningTime="2025-03-20 18:00:22.849823013 +0000 UTC m=+10.803472358" Mar 20 18:00:22.956641 systemd-networkd[1407]: flannel.1: Link UP Mar 20 18:00:22.956651 systemd-networkd[1407]: flannel.1: Gained carrier Mar 20 18:00:23.416750 kubelet[1769]: E0320 18:00:23.416678 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:23.650282 kubelet[1769]: I0320 18:00:23.650217 1769 topology_manager.go:215] "Topology Admit Handler" podUID="d48f87dd-cc6f-4326-9f3c-b59a854540ca" podNamespace="default" podName="nginx-deployment-85f456d6dd-6tjgj" Mar 20 18:00:23.656593 systemd[1]: Created slice kubepods-besteffort-podd48f87dd_cc6f_4326_9f3c_b59a854540ca.slice - libcontainer container kubepods-besteffort-podd48f87dd_cc6f_4326_9f3c_b59a854540ca.slice. Mar 20 18:00:23.684687 kubelet[1769]: I0320 18:00:23.684505 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xhtz8\" (UniqueName: \"kubernetes.io/projected/d48f87dd-cc6f-4326-9f3c-b59a854540ca-kube-api-access-xhtz8\") pod \"nginx-deployment-85f456d6dd-6tjgj\" (UID: \"d48f87dd-cc6f-4326-9f3c-b59a854540ca\") " pod="default/nginx-deployment-85f456d6dd-6tjgj" Mar 20 18:00:23.960305 containerd[1480]: time="2025-03-20T18:00:23.960189166Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-6tjgj,Uid:d48f87dd-cc6f-4326-9f3c-b59a854540ca,Namespace:default,Attempt:0,}" Mar 20 18:00:23.974635 systemd-networkd[1407]: cni0: Link UP Mar 20 18:00:23.974657 systemd-networkd[1407]: cni0: Gained carrier Mar 20 18:00:23.975364 systemd-networkd[1407]: cni0: Lost carrier Mar 20 18:00:23.982981 kernel: cni0: port 1(veth751c1373) entered blocking state Mar 20 18:00:23.983055 kernel: cni0: port 1(veth751c1373) entered disabled state Mar 20 18:00:23.983079 kernel: veth751c1373: entered allmulticast mode Mar 20 18:00:23.984608 kernel: veth751c1373: entered promiscuous mode Mar 20 18:00:23.985652 kernel: cni0: port 1(veth751c1373) entered blocking state Mar 20 18:00:23.985680 kernel: cni0: port 1(veth751c1373) entered forwarding state Mar 20 18:00:23.987431 kernel: cni0: port 1(veth751c1373) entered disabled state Mar 20 18:00:23.987824 systemd-networkd[1407]: veth751c1373: Link UP Mar 20 18:00:23.994457 kernel: cni0: port 1(veth751c1373) entered blocking state Mar 20 18:00:23.994575 kernel: cni0: port 1(veth751c1373) entered forwarding state Mar 20 18:00:23.994592 systemd-networkd[1407]: veth751c1373: Gained carrier Mar 20 18:00:23.995026 systemd-networkd[1407]: cni0: Gained carrier Mar 20 18:00:23.996692 containerd[1480]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000018938), "name":"cbr0", "type":"bridge"} Mar 20 18:00:23.996692 containerd[1480]: delegateAdd: netconf sent to delegate plugin: Mar 20 18:00:24.024649 containerd[1480]: {"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:00:24.024587706Z" level=info msg="connecting to shim 093c73b472980aae55f1c894de562816faf4bf40e536085e8a023b6dedf4d270" address="unix:///run/containerd/s/34b0ce09b7d786d7de1850f3ee4ea67bc046cb5d4b0934999ddafcd513d435ea" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:00:24.055029 systemd[1]: Started cri-containerd-093c73b472980aae55f1c894de562816faf4bf40e536085e8a023b6dedf4d270.scope - libcontainer container 093c73b472980aae55f1c894de562816faf4bf40e536085e8a023b6dedf4d270. Mar 20 18:00:24.068384 systemd-resolved[1345]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 20 18:00:24.104021 containerd[1480]: time="2025-03-20T18:00:24.103980165Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-85f456d6dd-6tjgj,Uid:d48f87dd-cc6f-4326-9f3c-b59a854540ca,Namespace:default,Attempt:0,} returns sandbox id \"093c73b472980aae55f1c894de562816faf4bf40e536085e8a023b6dedf4d270\"" Mar 20 18:00:24.105687 containerd[1480]: time="2025-03-20T18:00:24.105627565Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 20 18:00:24.417323 kubelet[1769]: E0320 18:00:24.417156 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:24.430060 systemd-networkd[1407]: flannel.1: Gained IPv6LL Mar 20 18:00:25.070043 systemd-networkd[1407]: cni0: Gained IPv6LL Mar 20 18:00:25.133973 systemd-networkd[1407]: veth751c1373: Gained IPv6LL Mar 20 18:00:25.418223 kubelet[1769]: E0320 18:00:25.418112 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:26.418672 kubelet[1769]: E0320 18:00:26.418612 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:27.419632 kubelet[1769]: E0320 18:00:27.419581 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:27.819969 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3595761659.mount: Deactivated successfully. Mar 20 18:00:28.420293 kubelet[1769]: E0320 18:00:28.420233 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:28.830970 containerd[1480]: time="2025-03-20T18:00:28.830809868Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:28.831673 containerd[1480]: time="2025-03-20T18:00:28.831605641Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73060131" Mar 20 18:00:28.832923 containerd[1480]: time="2025-03-20T18:00:28.832869200Z" level=info msg="ImageCreate event name:\"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:28.835488 containerd[1480]: time="2025-03-20T18:00:28.835455390Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:b927c62cc716b99bce51774b46a63feb63f5414c6f985fb80cacd1933bbd0e06\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:28.836376 containerd[1480]: time="2025-03-20T18:00:28.836329109Z" 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.730632093s" Mar 20 18:00:28.836376 containerd[1480]: time="2025-03-20T18:00:28.836363383Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\"" Mar 20 18:00:28.839335 containerd[1480]: time="2025-03-20T18:00:28.839299650Z" level=info msg="CreateContainer within sandbox \"093c73b472980aae55f1c894de562816faf4bf40e536085e8a023b6dedf4d270\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Mar 20 18:00:28.852340 containerd[1480]: time="2025-03-20T18:00:28.852290723Z" level=info msg="Container 0239ce28cfd986abad74a271e067fd3f0854ea19678bd9b158bb02741dce1b72: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:00:28.857092 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3012737298.mount: Deactivated successfully. Mar 20 18:00:28.859439 containerd[1480]: time="2025-03-20T18:00:28.859395978Z" level=info msg="CreateContainer within sandbox \"093c73b472980aae55f1c894de562816faf4bf40e536085e8a023b6dedf4d270\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"0239ce28cfd986abad74a271e067fd3f0854ea19678bd9b158bb02741dce1b72\"" Mar 20 18:00:28.859836 containerd[1480]: time="2025-03-20T18:00:28.859805696Z" level=info msg="StartContainer for \"0239ce28cfd986abad74a271e067fd3f0854ea19678bd9b158bb02741dce1b72\"" Mar 20 18:00:28.860664 containerd[1480]: time="2025-03-20T18:00:28.860635312Z" level=info msg="connecting to shim 0239ce28cfd986abad74a271e067fd3f0854ea19678bd9b158bb02741dce1b72" address="unix:///run/containerd/s/34b0ce09b7d786d7de1850f3ee4ea67bc046cb5d4b0934999ddafcd513d435ea" protocol=ttrpc version=3 Mar 20 18:00:28.883050 systemd[1]: Started cri-containerd-0239ce28cfd986abad74a271e067fd3f0854ea19678bd9b158bb02741dce1b72.scope - libcontainer container 0239ce28cfd986abad74a271e067fd3f0854ea19678bd9b158bb02741dce1b72. Mar 20 18:00:28.912738 containerd[1480]: time="2025-03-20T18:00:28.912695533Z" level=info msg="StartContainer for \"0239ce28cfd986abad74a271e067fd3f0854ea19678bd9b158bb02741dce1b72\" returns successfully" Mar 20 18:00:29.421233 kubelet[1769]: E0320 18:00:29.421175 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:29.935310 kubelet[1769]: I0320 18:00:29.935253 1769 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-85f456d6dd-6tjgj" podStartSLOduration=2.203180338 podStartE2EDuration="6.935233106s" podCreationTimestamp="2025-03-20 18:00:23 +0000 UTC" firstStartedPulling="2025-03-20 18:00:24.105182079 +0000 UTC m=+12.058831425" lastFinishedPulling="2025-03-20 18:00:28.837234848 +0000 UTC m=+16.790884193" observedRunningTime="2025-03-20 18:00:29.93510719 +0000 UTC m=+17.888756535" watchObservedRunningTime="2025-03-20 18:00:29.935233106 +0000 UTC m=+17.888882451" Mar 20 18:00:30.421923 kubelet[1769]: E0320 18:00:30.421758 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:31.422477 kubelet[1769]: E0320 18:00:31.422409 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:32.410427 kubelet[1769]: E0320 18:00:32.410369 1769 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:32.422864 kubelet[1769]: E0320 18:00:32.422838 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:33.423208 kubelet[1769]: E0320 18:00:33.423154 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:34.423911 kubelet[1769]: E0320 18:00:34.423850 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:35.424081 kubelet[1769]: E0320 18:00:35.424006 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:36.425068 kubelet[1769]: E0320 18:00:36.425020 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:36.803312 kubelet[1769]: I0320 18:00:36.803164 1769 topology_manager.go:215] "Topology Admit Handler" podUID="4aad615d-a617-4789-a8a9-45de3fe370f5" podNamespace="default" podName="nfs-server-provisioner-0" Mar 20 18:00:36.808943 systemd[1]: Created slice kubepods-besteffort-pod4aad615d_a617_4789_a8a9_45de3fe370f5.slice - libcontainer container kubepods-besteffort-pod4aad615d_a617_4789_a8a9_45de3fe370f5.slice. Mar 20 18:00:36.845155 kubelet[1769]: I0320 18:00:36.845110 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-x29n8\" (UniqueName: \"kubernetes.io/projected/4aad615d-a617-4789-a8a9-45de3fe370f5-kube-api-access-x29n8\") pod \"nfs-server-provisioner-0\" (UID: \"4aad615d-a617-4789-a8a9-45de3fe370f5\") " pod="default/nfs-server-provisioner-0" Mar 20 18:00:36.845155 kubelet[1769]: I0320 18:00:36.845144 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/4aad615d-a617-4789-a8a9-45de3fe370f5-data\") pod \"nfs-server-provisioner-0\" (UID: \"4aad615d-a617-4789-a8a9-45de3fe370f5\") " pod="default/nfs-server-provisioner-0" Mar 20 18:00:37.112903 containerd[1480]: time="2025-03-20T18:00:37.112721605Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:4aad615d-a617-4789-a8a9-45de3fe370f5,Namespace:default,Attempt:0,}" Mar 20 18:00:37.309615 systemd-networkd[1407]: veth58f2d853: Link UP Mar 20 18:00:37.311354 kernel: cni0: port 2(veth58f2d853) entered blocking state Mar 20 18:00:37.311426 kernel: cni0: port 2(veth58f2d853) entered disabled state Mar 20 18:00:37.311451 kernel: veth58f2d853: entered allmulticast mode Mar 20 18:00:37.312948 kernel: veth58f2d853: entered promiscuous mode Mar 20 18:00:37.317983 kernel: cni0: port 2(veth58f2d853) entered blocking state Mar 20 18:00:37.318031 kernel: cni0: port 2(veth58f2d853) entered forwarding state Mar 20 18:00:37.317978 systemd-networkd[1407]: veth58f2d853: Gained carrier Mar 20 18:00:37.320763 containerd[1480]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000ae8e8), "name":"cbr0", "type":"bridge"} Mar 20 18:00:37.320763 containerd[1480]: delegateAdd: netconf sent to delegate plugin: Mar 20 18:00:37.425745 kubelet[1769]: E0320 18:00:37.425643 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:37.532454 containerd[1480]: {"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:00:37.532416311Z" level=info msg="connecting to shim 287c2c8090066c7a3248d349781a60f894fb31b6b40f0ac334c19dcdb04883ea" address="unix:///run/containerd/s/19b69b2248f36dd1b04c6f41f7746a0ad5e5e96d6d20bdeb1cdeaacb59f4c0e4" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:00:37.558028 systemd[1]: Started cri-containerd-287c2c8090066c7a3248d349781a60f894fb31b6b40f0ac334c19dcdb04883ea.scope - libcontainer container 287c2c8090066c7a3248d349781a60f894fb31b6b40f0ac334c19dcdb04883ea. Mar 20 18:00:37.569785 systemd-resolved[1345]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 20 18:00:37.631313 containerd[1480]: time="2025-03-20T18:00:37.631243789Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:4aad615d-a617-4789-a8a9-45de3fe370f5,Namespace:default,Attempt:0,} returns sandbox id \"287c2c8090066c7a3248d349781a60f894fb31b6b40f0ac334c19dcdb04883ea\"" Mar 20 18:00:37.632918 containerd[1480]: time="2025-03-20T18:00:37.632864400Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Mar 20 18:00:38.426185 kubelet[1769]: E0320 18:00:38.426125 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:38.894021 systemd-networkd[1407]: veth58f2d853: Gained IPv6LL Mar 20 18:00:39.426572 kubelet[1769]: E0320 18:00:39.426533 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:40.427615 kubelet[1769]: E0320 18:00:40.427534 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:41.427959 kubelet[1769]: E0320 18:00:41.427913 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:41.625780 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3952453945.mount: Deactivated successfully. Mar 20 18:00:42.428989 kubelet[1769]: E0320 18:00:42.428940 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:43.429425 kubelet[1769]: E0320 18:00:43.429361 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:44.104555 containerd[1480]: time="2025-03-20T18:00:44.104470592Z" 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:00:44.105862 containerd[1480]: time="2025-03-20T18:00:44.105508592Z" 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:00:44.108715 containerd[1480]: time="2025-03-20T18:00:44.107423886Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:44.110323 containerd[1480]: time="2025-03-20T18:00:44.110261579Z" 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:00:44.111316 containerd[1480]: time="2025-03-20T18:00:44.111276094Z" 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 6.47834765s" Mar 20 18:00:44.111359 containerd[1480]: time="2025-03-20T18:00:44.111323996Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Mar 20 18:00:44.113722 containerd[1480]: time="2025-03-20T18:00:44.113681393Z" level=info msg="CreateContainer within sandbox \"287c2c8090066c7a3248d349781a60f894fb31b6b40f0ac334c19dcdb04883ea\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Mar 20 18:00:44.122098 containerd[1480]: time="2025-03-20T18:00:44.122065126Z" level=info msg="Container 066e499800a0cd718acdae4aa726cf8a99938466b1a593ff5da9bae675f39daa: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:00:44.125553 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount81996007.mount: Deactivated successfully. Mar 20 18:00:44.130043 containerd[1480]: time="2025-03-20T18:00:44.130012837Z" level=info msg="CreateContainer within sandbox \"287c2c8090066c7a3248d349781a60f894fb31b6b40f0ac334c19dcdb04883ea\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"066e499800a0cd718acdae4aa726cf8a99938466b1a593ff5da9bae675f39daa\"" Mar 20 18:00:44.130644 containerd[1480]: time="2025-03-20T18:00:44.130599025Z" level=info msg="StartContainer for \"066e499800a0cd718acdae4aa726cf8a99938466b1a593ff5da9bae675f39daa\"" Mar 20 18:00:44.131375 containerd[1480]: time="2025-03-20T18:00:44.131352082Z" level=info msg="connecting to shim 066e499800a0cd718acdae4aa726cf8a99938466b1a593ff5da9bae675f39daa" address="unix:///run/containerd/s/19b69b2248f36dd1b04c6f41f7746a0ad5e5e96d6d20bdeb1cdeaacb59f4c0e4" protocol=ttrpc version=3 Mar 20 18:00:44.155020 systemd[1]: Started cri-containerd-066e499800a0cd718acdae4aa726cf8a99938466b1a593ff5da9bae675f39daa.scope - libcontainer container 066e499800a0cd718acdae4aa726cf8a99938466b1a593ff5da9bae675f39daa. Mar 20 18:00:44.185414 containerd[1480]: time="2025-03-20T18:00:44.185376170Z" level=info msg="StartContainer for \"066e499800a0cd718acdae4aa726cf8a99938466b1a593ff5da9bae675f39daa\" returns successfully" Mar 20 18:00:44.430684 kubelet[1769]: E0320 18:00:44.430522 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:45.431570 kubelet[1769]: E0320 18:00:45.431498 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:46.432642 kubelet[1769]: E0320 18:00:46.432570 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:47.423291 update_engine[1465]: I20250320 18:00:47.423213 1465 update_attempter.cc:509] Updating boot flags... Mar 20 18:00:47.432746 kubelet[1769]: E0320 18:00:47.432709 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:47.654199 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2752) Mar 20 18:00:47.686912 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2752) Mar 20 18:00:47.722929 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2752) Mar 20 18:00:48.433706 kubelet[1769]: E0320 18:00:48.433629 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:49.434858 kubelet[1769]: E0320 18:00:49.434790 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:50.435797 kubelet[1769]: E0320 18:00:50.435724 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:51.436458 kubelet[1769]: E0320 18:00:51.436391 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:52.410997 kubelet[1769]: E0320 18:00:52.410916 1769 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:52.437554 kubelet[1769]: E0320 18:00:52.437485 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:53.438186 kubelet[1769]: E0320 18:00:53.438101 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:54.439269 kubelet[1769]: E0320 18:00:54.439178 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:54.745762 kubelet[1769]: I0320 18:00:54.745675 1769 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=12.266099334 podStartE2EDuration="18.745648454s" podCreationTimestamp="2025-03-20 18:00:36 +0000 UTC" firstStartedPulling="2025-03-20 18:00:37.63253197 +0000 UTC m=+25.586181315" lastFinishedPulling="2025-03-20 18:00:44.11208109 +0000 UTC m=+32.065730435" observedRunningTime="2025-03-20 18:00:44.895333677 +0000 UTC m=+32.848983022" watchObservedRunningTime="2025-03-20 18:00:54.745648454 +0000 UTC m=+42.699297899" Mar 20 18:00:54.746001 kubelet[1769]: I0320 18:00:54.745828 1769 topology_manager.go:215] "Topology Admit Handler" podUID="0f4a97f1-5265-46f0-ad5c-d76039af2522" podNamespace="default" podName="test-pod-1" Mar 20 18:00:54.751772 systemd[1]: Created slice kubepods-besteffort-pod0f4a97f1_5265_46f0_ad5c_d76039af2522.slice - libcontainer container kubepods-besteffort-pod0f4a97f1_5265_46f0_ad5c_d76039af2522.slice. Mar 20 18:00:54.835431 kubelet[1769]: I0320 18:00:54.835350 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5mxgt\" (UniqueName: \"kubernetes.io/projected/0f4a97f1-5265-46f0-ad5c-d76039af2522-kube-api-access-5mxgt\") pod \"test-pod-1\" (UID: \"0f4a97f1-5265-46f0-ad5c-d76039af2522\") " pod="default/test-pod-1" Mar 20 18:00:54.835431 kubelet[1769]: I0320 18:00:54.835418 1769 reconciler_common.go:247] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-59733324-caf6-41ee-a99e-d019f3553012\" (UniqueName: \"kubernetes.io/nfs/0f4a97f1-5265-46f0-ad5c-d76039af2522-pvc-59733324-caf6-41ee-a99e-d019f3553012\") pod \"test-pod-1\" (UID: \"0f4a97f1-5265-46f0-ad5c-d76039af2522\") " pod="default/test-pod-1" Mar 20 18:00:54.961926 kernel: FS-Cache: Loaded Mar 20 18:00:55.029980 kernel: RPC: Registered named UNIX socket transport module. Mar 20 18:00:55.030134 kernel: RPC: Registered udp transport module. Mar 20 18:00:55.030164 kernel: RPC: Registered tcp transport module. Mar 20 18:00:55.031320 kernel: RPC: Registered tcp-with-tls transport module. Mar 20 18:00:55.031368 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 20 18:00:55.247123 kernel: NFS: Registering the id_resolver key type Mar 20 18:00:55.247272 kernel: Key type id_resolver registered Mar 20 18:00:55.247294 kernel: Key type id_legacy registered Mar 20 18:00:55.273329 nfsidmap[2822]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 20 18:00:55.275335 nfsidmap[2823]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' Mar 20 18:00:55.355765 containerd[1480]: time="2025-03-20T18:00:55.355649693Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:0f4a97f1-5265-46f0-ad5c-d76039af2522,Namespace:default,Attempt:0,}" Mar 20 18:00:55.365771 systemd-networkd[1407]: veth95068a61: Link UP Mar 20 18:00:55.367451 kernel: cni0: port 3(veth95068a61) entered blocking state Mar 20 18:00:55.367497 kernel: cni0: port 3(veth95068a61) entered disabled state Mar 20 18:00:55.367516 kernel: veth95068a61: entered allmulticast mode Mar 20 18:00:55.367535 kernel: veth95068a61: entered promiscuous mode Mar 20 18:00:55.373153 kernel: cni0: port 3(veth95068a61) entered blocking state Mar 20 18:00:55.373201 kernel: cni0: port 3(veth95068a61) entered forwarding state Mar 20 18:00:55.372942 systemd-networkd[1407]: veth95068a61: Gained carrier Mar 20 18:00:55.375492 containerd[1480]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc000018938), "name":"cbr0", "type":"bridge"} Mar 20 18:00:55.375492 containerd[1480]: delegateAdd: netconf sent to delegate plugin: Mar 20 18:00:55.395035 containerd[1480]: {"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:00:55.394973864Z" level=info msg="connecting to shim 868afb3ffa8f1711f0ca8070c48212a75802e53ec4dfab886cc2e40259a395ff" address="unix:///run/containerd/s/05178b6ca9f6391de7291576fb09be7e397a85ff78ae0b8ae78a23c2c49b826f" namespace=k8s.io protocol=ttrpc version=3 Mar 20 18:00:55.420033 systemd[1]: Started cri-containerd-868afb3ffa8f1711f0ca8070c48212a75802e53ec4dfab886cc2e40259a395ff.scope - libcontainer container 868afb3ffa8f1711f0ca8070c48212a75802e53ec4dfab886cc2e40259a395ff. Mar 20 18:00:55.431665 systemd-resolved[1345]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Mar 20 18:00:55.439772 kubelet[1769]: E0320 18:00:55.439722 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:55.463019 containerd[1480]: time="2025-03-20T18:00:55.462973837Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:0f4a97f1-5265-46f0-ad5c-d76039af2522,Namespace:default,Attempt:0,} returns sandbox id \"868afb3ffa8f1711f0ca8070c48212a75802e53ec4dfab886cc2e40259a395ff\"" Mar 20 18:00:55.464593 containerd[1480]: time="2025-03-20T18:00:55.464551931Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Mar 20 18:00:55.872457 containerd[1480]: time="2025-03-20T18:00:55.872386859Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Mar 20 18:00:55.873573 containerd[1480]: time="2025-03-20T18:00:55.873451543Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Mar 20 18:00:55.876362 containerd[1480]: time="2025-03-20T18:00:55.876314418Z" 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 411.729033ms" Mar 20 18:00:55.876362 containerd[1480]: time="2025-03-20T18:00:55.876352810Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:d25119ebd2aadc346788ac84ae0c5b1b018c687dcfd3167bb27e341f8b5caeee\"" Mar 20 18:00:55.878632 containerd[1480]: time="2025-03-20T18:00:55.878583529Z" level=info msg="CreateContainer within sandbox \"868afb3ffa8f1711f0ca8070c48212a75802e53ec4dfab886cc2e40259a395ff\" for container &ContainerMetadata{Name:test,Attempt:0,}" Mar 20 18:00:55.889590 containerd[1480]: time="2025-03-20T18:00:55.889532055Z" level=info msg="Container ad69e1b13a94add02497df4cc136a2eec3049a18bc470b0ccf9668ef650aa65f: CDI devices from CRI Config.CDIDevices: []" Mar 20 18:00:55.898350 containerd[1480]: time="2025-03-20T18:00:55.898305106Z" level=info msg="CreateContainer within sandbox \"868afb3ffa8f1711f0ca8070c48212a75802e53ec4dfab886cc2e40259a395ff\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"ad69e1b13a94add02497df4cc136a2eec3049a18bc470b0ccf9668ef650aa65f\"" Mar 20 18:00:55.898875 containerd[1480]: time="2025-03-20T18:00:55.898828587Z" level=info msg="StartContainer for \"ad69e1b13a94add02497df4cc136a2eec3049a18bc470b0ccf9668ef650aa65f\"" Mar 20 18:00:55.899815 containerd[1480]: time="2025-03-20T18:00:55.899782691Z" level=info msg="connecting to shim ad69e1b13a94add02497df4cc136a2eec3049a18bc470b0ccf9668ef650aa65f" address="unix:///run/containerd/s/05178b6ca9f6391de7291576fb09be7e397a85ff78ae0b8ae78a23c2c49b826f" protocol=ttrpc version=3 Mar 20 18:00:55.931046 systemd[1]: Started cri-containerd-ad69e1b13a94add02497df4cc136a2eec3049a18bc470b0ccf9668ef650aa65f.scope - libcontainer container ad69e1b13a94add02497df4cc136a2eec3049a18bc470b0ccf9668ef650aa65f. Mar 20 18:00:55.964210 containerd[1480]: time="2025-03-20T18:00:55.964161926Z" level=info msg="StartContainer for \"ad69e1b13a94add02497df4cc136a2eec3049a18bc470b0ccf9668ef650aa65f\" returns successfully" Mar 20 18:00:56.440242 kubelet[1769]: E0320 18:00:56.440179 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:56.915854 kubelet[1769]: I0320 18:00:56.915792 1769 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=20.502910094 podStartE2EDuration="20.915777299s" podCreationTimestamp="2025-03-20 18:00:36 +0000 UTC" firstStartedPulling="2025-03-20 18:00:55.464292521 +0000 UTC m=+43.417941866" lastFinishedPulling="2025-03-20 18:00:55.877159726 +0000 UTC m=+43.830809071" observedRunningTime="2025-03-20 18:00:56.915638287 +0000 UTC m=+44.869287632" watchObservedRunningTime="2025-03-20 18:00:56.915777299 +0000 UTC m=+44.869426644" Mar 20 18:00:57.006050 systemd-networkd[1407]: veth95068a61: Gained IPv6LL Mar 20 18:00:57.441043 kubelet[1769]: E0320 18:00:57.440981 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:58.442192 kubelet[1769]: E0320 18:00:58.442135 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Mar 20 18:00:59.442796 kubelet[1769]: E0320 18:00:59.442715 1769 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"