May 10 10:00:01.929778 kernel: Linux version 6.6.89-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 Sat May 10 08:33:52 -00 2025 May 10 10:00:01.929812 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=cdff7a1e66558670c3a31fd90f395811dccc4cb131ce51930f033b8634f7f080 May 10 10:00:01.929824 kernel: BIOS-provided physical RAM map: May 10 10:00:01.929834 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable May 10 10:00:01.929842 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable May 10 10:00:01.929851 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS May 10 10:00:01.929862 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable May 10 10:00:01.929872 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS May 10 10:00:01.929885 kernel: BIOS-e820: [mem 0x000000000080c000-0x0000000000810fff] usable May 10 10:00:01.929894 kernel: BIOS-e820: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS May 10 10:00:01.929903 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009bd3efff] usable May 10 10:00:01.929912 kernel: BIOS-e820: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved May 10 10:00:01.929926 kernel: BIOS-e820: [mem 0x000000009be00000-0x000000009c8ecfff] usable May 10 10:00:01.929937 kernel: BIOS-e820: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved May 10 10:00:01.929952 kernel: BIOS-e820: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data May 10 10:00:01.929962 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS May 10 10:00:01.929972 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009ce90fff] usable May 10 10:00:01.929981 kernel: BIOS-e820: [mem 0x000000009ce91000-0x000000009ce94fff] reserved May 10 10:00:01.929991 kernel: BIOS-e820: [mem 0x000000009ce95000-0x000000009ce96fff] ACPI NVS May 10 10:00:01.930000 kernel: BIOS-e820: [mem 0x000000009ce97000-0x000000009cedbfff] usable May 10 10:00:01.930010 kernel: BIOS-e820: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved May 10 10:00:01.930020 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS May 10 10:00:01.930029 kernel: BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved May 10 10:00:01.930039 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved May 10 10:00:01.930052 kernel: BIOS-e820: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved May 10 10:00:01.930062 kernel: BIOS-e820: [mem 0x000000fd00000000-0x000000ffffffffff] reserved May 10 10:00:01.930071 kernel: NX (Execute Disable) protection: active May 10 10:00:01.930081 kernel: APIC: Static calls initialized May 10 10:00:01.930091 kernel: e820: update [mem 0x9b320018-0x9b329c57] usable ==> usable May 10 10:00:01.930100 kernel: e820: update [mem 0x9b320018-0x9b329c57] usable ==> usable May 10 10:00:01.930110 kernel: e820: update [mem 0x9b2e3018-0x9b31fe57] usable ==> usable May 10 10:00:01.930119 kernel: e820: update [mem 0x9b2e3018-0x9b31fe57] usable ==> usable May 10 10:00:01.930129 kernel: extended physical RAM map: May 10 10:00:01.930138 kernel: reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable May 10 10:00:01.930148 kernel: reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable May 10 10:00:01.930158 kernel: reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS May 10 10:00:01.930171 kernel: reserve setup_data: [mem 0x0000000000808000-0x000000000080afff] usable May 10 10:00:01.930181 kernel: reserve setup_data: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS May 10 10:00:01.930190 kernel: reserve setup_data: [mem 0x000000000080c000-0x0000000000810fff] usable May 10 10:00:01.930200 kernel: reserve setup_data: [mem 0x0000000000811000-0x00000000008fffff] ACPI NVS May 10 10:00:01.930215 kernel: reserve setup_data: [mem 0x0000000000900000-0x000000009b2e3017] usable May 10 10:00:01.930225 kernel: reserve setup_data: [mem 0x000000009b2e3018-0x000000009b31fe57] usable May 10 10:00:01.930238 kernel: reserve setup_data: [mem 0x000000009b31fe58-0x000000009b320017] usable May 10 10:00:01.930249 kernel: reserve setup_data: [mem 0x000000009b320018-0x000000009b329c57] usable May 10 10:00:01.930258 kernel: reserve setup_data: [mem 0x000000009b329c58-0x000000009bd3efff] usable May 10 10:00:01.930272 kernel: reserve setup_data: [mem 0x000000009bd3f000-0x000000009bdfffff] reserved May 10 10:00:01.930283 kernel: reserve setup_data: [mem 0x000000009be00000-0x000000009c8ecfff] usable May 10 10:00:01.930293 kernel: reserve setup_data: [mem 0x000000009c8ed000-0x000000009cb6cfff] reserved May 10 10:00:01.930303 kernel: reserve setup_data: [mem 0x000000009cb6d000-0x000000009cb7efff] ACPI data May 10 10:00:01.930313 kernel: reserve setup_data: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS May 10 10:00:01.930323 kernel: reserve setup_data: [mem 0x000000009cbff000-0x000000009ce90fff] usable May 10 10:00:01.930333 kernel: reserve setup_data: [mem 0x000000009ce91000-0x000000009ce94fff] reserved May 10 10:00:01.930356 kernel: reserve setup_data: [mem 0x000000009ce95000-0x000000009ce96fff] ACPI NVS May 10 10:00:01.930366 kernel: reserve setup_data: [mem 0x000000009ce97000-0x000000009cedbfff] usable May 10 10:00:01.930376 kernel: reserve setup_data: [mem 0x000000009cedc000-0x000000009cf5ffff] reserved May 10 10:00:01.930386 kernel: reserve setup_data: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS May 10 10:00:01.930396 kernel: reserve setup_data: [mem 0x00000000e0000000-0x00000000efffffff] reserved May 10 10:00:01.930406 kernel: reserve setup_data: [mem 0x00000000feffc000-0x00000000feffffff] reserved May 10 10:00:01.930420 kernel: reserve setup_data: [mem 0x00000000ffc00000-0x00000000ffffffff] reserved May 10 10:00:01.930430 kernel: reserve setup_data: [mem 0x000000fd00000000-0x000000ffffffffff] reserved May 10 10:00:01.930440 kernel: efi: EFI v2.7 by EDK II May 10 10:00:01.930450 kernel: efi: SMBIOS=0x9c988000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b9e4198 RNG=0x9cb73018 May 10 10:00:01.930461 kernel: random: crng init done May 10 10:00:01.930475 kernel: efi: Remove mem151: MMIO range=[0xffc00000-0xffffffff] (4MB) from e820 map May 10 10:00:01.930486 kernel: e820: remove [mem 0xffc00000-0xffffffff] reserved May 10 10:00:01.930496 kernel: secureboot: Secure boot disabled May 10 10:00:01.930506 kernel: SMBIOS 2.8 present. May 10 10:00:01.930516 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS unknown 02/02/2022 May 10 10:00:01.930526 kernel: Hypervisor detected: KVM May 10 10:00:01.930536 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 10 10:00:01.930546 kernel: kvm-clock: using sched offset of 4973030021 cycles May 10 10:00:01.930557 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 10 10:00:01.930568 kernel: tsc: Detected 2794.748 MHz processor May 10 10:00:01.930579 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 10 10:00:01.930593 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 10 10:00:01.930604 kernel: last_pfn = 0x9cedc max_arch_pfn = 0x400000000 May 10 10:00:01.930614 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs May 10 10:00:01.930625 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 10 10:00:01.930635 kernel: Using GB pages for direct mapping May 10 10:00:01.930645 kernel: ACPI: Early table checksum verification disabled May 10 10:00:01.930656 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) May 10 10:00:01.930666 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) May 10 10:00:01.930677 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:00:01.930691 kernel: ACPI: DSDT 0x000000009CB7A000 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:00:01.930701 kernel: ACPI: FACS 0x000000009CBDD000 000040 May 10 10:00:01.930725 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:00:01.930736 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:00:01.930747 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:00:01.930757 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 10 10:00:01.930768 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) May 10 10:00:01.930778 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] May 10 10:00:01.930793 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1a7] May 10 10:00:01.930803 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] May 10 10:00:01.930813 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] May 10 10:00:01.930824 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] May 10 10:00:01.930834 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] May 10 10:00:01.930844 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] May 10 10:00:01.930854 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] May 10 10:00:01.930864 kernel: No NUMA configuration found May 10 10:00:01.930875 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cedbfff] May 10 10:00:01.930885 kernel: NODE_DATA(0) allocated [mem 0x9ce36000-0x9ce3dfff] May 10 10:00:01.930899 kernel: Zone ranges: May 10 10:00:01.930910 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 10 10:00:01.930920 kernel: DMA32 [mem 0x0000000001000000-0x000000009cedbfff] May 10 10:00:01.930929 kernel: Normal empty May 10 10:00:01.930943 kernel: Device empty May 10 10:00:01.930954 kernel: Movable zone start for each node May 10 10:00:01.932155 kernel: Early memory node ranges May 10 10:00:01.932171 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] May 10 10:00:01.932182 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] May 10 10:00:01.932198 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] May 10 10:00:01.932209 kernel: node 0: [mem 0x000000000080c000-0x0000000000810fff] May 10 10:00:01.932220 kernel: node 0: [mem 0x0000000000900000-0x000000009bd3efff] May 10 10:00:01.932231 kernel: node 0: [mem 0x000000009be00000-0x000000009c8ecfff] May 10 10:00:01.932241 kernel: node 0: [mem 0x000000009cbff000-0x000000009ce90fff] May 10 10:00:01.932252 kernel: node 0: [mem 0x000000009ce97000-0x000000009cedbfff] May 10 10:00:01.932262 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cedbfff] May 10 10:00:01.932274 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 10 10:00:01.932299 kernel: On node 0, zone DMA: 96 pages in unavailable ranges May 10 10:00:01.932310 kernel: On node 0, zone DMA: 8 pages in unavailable ranges May 10 10:00:01.932320 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 10 10:00:01.932331 kernel: On node 0, zone DMA: 239 pages in unavailable ranges May 10 10:00:01.932353 kernel: On node 0, zone DMA32: 193 pages in unavailable ranges May 10 10:00:01.932364 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges May 10 10:00:01.932375 kernel: On node 0, zone DMA32: 6 pages in unavailable ranges May 10 10:00:01.932386 kernel: On node 0, zone DMA32: 12580 pages in unavailable ranges May 10 10:00:01.932396 kernel: ACPI: PM-Timer IO Port: 0x608 May 10 10:00:01.932407 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 10 10:00:01.932442 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 10 10:00:01.932456 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 10 10:00:01.932467 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 10 10:00:01.932478 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 10 10:00:01.932489 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 10 10:00:01.932500 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 10 10:00:01.932511 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 10 10:00:01.932521 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 May 10 10:00:01.932537 kernel: TSC deadline timer available May 10 10:00:01.932548 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs May 10 10:00:01.932559 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() May 10 10:00:01.932570 kernel: kvm-guest: KVM setup pv remote TLB flush May 10 10:00:01.932581 kernel: kvm-guest: setup PV sched yield May 10 10:00:01.932592 kernel: [mem 0x9d000000-0xdfffffff] available for PCI devices May 10 10:00:01.932602 kernel: Booting paravirtualized kernel on KVM May 10 10:00:01.932613 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 10 10:00:01.932624 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 May 10 10:00:01.932638 kernel: percpu: Embedded 58 pages/cpu s197416 r8192 d31960 u524288 May 10 10:00:01.932649 kernel: pcpu-alloc: s197416 r8192 d31960 u524288 alloc=1*2097152 May 10 10:00:01.932660 kernel: pcpu-alloc: [0] 0 1 2 3 May 10 10:00:01.932670 kernel: kvm-guest: PV spinlocks enabled May 10 10:00:01.932681 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 10 10:00:01.932693 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=cdff7a1e66558670c3a31fd90f395811dccc4cb131ce51930f033b8634f7f080 May 10 10:00:01.932705 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 10 10:00:01.932735 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 10 10:00:01.932751 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 10 10:00:01.932762 kernel: Fallback order for Node 0: 0 May 10 10:00:01.932773 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629460 May 10 10:00:01.932783 kernel: Policy zone: DMA32 May 10 10:00:01.932794 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 10 10:00:01.932805 kernel: Memory: 2391604K/2565800K available (14336K kernel code, 2309K rwdata, 9044K rodata, 53680K init, 1596K bss, 173940K reserved, 0K cma-reserved) May 10 10:00:01.932816 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 10 10:00:01.932827 kernel: ftrace: allocating 38190 entries in 150 pages May 10 10:00:01.932838 kernel: ftrace: allocated 150 pages with 4 groups May 10 10:00:01.932852 kernel: Dynamic Preempt: voluntary May 10 10:00:01.932863 kernel: rcu: Preemptible hierarchical RCU implementation. May 10 10:00:01.932875 kernel: rcu: RCU event tracing is enabled. May 10 10:00:01.932887 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 10 10:00:01.932898 kernel: Trampoline variant of Tasks RCU enabled. May 10 10:00:01.932909 kernel: Rude variant of Tasks RCU enabled. May 10 10:00:01.932920 kernel: Tracing variant of Tasks RCU enabled. May 10 10:00:01.932931 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 10 10:00:01.932941 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 10 10:00:01.932956 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 May 10 10:00:01.932967 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 10 10:00:01.932977 kernel: Console: colour dummy device 80x25 May 10 10:00:01.932988 kernel: printk: console [ttyS0] enabled May 10 10:00:01.932999 kernel: ACPI: Core revision 20230628 May 10 10:00:01.933010 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns May 10 10:00:01.933021 kernel: APIC: Switch to symmetric I/O mode setup May 10 10:00:01.933031 kernel: x2apic enabled May 10 10:00:01.933042 kernel: APIC: Switched APIC routing to: physical x2apic May 10 10:00:01.933053 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() May 10 10:00:01.933067 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() May 10 10:00:01.933078 kernel: kvm-guest: setup PV IPIs May 10 10:00:01.933089 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 10 10:00:01.933100 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized May 10 10:00:01.933111 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) May 10 10:00:01.933122 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated May 10 10:00:01.933133 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 May 10 10:00:01.933144 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 May 10 10:00:01.933158 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 10 10:00:01.933169 kernel: Spectre V2 : Mitigation: Retpolines May 10 10:00:01.933180 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 10 10:00:01.933191 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls May 10 10:00:01.933202 kernel: RETBleed: Mitigation: untrained return thunk May 10 10:00:01.933213 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 10 10:00:01.933224 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 10 10:00:01.933234 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! May 10 10:00:01.933246 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. May 10 10:00:01.933264 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode May 10 10:00:01.933275 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 10 10:00:01.933286 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 10 10:00:01.933297 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 10 10:00:01.933308 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 10 10:00:01.933319 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. May 10 10:00:01.933330 kernel: Freeing SMP alternatives memory: 32K May 10 10:00:01.933340 kernel: pid_max: default: 32768 minimum: 301 May 10 10:00:01.933360 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 10 10:00:01.933375 kernel: landlock: Up and running. May 10 10:00:01.933386 kernel: SELinux: Initializing. May 10 10:00:01.933397 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 10 10:00:01.933407 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 10 10:00:01.933418 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) May 10 10:00:01.933429 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 10 10:00:01.933440 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 10 10:00:01.933451 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 10 10:00:01.933466 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. May 10 10:00:01.933477 kernel: ... version: 0 May 10 10:00:01.933488 kernel: ... bit width: 48 May 10 10:00:01.933499 kernel: ... generic registers: 6 May 10 10:00:01.933510 kernel: ... value mask: 0000ffffffffffff May 10 10:00:01.933521 kernel: ... max period: 00007fffffffffff May 10 10:00:01.933531 kernel: ... fixed-purpose events: 0 May 10 10:00:01.933542 kernel: ... event mask: 000000000000003f May 10 10:00:01.933552 kernel: signal: max sigframe size: 1776 May 10 10:00:01.933575 kernel: rcu: Hierarchical SRCU implementation. May 10 10:00:01.933592 kernel: rcu: Max phase no-delay instances is 400. May 10 10:00:01.933603 kernel: smp: Bringing up secondary CPUs ... May 10 10:00:01.933613 kernel: smpboot: x86: Booting SMP configuration: May 10 10:00:01.933624 kernel: .... node #0, CPUs: #1 #2 #3 May 10 10:00:01.933635 kernel: smp: Brought up 1 node, 4 CPUs May 10 10:00:01.933646 kernel: smpboot: Max logical packages: 1 May 10 10:00:01.933657 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) May 10 10:00:01.933668 kernel: devtmpfs: initialized May 10 10:00:01.933679 kernel: x86/mm: Memory block size: 128MB May 10 10:00:01.934981 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) May 10 10:00:01.934994 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) May 10 10:00:01.935005 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00811000-0x008fffff] (978944 bytes) May 10 10:00:01.935017 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) May 10 10:00:01.935027 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9ce95000-0x9ce96fff] (8192 bytes) May 10 10:00:01.935038 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) May 10 10:00:01.935048 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 10 10:00:01.935059 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 10 10:00:01.935070 kernel: pinctrl core: initialized pinctrl subsystem May 10 10:00:01.935087 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 10 10:00:01.935099 kernel: audit: initializing netlink subsys (disabled) May 10 10:00:01.935110 kernel: audit: type=2000 audit(1746871199.827:1): state=initialized audit_enabled=0 res=1 May 10 10:00:01.935121 kernel: thermal_sys: Registered thermal governor 'step_wise' May 10 10:00:01.935132 kernel: thermal_sys: Registered thermal governor 'user_space' May 10 10:00:01.935143 kernel: cpuidle: using governor menu May 10 10:00:01.935154 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 10 10:00:01.935166 kernel: dca service started, version 1.12.1 May 10 10:00:01.935176 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000) May 10 10:00:01.935192 kernel: PCI: Using configuration type 1 for base access May 10 10:00:01.935203 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 10 10:00:01.935214 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 10 10:00:01.935226 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 10 10:00:01.935237 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 10 10:00:01.935248 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 10 10:00:01.935259 kernel: ACPI: Added _OSI(Module Device) May 10 10:00:01.935270 kernel: ACPI: Added _OSI(Processor Device) May 10 10:00:01.935284 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 10 10:00:01.935295 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 10 10:00:01.935307 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 10 10:00:01.935318 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 10 10:00:01.935329 kernel: ACPI: Interpreter enabled May 10 10:00:01.935340 kernel: ACPI: PM: (supports S0 S3 S5) May 10 10:00:01.935360 kernel: ACPI: Using IOAPIC for interrupt routing May 10 10:00:01.935371 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 10 10:00:01.935382 kernel: PCI: Using E820 reservations for host bridge windows May 10 10:00:01.935393 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F May 10 10:00:01.935408 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 10 10:00:01.935667 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 10 10:00:01.935829 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] May 10 10:00:01.935961 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] May 10 10:00:01.935972 kernel: PCI host bridge to bus 0000:00 May 10 10:00:01.936110 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 10 10:00:01.936235 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 10 10:00:01.936370 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 10 10:00:01.936489 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xdfffffff window] May 10 10:00:01.936606 kernel: pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfebfffff window] May 10 10:00:01.936742 kernel: pci_bus 0000:00: root bus resource [mem 0x380000000000-0x3807ffffffff window] May 10 10:00:01.936866 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 10 10:00:01.937024 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 May 10 10:00:01.937178 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 May 10 10:00:01.937310 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] May 10 10:00:01.937449 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xc1044000-0xc1044fff] May 10 10:00:01.937578 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] May 10 10:00:01.937708 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb May 10 10:00:01.937883 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 10 10:00:01.938035 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 May 10 10:00:01.938174 kernel: pci 0000:00:02.0: reg 0x10: [io 0x6100-0x611f] May 10 10:00:01.938305 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xc1043000-0xc1043fff] May 10 10:00:01.938444 kernel: pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] May 10 10:00:01.938616 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 May 10 10:00:01.938773 kernel: pci 0000:00:03.0: reg 0x10: [io 0x6000-0x607f] May 10 10:00:01.938910 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff] May 10 10:00:01.939054 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] May 10 10:00:01.939203 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 May 10 10:00:01.939334 kernel: pci 0000:00:04.0: reg 0x10: [io 0x60e0-0x60ff] May 10 10:00:01.939473 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff] May 10 10:00:01.939604 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] May 10 10:00:01.939757 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] May 10 10:00:01.939911 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 May 10 10:00:01.940052 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO May 10 10:00:01.940201 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 May 10 10:00:01.940332 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x60c0-0x60df] May 10 10:00:01.940471 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xc1040000-0xc1040fff] May 10 10:00:01.940619 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 May 10 10:00:01.940773 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6080-0x60bf] May 10 10:00:01.940785 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 10 10:00:01.940799 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 10 10:00:01.940807 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 10 10:00:01.940815 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 10 10:00:01.940823 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 May 10 10:00:01.940831 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 May 10 10:00:01.940839 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 May 10 10:00:01.940847 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 May 10 10:00:01.940856 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 May 10 10:00:01.940864 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 May 10 10:00:01.940874 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 May 10 10:00:01.940882 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 May 10 10:00:01.940891 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 May 10 10:00:01.940899 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 May 10 10:00:01.940906 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 May 10 10:00:01.940915 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 May 10 10:00:01.940923 kernel: iommu: Default domain type: Translated May 10 10:00:01.940931 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 10 10:00:01.940939 kernel: efivars: Registered efivars operations May 10 10:00:01.940950 kernel: PCI: Using ACPI for IRQ routing May 10 10:00:01.940958 kernel: PCI: pci_cache_line_size set to 64 bytes May 10 10:00:01.940966 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] May 10 10:00:01.940974 kernel: e820: reserve RAM buffer [mem 0x00811000-0x008fffff] May 10 10:00:01.940982 kernel: e820: reserve RAM buffer [mem 0x9b2e3018-0x9bffffff] May 10 10:00:01.940990 kernel: e820: reserve RAM buffer [mem 0x9b320018-0x9bffffff] May 10 10:00:01.940998 kernel: e820: reserve RAM buffer [mem 0x9bd3f000-0x9bffffff] May 10 10:00:01.941008 kernel: e820: reserve RAM buffer [mem 0x9c8ed000-0x9fffffff] May 10 10:00:01.941038 kernel: e820: reserve RAM buffer [mem 0x9ce91000-0x9fffffff] May 10 10:00:01.941046 kernel: e820: reserve RAM buffer [mem 0x9cedc000-0x9fffffff] May 10 10:00:01.941264 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device May 10 10:00:01.941408 kernel: pci 0000:00:01.0: vgaarb: bridge control possible May 10 10:00:01.941541 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 10 10:00:01.941552 kernel: vgaarb: loaded May 10 10:00:01.941560 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 May 10 10:00:01.941569 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter May 10 10:00:01.941577 kernel: clocksource: Switched to clocksource kvm-clock May 10 10:00:01.941591 kernel: VFS: Disk quotas dquot_6.6.0 May 10 10:00:01.941600 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 10 10:00:01.941608 kernel: pnp: PnP ACPI init May 10 10:00:01.941797 kernel: system 00:05: [mem 0xe0000000-0xefffffff window] has been reserved May 10 10:00:01.941812 kernel: pnp: PnP ACPI: found 6 devices May 10 10:00:01.941821 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 10 10:00:01.941829 kernel: NET: Registered PF_INET protocol family May 10 10:00:01.941858 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 10 10:00:01.941871 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 10 10:00:01.941880 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 10 10:00:01.941888 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 10 10:00:01.941896 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 10 10:00:01.941905 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 10 10:00:01.941914 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 10 10:00:01.941922 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 10 10:00:01.941931 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 10 10:00:01.941939 kernel: NET: Registered PF_XDP protocol family May 10 10:00:01.942079 kernel: pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window May 10 10:00:01.942212 kernel: pci 0000:00:04.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref] May 10 10:00:01.942332 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 10 10:00:01.942462 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 10 10:00:01.942581 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 10 10:00:01.942699 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xdfffffff window] May 10 10:00:01.942843 kernel: pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfebfffff window] May 10 10:00:01.942969 kernel: pci_bus 0000:00: resource 9 [mem 0x380000000000-0x3807ffffffff window] May 10 10:00:01.942985 kernel: PCI: CLS 0 bytes, default 64 May 10 10:00:01.942993 kernel: Initialise system trusted keyrings May 10 10:00:01.943002 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 10 10:00:01.943010 kernel: Key type asymmetric registered May 10 10:00:01.943019 kernel: Asymmetric key parser 'x509' registered May 10 10:00:01.943027 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 10 10:00:01.943035 kernel: io scheduler mq-deadline registered May 10 10:00:01.943044 kernel: io scheduler kyber registered May 10 10:00:01.943052 kernel: io scheduler bfq registered May 10 10:00:01.943063 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 10 10:00:01.943072 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 May 10 10:00:01.943080 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 May 10 10:00:01.943092 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 May 10 10:00:01.943100 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 10 10:00:01.943111 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 10 10:00:01.943120 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 10 10:00:01.943128 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 10 10:00:01.943136 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 10 10:00:01.943306 kernel: rtc_cmos 00:04: RTC can wake from S4 May 10 10:00:01.943319 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 10 10:00:01.943452 kernel: rtc_cmos 00:04: registered as rtc0 May 10 10:00:01.943576 kernel: rtc_cmos 00:04: setting system clock to 2025-05-10T10:00:01 UTC (1746871201) May 10 10:00:01.943705 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram May 10 10:00:01.943733 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled May 10 10:00:01.943745 kernel: efifb: probing for efifb May 10 10:00:01.943753 kernel: efifb: framebuffer at 0xc0000000, using 4000k, total 4000k May 10 10:00:01.943762 kernel: efifb: mode is 1280x800x32, linelength=5120, pages=1 May 10 10:00:01.943770 kernel: efifb: scrolling: redraw May 10 10:00:01.943778 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 10 10:00:01.943787 kernel: Console: switching to colour frame buffer device 160x50 May 10 10:00:01.943795 kernel: fb0: EFI VGA frame buffer device May 10 10:00:01.943808 kernel: pstore: Using crash dump compression: deflate May 10 10:00:01.943816 kernel: pstore: Registered efi_pstore as persistent store backend May 10 10:00:01.943825 kernel: NET: Registered PF_INET6 protocol family May 10 10:00:01.943833 kernel: Segment Routing with IPv6 May 10 10:00:01.943841 kernel: In-situ OAM (IOAM) with IPv6 May 10 10:00:01.943850 kernel: NET: Registered PF_PACKET protocol family May 10 10:00:01.943858 kernel: Key type dns_resolver registered May 10 10:00:01.943866 kernel: IPI shorthand broadcast: enabled May 10 10:00:01.943874 kernel: sched_clock: Marking stable (3531003766, 158638456)->(3715824357, -26182135) May 10 10:00:01.943885 kernel: registered taskstats version 1 May 10 10:00:01.943894 kernel: Loading compiled-in X.509 certificates May 10 10:00:01.943902 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.89-flatcar: f8080549509982706805ea0b811f8f4bcb4a274e' May 10 10:00:01.943910 kernel: Key type .fscrypt registered May 10 10:00:01.943918 kernel: Key type fscrypt-provisioning registered May 10 10:00:01.943928 kernel: ima: No TPM chip found, activating TPM-bypass! May 10 10:00:01.943938 kernel: ima: Allocated hash algorithm: sha1 May 10 10:00:01.943947 kernel: ima: No architecture policies found May 10 10:00:01.943957 kernel: clk: Disabling unused clocks May 10 10:00:01.943967 kernel: Warning: unable to open an initial console. May 10 10:00:01.943976 kernel: Freeing unused kernel image (initmem) memory: 53680K May 10 10:00:01.943985 kernel: Write protecting the kernel read-only data: 24576k May 10 10:00:01.943993 kernel: Freeing unused kernel image (rodata/data gap) memory: 1196K May 10 10:00:01.944001 kernel: Run /init as init process May 10 10:00:01.944010 kernel: with arguments: May 10 10:00:01.944018 kernel: /init May 10 10:00:01.944026 kernel: with environment: May 10 10:00:01.944034 kernel: HOME=/ May 10 10:00:01.944045 kernel: TERM=linux May 10 10:00:01.944053 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 10 10:00:01.944063 systemd[1]: Successfully made /usr/ read-only. May 10 10:00:01.944074 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) May 10 10:00:01.944084 systemd[1]: Detected virtualization kvm. May 10 10:00:01.944093 systemd[1]: Detected architecture x86-64. May 10 10:00:01.944101 systemd[1]: Running in initrd. May 10 10:00:01.944110 systemd[1]: No hostname configured, using default hostname. May 10 10:00:01.944121 systemd[1]: Hostname set to . May 10 10:00:01.944130 systemd[1]: Initializing machine ID from VM UUID. May 10 10:00:01.944139 systemd[1]: Queued start job for default target initrd.target. May 10 10:00:01.944148 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 10 10:00:01.944157 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 10 10:00:01.944166 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 10 10:00:01.944175 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 10 10:00:01.944184 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 10 10:00:01.944196 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 10 10:00:01.944206 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 10 10:00:01.944215 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 10 10:00:01.944224 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 10 10:00:01.944236 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 10 10:00:01.944245 systemd[1]: Reached target paths.target - Path Units. May 10 10:00:01.944256 systemd[1]: Reached target slices.target - Slice Units. May 10 10:00:01.944264 systemd[1]: Reached target swap.target - Swaps. May 10 10:00:01.944273 systemd[1]: Reached target timers.target - Timer Units. May 10 10:00:01.944284 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 10 10:00:01.944293 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 10 10:00:01.944302 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 10 10:00:01.944311 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 10 10:00:01.944320 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 10 10:00:01.944329 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 10 10:00:01.944340 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 10 10:00:01.944374 systemd[1]: Reached target sockets.target - Socket Units. May 10 10:00:01.944383 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 10 10:00:01.944392 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 10 10:00:01.944401 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 10 10:00:01.944410 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). May 10 10:00:01.944419 systemd[1]: Starting systemd-fsck-usr.service... May 10 10:00:01.944428 systemd[1]: Starting systemd-journald.service - Journal Service... May 10 10:00:01.944440 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 10 10:00:01.944449 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 10 10:00:01.944458 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 10 10:00:01.944468 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 10 10:00:01.944477 systemd[1]: Finished systemd-fsck-usr.service. May 10 10:00:01.944488 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 10 10:00:01.944526 systemd-journald[195]: Collecting audit messages is disabled. May 10 10:00:01.944547 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 10 10:00:01.944557 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 10 10:00:01.944569 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:00:01.944578 systemd-journald[195]: Journal started May 10 10:00:01.944597 systemd-journald[195]: Runtime Journal (/run/log/journal/911a4f0f20614d60b6c1e747ac10655b) is 6M, max 48.5M, 42.4M free. May 10 10:00:01.949252 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 10 10:00:01.918649 systemd-modules-load[196]: Inserted module 'overlay' May 10 10:00:01.952641 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 10 10:00:01.956115 systemd-modules-load[196]: Inserted module 'br_netfilter' May 10 10:00:01.957189 kernel: Bridge firewalling registered May 10 10:00:01.957209 systemd[1]: Started systemd-journald.service - Journal Service. May 10 10:00:01.959226 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 10 10:00:01.961691 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 10 10:00:01.964695 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 10 10:00:01.965417 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 10 10:00:01.985628 systemd-tmpfiles[220]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 10 10:00:01.989970 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 10 10:00:01.992271 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 10 10:00:01.994672 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 10 10:00:02.009976 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 10 10:00:02.011154 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 10 10:00:02.030442 dracut-cmdline[237]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=cdff7a1e66558670c3a31fd90f395811dccc4cb131ce51930f033b8634f7f080 May 10 10:00:02.057353 systemd-resolved[226]: Positive Trust Anchors: May 10 10:00:02.057369 systemd-resolved[226]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 10 10:00:02.057402 systemd-resolved[226]: 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 May 10 10:00:02.068190 systemd-resolved[226]: Defaulting to hostname 'linux'. May 10 10:00:02.070286 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 10 10:00:02.070437 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 10 10:00:02.125754 kernel: SCSI subsystem initialized May 10 10:00:02.135742 kernel: Loading iSCSI transport class v2.0-870. May 10 10:00:02.146746 kernel: iscsi: registered transport (tcp) May 10 10:00:02.168763 kernel: iscsi: registered transport (qla4xxx) May 10 10:00:02.168795 kernel: QLogic iSCSI HBA Driver May 10 10:00:02.191737 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 10 10:00:02.215044 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 10 10:00:02.219015 systemd[1]: Reached target network-pre.target - Preparation for Network. May 10 10:00:02.277209 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 10 10:00:02.280951 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 10 10:00:02.342752 kernel: raid6: avx2x4 gen() 22533 MB/s May 10 10:00:02.359747 kernel: raid6: avx2x2 gen() 27249 MB/s May 10 10:00:02.377086 kernel: raid6: avx2x1 gen() 18541 MB/s May 10 10:00:02.377118 kernel: raid6: using algorithm avx2x2 gen() 27249 MB/s May 10 10:00:02.394988 kernel: raid6: .... xor() 15447 MB/s, rmw enabled May 10 10:00:02.395019 kernel: raid6: using avx2x2 recovery algorithm May 10 10:00:02.417752 kernel: xor: automatically using best checksumming function avx May 10 10:00:02.569755 kernel: Btrfs loaded, zoned=no, fsverity=no May 10 10:00:02.579494 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 10 10:00:02.583794 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 10 10:00:02.618548 systemd-udevd[445]: Using default interface naming scheme 'v255'. May 10 10:00:02.624702 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 10 10:00:02.628783 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 10 10:00:02.658645 dracut-pre-trigger[453]: rd.md=0: removing MD RAID activation May 10 10:00:02.694074 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 10 10:00:02.697401 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 10 10:00:02.788979 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 10 10:00:02.792528 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 10 10:00:02.829787 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues May 10 10:00:02.835909 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 10 10:00:02.842208 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 10 10:00:02.842239 kernel: GPT:9289727 != 19775487 May 10 10:00:02.842254 kernel: GPT:Alternate GPT header not at the end of the disk. May 10 10:00:02.842269 kernel: GPT:9289727 != 19775487 May 10 10:00:02.842281 kernel: GPT: Use GNU Parted to correct GPT errors. May 10 10:00:02.842295 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 10 10:00:02.853743 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 May 10 10:00:02.857793 kernel: cryptd: max_cpu_qlen set to 1000 May 10 10:00:02.869761 kernel: libata version 3.00 loaded. May 10 10:00:02.874134 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 10 10:00:02.874436 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:00:02.878775 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 10 10:00:02.882754 kernel: AVX2 version of gcm_enc/dec engaged. May 10 10:00:02.882787 kernel: AES CTR mode by8 optimization enabled May 10 10:00:02.882946 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 10 10:00:02.887958 kernel: ahci 0000:00:1f.2: version 3.0 May 10 10:00:02.888203 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 May 10 10:00:02.889639 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode May 10 10:00:02.889835 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only May 10 10:00:02.894738 kernel: scsi host0: ahci May 10 10:00:02.894969 kernel: scsi host1: ahci May 10 10:00:02.895911 kernel: scsi host2: ahci May 10 10:00:02.896741 kernel: scsi host3: ahci May 10 10:00:02.896954 kernel: scsi host4: ahci May 10 10:00:02.897496 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 10 10:00:02.899106 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:00:02.904781 kernel: scsi host5: ahci May 10 10:00:02.905012 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 May 10 10:00:02.905026 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 May 10 10:00:02.905036 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 May 10 10:00:02.902940 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 10 10:00:02.913591 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 May 10 10:00:02.913609 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 May 10 10:00:02.913619 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 May 10 10:00:02.915088 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 10 10:00:02.924752 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (491) May 10 10:00:02.926751 kernel: BTRFS: device fsid 447a9416-2d70-470c-8858-df3b82fa5271 devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (503) May 10 10:00:02.942725 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 10 10:00:02.951918 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 10 10:00:02.953440 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:00:02.965367 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 10 10:00:02.973967 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 10 10:00:02.975294 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 10 10:00:02.979523 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 10 10:00:03.003787 disk-uuid[586]: Primary Header is updated. May 10 10:00:03.003787 disk-uuid[586]: Secondary Entries is updated. May 10 10:00:03.003787 disk-uuid[586]: Secondary Header is updated. May 10 10:00:03.008743 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 10 10:00:03.014743 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 10 10:00:03.213773 kernel: ata4: SATA link down (SStatus 0 SControl 300) May 10 10:00:03.213906 kernel: ata2: SATA link down (SStatus 0 SControl 300) May 10 10:00:03.214768 kernel: ata1: SATA link down (SStatus 0 SControl 300) May 10 10:00:03.221761 kernel: ata6: SATA link down (SStatus 0 SControl 300) May 10 10:00:03.221852 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 10 10:00:03.222759 kernel: ata5: SATA link down (SStatus 0 SControl 300) May 10 10:00:03.223752 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 May 10 10:00:03.224983 kernel: ata3.00: applying bridge limits May 10 10:00:03.225046 kernel: ata3.00: configured for UDMA/100 May 10 10:00:03.227754 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 May 10 10:00:03.298757 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray May 10 10:00:03.299132 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 10 10:00:03.316804 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 May 10 10:00:03.606755 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 10 10:00:03.607540 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 10 10:00:03.610963 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 10 10:00:03.611221 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 10 10:00:03.612737 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 10 10:00:03.644839 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 10 10:00:04.016758 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 10 10:00:04.017128 disk-uuid[587]: The operation has completed successfully. May 10 10:00:04.056502 systemd[1]: disk-uuid.service: Deactivated successfully. May 10 10:00:04.056672 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 10 10:00:04.093371 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 10 10:00:04.115161 sh[628]: Success May 10 10:00:04.134016 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 10 10:00:04.134088 kernel: device-mapper: uevent: version 1.0.3 May 10 10:00:04.135082 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 10 10:00:04.144744 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" May 10 10:00:04.180249 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 10 10:00:04.183025 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 10 10:00:04.201216 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 10 10:00:04.208197 kernel: BTRFS info (device dm-0): first mount of filesystem 447a9416-2d70-470c-8858-df3b82fa5271 May 10 10:00:04.208238 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 10 10:00:04.208250 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 10 10:00:04.209200 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 10 10:00:04.209928 kernel: BTRFS info (device dm-0): using free space tree May 10 10:00:04.214367 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 10 10:00:04.215852 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 10 10:00:04.217429 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 10 10:00:04.218438 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 10 10:00:04.221177 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 10 10:00:04.251156 kernel: BTRFS info (device vda6): first mount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:00:04.251229 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 10 10:00:04.251245 kernel: BTRFS info (device vda6): using free space tree May 10 10:00:04.254743 kernel: BTRFS info (device vda6): auto enabling async discard May 10 10:00:04.260759 kernel: BTRFS info (device vda6): last unmount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:00:04.266506 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 10 10:00:04.269836 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 10 10:00:04.370560 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 10 10:00:04.371792 ignition[720]: Ignition 2.21.0 May 10 10:00:04.371801 ignition[720]: Stage: fetch-offline May 10 10:00:04.374485 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 10 10:00:04.371848 ignition[720]: no configs at "/usr/lib/ignition/base.d" May 10 10:00:04.371861 ignition[720]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:00:04.371973 ignition[720]: parsed url from cmdline: "" May 10 10:00:04.371978 ignition[720]: no config URL provided May 10 10:00:04.371985 ignition[720]: reading system config file "/usr/lib/ignition/user.ign" May 10 10:00:04.371996 ignition[720]: no config at "/usr/lib/ignition/user.ign" May 10 10:00:04.372025 ignition[720]: op(1): [started] loading QEMU firmware config module May 10 10:00:04.372032 ignition[720]: op(1): executing: "modprobe" "qemu_fw_cfg" May 10 10:00:04.491756 ignition[720]: op(1): [finished] loading QEMU firmware config module May 10 10:00:04.493375 ignition[720]: parsing config with SHA512: 6cb8c24b1e6f78db517a5d00da7ef1577025265dc6ec45191a5c6bebe43b84a3ae5155f36dfa0ec45331db707d7b92066aa920032c141a5b3d64dbd0b204136e May 10 10:00:04.497342 unknown[720]: fetched base config from "system" May 10 10:00:04.497360 unknown[720]: fetched user config from "qemu" May 10 10:00:04.497612 ignition[720]: fetch-offline: fetch-offline passed May 10 10:00:04.497668 ignition[720]: Ignition finished successfully May 10 10:00:04.505396 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 10 10:00:04.532782 systemd-networkd[817]: lo: Link UP May 10 10:00:04.532792 systemd-networkd[817]: lo: Gained carrier May 10 10:00:04.534936 systemd-networkd[817]: Enumeration completed May 10 10:00:04.535079 systemd[1]: Started systemd-networkd.service - Network Configuration. May 10 10:00:04.535455 systemd-networkd[817]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 10 10:00:04.535461 systemd-networkd[817]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 10 10:00:04.536469 systemd-networkd[817]: eth0: Link UP May 10 10:00:04.536473 systemd-networkd[817]: eth0: Gained carrier May 10 10:00:04.536484 systemd-networkd[817]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 10 10:00:04.537677 systemd[1]: Reached target network.target - Network. May 10 10:00:04.539647 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 10 10:00:04.540568 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 10 10:00:04.554767 systemd-networkd[817]: eth0: DHCPv4 address 10.0.0.72/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 10 10:00:04.578847 ignition[821]: Ignition 2.21.0 May 10 10:00:04.578865 ignition[821]: Stage: kargs May 10 10:00:04.579056 ignition[821]: no configs at "/usr/lib/ignition/base.d" May 10 10:00:04.579069 ignition[821]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:00:04.579759 ignition[821]: kargs: kargs passed May 10 10:00:04.579809 ignition[821]: Ignition finished successfully May 10 10:00:04.585143 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 10 10:00:04.588649 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 10 10:00:04.620324 ignition[830]: Ignition 2.21.0 May 10 10:00:04.620340 ignition[830]: Stage: disks May 10 10:00:04.620511 ignition[830]: no configs at "/usr/lib/ignition/base.d" May 10 10:00:04.620524 ignition[830]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:00:04.623455 ignition[830]: disks: disks passed May 10 10:00:04.623534 ignition[830]: Ignition finished successfully May 10 10:00:04.627162 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 10 10:00:04.629468 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 10 10:00:04.630645 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 10 10:00:04.632973 systemd[1]: Reached target local-fs.target - Local File Systems. May 10 10:00:04.635299 systemd[1]: Reached target sysinit.target - System Initialization. May 10 10:00:04.635367 systemd[1]: Reached target basic.target - Basic System. May 10 10:00:04.636817 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 10 10:00:04.665923 systemd-fsck[840]: ROOT: clean, 15/553520 files, 52789/553472 blocks May 10 10:00:04.672669 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 10 10:00:04.676673 systemd[1]: Mounting sysroot.mount - /sysroot... May 10 10:00:04.809759 kernel: EXT4-fs (vda9): mounted filesystem f8cce592-76ea-4219-9560-1ef21b28761f r/w with ordered data mode. Quota mode: none. May 10 10:00:04.810958 systemd[1]: Mounted sysroot.mount - /sysroot. May 10 10:00:04.811771 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 10 10:00:04.815444 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 10 10:00:04.817309 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 10 10:00:04.818409 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 10 10:00:04.818454 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 10 10:00:04.818480 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 10 10:00:04.835798 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 10 10:00:04.840132 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 10 10:00:04.842507 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (849) May 10 10:00:04.845160 kernel: BTRFS info (device vda6): first mount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:00:04.845202 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 10 10:00:04.845217 kernel: BTRFS info (device vda6): using free space tree May 10 10:00:04.847760 kernel: BTRFS info (device vda6): auto enabling async discard May 10 10:00:04.862604 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 10 10:00:04.898118 initrd-setup-root[873]: cut: /sysroot/etc/passwd: No such file or directory May 10 10:00:04.903826 initrd-setup-root[880]: cut: /sysroot/etc/group: No such file or directory May 10 10:00:04.908254 initrd-setup-root[887]: cut: /sysroot/etc/shadow: No such file or directory May 10 10:00:04.912230 initrd-setup-root[894]: cut: /sysroot/etc/gshadow: No such file or directory May 10 10:00:05.009981 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 10 10:00:05.013283 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 10 10:00:05.016229 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 10 10:00:05.040771 kernel: BTRFS info (device vda6): last unmount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:00:05.055980 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 10 10:00:05.100847 ignition[963]: INFO : Ignition 2.21.0 May 10 10:00:05.100847 ignition[963]: INFO : Stage: mount May 10 10:00:05.102826 ignition[963]: INFO : no configs at "/usr/lib/ignition/base.d" May 10 10:00:05.102826 ignition[963]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:00:05.106799 ignition[963]: INFO : mount: mount passed May 10 10:00:05.106799 ignition[963]: INFO : Ignition finished successfully May 10 10:00:05.110054 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 10 10:00:05.113852 systemd[1]: Starting ignition-files.service - Ignition (files)... May 10 10:00:05.208312 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 10 10:00:05.211598 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 10 10:00:05.235322 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (975) May 10 10:00:05.235386 kernel: BTRFS info (device vda6): first mount of filesystem b607f6a7-c99c-4217-b084-4c38060efb12 May 10 10:00:05.235401 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 10 10:00:05.236332 kernel: BTRFS info (device vda6): using free space tree May 10 10:00:05.239747 kernel: BTRFS info (device vda6): auto enabling async discard May 10 10:00:05.242074 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 10 10:00:05.288749 ignition[992]: INFO : Ignition 2.21.0 May 10 10:00:05.288749 ignition[992]: INFO : Stage: files May 10 10:00:05.291065 ignition[992]: INFO : no configs at "/usr/lib/ignition/base.d" May 10 10:00:05.291065 ignition[992]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:00:05.291065 ignition[992]: DEBUG : files: compiled without relabeling support, skipping May 10 10:00:05.295511 ignition[992]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 10 10:00:05.295511 ignition[992]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 10 10:00:05.299109 ignition[992]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 10 10:00:05.299109 ignition[992]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 10 10:00:05.299109 ignition[992]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 10 10:00:05.299109 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 10 10:00:05.299109 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 10 10:00:05.299109 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 10 10:00:05.299109 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 10 10:00:05.299109 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 10 10:00:05.299109 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 10 10:00:05.299109 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 10 10:00:05.296456 unknown[992]: wrote ssh authorized keys file for user: core May 10 10:00:05.321151 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.31.0-x86-64.raw: attempt #1 May 10 10:00:05.655472 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 10 10:00:05.815878 systemd-networkd[817]: eth0: Gained IPv6LL May 10 10:00:05.924287 ignition[992]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.31.0-x86-64.raw" May 10 10:00:05.924287 ignition[992]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 10 10:00:05.928175 ignition[992]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 10 10:00:05.928175 ignition[992]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 10 10:00:05.928175 ignition[992]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 10 10:00:05.928175 ignition[992]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" May 10 10:00:05.951704 ignition[992]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" May 10 10:00:05.956806 ignition[992]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 10 10:00:05.958789 ignition[992]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" May 10 10:00:05.958789 ignition[992]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 10 10:00:05.958789 ignition[992]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 10 10:00:05.958789 ignition[992]: INFO : files: files passed May 10 10:00:05.958789 ignition[992]: INFO : Ignition finished successfully May 10 10:00:05.961266 systemd[1]: Finished ignition-files.service - Ignition (files). May 10 10:00:05.964881 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 10 10:00:05.967702 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 10 10:00:05.978154 systemd[1]: ignition-quench.service: Deactivated successfully. May 10 10:00:05.979248 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 10 10:00:05.981706 initrd-setup-root-after-ignition[1022]: grep: /sysroot/oem/oem-release: No such file or directory May 10 10:00:05.983193 initrd-setup-root-after-ignition[1024]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 10 10:00:05.983193 initrd-setup-root-after-ignition[1024]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 10 10:00:05.988487 initrd-setup-root-after-ignition[1028]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 10 10:00:05.990872 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 10 10:00:05.992634 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 10 10:00:05.996132 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 10 10:00:06.076854 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 10 10:00:06.077027 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 10 10:00:06.079989 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 10 10:00:06.082506 systemd[1]: Reached target initrd.target - Initrd Default Target. May 10 10:00:06.084899 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 10 10:00:06.086275 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 10 10:00:06.118629 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 10 10:00:06.122833 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 10 10:00:06.148812 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 10 10:00:06.149076 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 10 10:00:06.149513 systemd[1]: Stopped target timers.target - Timer Units. May 10 10:00:06.150180 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 10 10:00:06.150393 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 10 10:00:06.151351 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 10 10:00:06.151856 systemd[1]: Stopped target basic.target - Basic System. May 10 10:00:06.152343 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 10 10:00:06.152842 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 10 10:00:06.153331 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 10 10:00:06.153644 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 10 10:00:06.154157 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 10 10:00:06.154483 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 10 10:00:06.154841 systemd[1]: Stopped target sysinit.target - System Initialization. May 10 10:00:06.155335 systemd[1]: Stopped target local-fs.target - Local File Systems. May 10 10:00:06.155658 systemd[1]: Stopped target swap.target - Swaps. May 10 10:00:06.156112 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 10 10:00:06.156279 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 10 10:00:06.156981 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 10 10:00:06.157320 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 10 10:00:06.157601 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 10 10:00:06.216986 ignition[1048]: INFO : Ignition 2.21.0 May 10 10:00:06.216986 ignition[1048]: INFO : Stage: umount May 10 10:00:06.216986 ignition[1048]: INFO : no configs at "/usr/lib/ignition/base.d" May 10 10:00:06.216986 ignition[1048]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 10 10:00:06.216986 ignition[1048]: INFO : umount: umount passed May 10 10:00:06.216986 ignition[1048]: INFO : Ignition finished successfully May 10 10:00:06.157819 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 10 10:00:06.158115 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 10 10:00:06.158282 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 10 10:00:06.158956 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 10 10:00:06.159122 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 10 10:00:06.159560 systemd[1]: Stopped target paths.target - Path Units. May 10 10:00:06.160112 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 10 10:00:06.164808 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 10 10:00:06.165366 systemd[1]: Stopped target slices.target - Slice Units. May 10 10:00:06.165761 systemd[1]: Stopped target sockets.target - Socket Units. May 10 10:00:06.166165 systemd[1]: iscsid.socket: Deactivated successfully. May 10 10:00:06.166335 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 10 10:00:06.166804 systemd[1]: iscsiuio.socket: Deactivated successfully. May 10 10:00:06.166942 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 10 10:00:06.167406 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 10 10:00:06.167568 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 10 10:00:06.168197 systemd[1]: ignition-files.service: Deactivated successfully. May 10 10:00:06.168363 systemd[1]: Stopped ignition-files.service - Ignition (files). May 10 10:00:06.169991 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 10 10:00:06.170321 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 10 10:00:06.170495 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 10 10:00:06.172224 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 10 10:00:06.172555 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 10 10:00:06.172708 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 10 10:00:06.173282 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 10 10:00:06.173441 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 10 10:00:06.198252 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 10 10:00:06.198437 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 10 10:00:06.219620 systemd[1]: ignition-mount.service: Deactivated successfully. May 10 10:00:06.219817 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 10 10:00:06.222035 systemd[1]: Stopped target network.target - Network. May 10 10:00:06.224267 systemd[1]: ignition-disks.service: Deactivated successfully. May 10 10:00:06.224348 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 10 10:00:06.226782 systemd[1]: ignition-kargs.service: Deactivated successfully. May 10 10:00:06.226838 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 10 10:00:06.229153 systemd[1]: ignition-setup.service: Deactivated successfully. May 10 10:00:06.229221 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 10 10:00:06.232171 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 10 10:00:06.232222 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 10 10:00:06.232496 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 10 10:00:06.233136 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 10 10:00:06.234798 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 10 10:00:06.240189 systemd[1]: systemd-resolved.service: Deactivated successfully. May 10 10:00:06.240427 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 10 10:00:06.246089 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 10 10:00:06.246630 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 10 10:00:06.246703 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 10 10:00:06.249870 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 10 10:00:06.250209 systemd[1]: systemd-networkd.service: Deactivated successfully. May 10 10:00:06.250388 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 10 10:00:06.254569 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 10 10:00:06.255475 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 10 10:00:06.257089 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 10 10:00:06.257161 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 10 10:00:06.263512 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 10 10:00:06.265032 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 10 10:00:06.265111 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 10 10:00:06.267878 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 10 10:00:06.267952 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 10 10:00:06.270462 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 10 10:00:06.270533 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 10 10:00:06.272953 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 10 10:00:06.277480 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 10 10:00:06.301376 systemd[1]: systemd-udevd.service: Deactivated successfully. May 10 10:00:06.301636 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 10 10:00:06.304744 systemd[1]: network-cleanup.service: Deactivated successfully. May 10 10:00:06.304891 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 10 10:00:06.308085 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 10 10:00:06.308192 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 10 10:00:06.309798 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 10 10:00:06.309855 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 10 10:00:06.312182 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 10 10:00:06.312268 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 10 10:00:06.314791 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 10 10:00:06.314859 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 10 10:00:06.317309 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 10 10:00:06.317369 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 10 10:00:06.321339 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 10 10:00:06.323618 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 10 10:00:06.323692 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 10 10:00:06.326502 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 10 10:00:06.326573 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 10 10:00:06.329464 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 10 10:00:06.329531 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:00:06.346366 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 10 10:00:06.346525 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 10 10:00:06.426247 systemd[1]: sysroot-boot.service: Deactivated successfully. May 10 10:00:06.426402 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 10 10:00:06.429547 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 10 10:00:06.431645 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 10 10:00:06.431710 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 10 10:00:06.435793 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 10 10:00:06.460176 systemd[1]: Switching root. May 10 10:00:06.501196 systemd-journald[195]: Journal stopped May 10 10:00:07.843854 systemd-journald[195]: Received SIGTERM from PID 1 (systemd). May 10 10:00:07.843928 kernel: SELinux: policy capability network_peer_controls=1 May 10 10:00:07.843953 kernel: SELinux: policy capability open_perms=1 May 10 10:00:07.843969 kernel: SELinux: policy capability extended_socket_class=1 May 10 10:00:07.843982 kernel: SELinux: policy capability always_check_network=0 May 10 10:00:07.843994 kernel: SELinux: policy capability cgroup_seclabel=1 May 10 10:00:07.844005 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 10 10:00:07.844017 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 10 10:00:07.844029 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 10 10:00:07.844040 kernel: audit: type=1403 audit(1746871206.890:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 10 10:00:07.844054 systemd[1]: Successfully loaded SELinux policy in 42.537ms. May 10 10:00:07.844078 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 20.665ms. May 10 10:00:07.844092 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) May 10 10:00:07.844110 systemd[1]: Detected virtualization kvm. May 10 10:00:07.844122 systemd[1]: Detected architecture x86-64. May 10 10:00:07.844135 systemd[1]: Detected first boot. May 10 10:00:07.844148 systemd[1]: Initializing machine ID from VM UUID. May 10 10:00:07.844160 zram_generator::config[1093]: No configuration found. May 10 10:00:07.844180 kernel: Guest personality initialized and is inactive May 10 10:00:07.844203 kernel: VMCI host device registered (name=vmci, major=10, minor=125) May 10 10:00:07.844215 kernel: Initialized host personality May 10 10:00:07.844227 kernel: NET: Registered PF_VSOCK protocol family May 10 10:00:07.844239 systemd[1]: Populated /etc with preset unit settings. May 10 10:00:07.844258 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 10 10:00:07.844270 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 10 10:00:07.844282 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 10 10:00:07.844295 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 10 10:00:07.844307 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 10 10:00:07.844323 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 10 10:00:07.844335 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 10 10:00:07.844348 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 10 10:00:07.844360 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 10 10:00:07.844373 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 10 10:00:07.844386 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 10 10:00:07.844399 systemd[1]: Created slice user.slice - User and Session Slice. May 10 10:00:07.844411 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 10 10:00:07.844427 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 10 10:00:07.844440 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 10 10:00:07.844452 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 10 10:00:07.844465 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 10 10:00:07.844478 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 10 10:00:07.844491 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 10 10:00:07.844503 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 10 10:00:07.844516 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 10 10:00:07.844532 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 10 10:00:07.844544 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 10 10:00:07.844556 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 10 10:00:07.844569 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 10 10:00:07.844581 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 10 10:00:07.844593 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 10 10:00:07.844617 systemd[1]: Reached target slices.target - Slice Units. May 10 10:00:07.844630 systemd[1]: Reached target swap.target - Swaps. May 10 10:00:07.844643 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 10 10:00:07.844659 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 10 10:00:07.844672 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 10 10:00:07.844684 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 10 10:00:07.844697 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 10 10:00:07.844709 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 10 10:00:07.844734 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 10 10:00:07.844746 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 10 10:00:07.844758 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 10 10:00:07.844770 systemd[1]: Mounting media.mount - External Media Directory... May 10 10:00:07.844793 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:00:07.844805 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 10 10:00:07.844818 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 10 10:00:07.844830 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 10 10:00:07.844843 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). May 10 10:00:07.844855 systemd[1]: Reached target machines.target - Containers. May 10 10:00:07.844868 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 10 10:00:07.844886 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 10 10:00:07.844901 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 10 10:00:07.844922 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 10 10:00:07.844935 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 10 10:00:07.844947 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 10 10:00:07.844960 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 10 10:00:07.844973 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 10 10:00:07.844985 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 10 10:00:07.844998 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 10 10:00:07.845010 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 10 10:00:07.845026 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 10 10:00:07.845039 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 10 10:00:07.845051 systemd[1]: Stopped systemd-fsck-usr.service. May 10 10:00:07.845065 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). May 10 10:00:07.845077 systemd[1]: Starting systemd-journald.service - Journal Service... May 10 10:00:07.845089 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 10 10:00:07.845105 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 10 10:00:07.845118 kernel: fuse: init (API version 7.39) May 10 10:00:07.845131 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 10 10:00:07.845143 kernel: loop: module loaded May 10 10:00:07.845155 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 10 10:00:07.845168 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 10 10:00:07.845180 systemd[1]: verity-setup.service: Deactivated successfully. May 10 10:00:07.845207 systemd[1]: Stopped verity-setup.service. May 10 10:00:07.845223 kernel: ACPI: bus type drm_connector registered May 10 10:00:07.845235 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:00:07.845248 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 10 10:00:07.845260 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 10 10:00:07.845273 systemd[1]: Mounted media.mount - External Media Directory. May 10 10:00:07.845291 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 10 10:00:07.845303 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 10 10:00:07.845338 systemd-journald[1162]: Collecting audit messages is disabled. May 10 10:00:07.845361 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 10 10:00:07.845374 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 10 10:00:07.845387 systemd-journald[1162]: Journal started May 10 10:00:07.845413 systemd-journald[1162]: Runtime Journal (/run/log/journal/911a4f0f20614d60b6c1e747ac10655b) is 6M, max 48.5M, 42.4M free. May 10 10:00:07.538862 systemd[1]: Queued start job for default target multi-user.target. May 10 10:00:07.552047 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 10 10:00:07.552806 systemd[1]: systemd-journald.service: Deactivated successfully. May 10 10:00:07.848733 systemd[1]: Started systemd-journald.service - Journal Service. May 10 10:00:07.849750 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 10 10:00:07.851311 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 10 10:00:07.851552 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 10 10:00:07.853100 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 10 10:00:07.853325 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 10 10:00:07.854780 systemd[1]: modprobe@drm.service: Deactivated successfully. May 10 10:00:07.854999 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 10 10:00:07.856370 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 10 10:00:07.856603 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 10 10:00:07.858132 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 10 10:00:07.858361 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 10 10:00:07.859750 systemd[1]: modprobe@loop.service: Deactivated successfully. May 10 10:00:07.859965 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 10 10:00:07.861419 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 10 10:00:07.862886 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 10 10:00:07.864482 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 10 10:00:07.866106 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 10 10:00:07.881558 systemd[1]: Reached target network-pre.target - Preparation for Network. May 10 10:00:07.884399 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 10 10:00:07.886703 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 10 10:00:07.887848 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 10 10:00:07.887878 systemd[1]: Reached target local-fs.target - Local File Systems. May 10 10:00:07.889906 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 10 10:00:07.901831 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 10 10:00:07.903037 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 10 10:00:07.904293 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 10 10:00:07.907561 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 10 10:00:07.908991 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 10 10:00:07.910843 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 10 10:00:07.912275 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 10 10:00:07.914473 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 10 10:00:07.917428 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 10 10:00:07.921868 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 10 10:00:07.925649 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 10 10:00:07.927086 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 10 10:00:07.938524 systemd-journald[1162]: Time spent on flushing to /var/log/journal/911a4f0f20614d60b6c1e747ac10655b is 23.511ms for 1046 entries. May 10 10:00:07.938524 systemd-journald[1162]: System Journal (/var/log/journal/911a4f0f20614d60b6c1e747ac10655b) is 8M, max 195.6M, 187.6M free. May 10 10:00:07.975497 systemd-journald[1162]: Received client request to flush runtime journal. May 10 10:00:07.975564 kernel: loop0: detected capacity change from 0 to 146240 May 10 10:00:07.957264 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 10 10:00:07.958959 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 10 10:00:07.962345 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 10 10:00:07.966038 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 10 10:00:07.979313 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 10 10:00:07.983563 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 10 10:00:07.989750 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 10 10:00:08.001387 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 10 10:00:08.005192 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 10 10:00:08.011740 kernel: loop1: detected capacity change from 0 to 113872 May 10 10:00:08.025477 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 10 10:00:08.040482 systemd-tmpfiles[1229]: ACLs are not supported, ignoring. May 10 10:00:08.040503 systemd-tmpfiles[1229]: ACLs are not supported, ignoring. May 10 10:00:08.050853 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 10 10:00:08.056162 kernel: loop2: detected capacity change from 0 to 205544 May 10 10:00:08.181596 kernel: loop3: detected capacity change from 0 to 146240 May 10 10:00:08.199755 kernel: loop4: detected capacity change from 0 to 113872 May 10 10:00:08.214746 kernel: loop5: detected capacity change from 0 to 205544 May 10 10:00:08.269500 (sd-merge)[1236]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. May 10 10:00:08.271072 (sd-merge)[1236]: Merged extensions into '/usr'. May 10 10:00:08.277602 systemd[1]: Reload requested from client PID 1212 ('systemd-sysext') (unit systemd-sysext.service)... May 10 10:00:08.277619 systemd[1]: Reloading... May 10 10:00:08.384747 zram_generator::config[1262]: No configuration found. May 10 10:00:08.439731 ldconfig[1207]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 10 10:00:08.499207 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. May 10 10:00:08.589555 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 10 10:00:08.590798 systemd[1]: Reloading finished in 312 ms. May 10 10:00:08.624429 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 10 10:00:08.626289 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 10 10:00:08.646377 systemd[1]: Starting ensure-sysext.service... May 10 10:00:08.648413 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 10 10:00:08.660070 systemd[1]: Reload requested from client PID 1299 ('systemctl') (unit ensure-sysext.service)... May 10 10:00:08.660091 systemd[1]: Reloading... May 10 10:00:08.759538 systemd-tmpfiles[1300]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 10 10:00:08.760069 systemd-tmpfiles[1300]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 10 10:00:08.760555 systemd-tmpfiles[1300]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 10 10:00:08.762174 systemd-tmpfiles[1300]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 10 10:00:08.763299 systemd-tmpfiles[1300]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 10 10:00:08.763697 systemd-tmpfiles[1300]: ACLs are not supported, ignoring. May 10 10:00:08.763881 systemd-tmpfiles[1300]: ACLs are not supported, ignoring. May 10 10:00:08.768763 zram_generator::config[1330]: No configuration found. May 10 10:00:08.769076 systemd-tmpfiles[1300]: Detected autofs mount point /boot during canonicalization of boot. May 10 10:00:08.769192 systemd-tmpfiles[1300]: Skipping /boot May 10 10:00:08.822653 systemd-tmpfiles[1300]: Detected autofs mount point /boot during canonicalization of boot. May 10 10:00:08.822857 systemd-tmpfiles[1300]: Skipping /boot May 10 10:00:08.874935 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. May 10 10:00:08.976665 systemd[1]: Reloading finished in 316 ms. May 10 10:00:08.991959 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 10 10:00:09.010050 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 10 10:00:09.020292 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 10 10:00:09.024014 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 10 10:00:09.026785 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 10 10:00:09.045445 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 10 10:00:09.049386 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 10 10:00:09.052904 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 10 10:00:09.058665 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:00:09.059219 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 10 10:00:09.067702 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 10 10:00:09.070942 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 10 10:00:09.074026 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 10 10:00:09.075990 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 10 10:00:09.076107 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). May 10 10:00:09.078156 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 10 10:00:09.081826 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:00:09.088692 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 10 10:00:09.090973 systemd[1]: modprobe@loop.service: Deactivated successfully. May 10 10:00:09.091326 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 10 10:00:09.095021 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 10 10:00:09.095270 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 10 10:00:09.097858 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 10 10:00:09.098142 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 10 10:00:09.106088 systemd-udevd[1370]: Using default interface naming scheme 'v255'. May 10 10:00:09.108477 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 10 10:00:09.114636 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:00:09.114904 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 10 10:00:09.117196 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 10 10:00:09.119827 augenrules[1400]: No rules May 10 10:00:09.120748 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 10 10:00:09.125512 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 10 10:00:09.136775 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 10 10:00:09.139256 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 10 10:00:09.139385 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). May 10 10:00:09.140819 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 10 10:00:09.141883 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 10 10:00:09.143273 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 10 10:00:09.145169 systemd[1]: audit-rules.service: Deactivated successfully. May 10 10:00:09.146736 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 10 10:00:09.148123 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 10 10:00:09.149823 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 10 10:00:09.151551 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 10 10:00:09.151819 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 10 10:00:09.153502 systemd[1]: modprobe@drm.service: Deactivated successfully. May 10 10:00:09.153755 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 10 10:00:09.155412 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 10 10:00:09.157741 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 10 10:00:09.160298 systemd[1]: modprobe@loop.service: Deactivated successfully. May 10 10:00:09.160544 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 10 10:00:09.170254 systemd[1]: Finished ensure-sysext.service. May 10 10:00:09.177785 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 10 10:00:09.198010 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 10 10:00:09.200816 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 10 10:00:09.200888 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 10 10:00:09.205920 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 10 10:00:09.207126 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). May 10 10:00:09.236799 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1449) May 10 10:00:09.250393 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 10 10:00:09.289538 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 10 10:00:09.293313 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 10 10:00:09.313750 kernel: mousedev: PS/2 mouse device common for all mice May 10 10:00:09.319791 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 May 10 10:00:09.326786 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 10 10:00:09.335749 kernel: ACPI: button: Power Button [PWRF] May 10 10:00:09.356770 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device May 10 10:00:09.357090 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt May 10 10:00:09.357276 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) May 10 10:00:09.358484 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD May 10 10:00:09.362495 systemd-resolved[1369]: Positive Trust Anchors: May 10 10:00:09.362520 systemd-resolved[1369]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 10 10:00:09.362552 systemd-resolved[1369]: 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 May 10 10:00:09.368039 systemd-resolved[1369]: Defaulting to hostname 'linux'. May 10 10:00:09.369814 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 10 10:00:09.372484 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 10 10:00:09.393894 systemd-networkd[1451]: lo: Link UP May 10 10:00:09.394176 systemd-networkd[1451]: lo: Gained carrier May 10 10:00:09.396026 systemd-networkd[1451]: Enumeration completed May 10 10:00:09.396190 systemd[1]: Started systemd-networkd.service - Network Configuration. May 10 10:00:09.397163 systemd-networkd[1451]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 10 10:00:09.397495 systemd[1]: Reached target network.target - Network. May 10 10:00:09.397625 systemd-networkd[1451]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 10 10:00:09.400441 systemd-networkd[1451]: eth0: Link UP May 10 10:00:09.400814 systemd-networkd[1451]: eth0: Gained carrier May 10 10:00:09.400898 systemd-networkd[1451]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 10 10:00:09.401049 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 10 10:00:09.404965 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 10 10:00:09.406254 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 10 10:00:09.408853 systemd[1]: Reached target sysinit.target - System Initialization. May 10 10:00:09.410021 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 10 10:00:09.411355 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 10 10:00:09.412803 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. May 10 10:00:09.412976 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 10 10:00:09.417074 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 10 10:00:09.417109 systemd[1]: Reached target paths.target - Path Units. May 10 10:00:09.418034 systemd[1]: Reached target time-set.target - System Time Set. May 10 10:00:09.419309 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 10 10:00:09.420904 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 10 10:00:09.422210 systemd[1]: Reached target timers.target - Timer Units. May 10 10:00:09.425015 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 10 10:00:09.425151 systemd-networkd[1451]: eth0: DHCPv4 address 10.0.0.72/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 10 10:00:09.428095 systemd[1]: Starting docker.socket - Docker Socket for the API... May 10 10:00:09.428596 systemd-timesyncd[1452]: Network configuration changed, trying to establish connection. May 10 10:00:11.186959 systemd-resolved[1369]: Clock change detected. Flushing caches. May 10 10:00:11.187034 systemd-timesyncd[1452]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 10 10:00:11.187103 systemd-timesyncd[1452]: Initial clock synchronization to Sat 2025-05-10 10:00:11.186907 UTC. May 10 10:00:11.187932 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 10 10:00:11.190976 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 10 10:00:11.192269 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 10 10:00:11.207595 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 10 10:00:11.209572 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 10 10:00:11.213060 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 10 10:00:11.215715 systemd[1]: Reached target sockets.target - Socket Units. May 10 10:00:11.216807 systemd[1]: Reached target basic.target - Basic System. May 10 10:00:11.217885 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 10 10:00:11.217918 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 10 10:00:11.255091 systemd[1]: Starting containerd.service - containerd container runtime... May 10 10:00:11.258369 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 10 10:00:11.262619 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 10 10:00:11.269622 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 10 10:00:11.275238 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 10 10:00:11.276586 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). May 10 10:00:11.283031 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... May 10 10:00:11.286088 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 10 10:00:11.295490 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 10 10:00:11.299122 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 10 10:00:11.300554 jq[1491]: false May 10 10:00:11.306020 systemd[1]: Starting systemd-logind.service - User Login Management... May 10 10:00:11.309026 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 10 10:00:11.310269 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 10 10:00:11.313095 systemd[1]: Starting update-engine.service - Update Engine... May 10 10:00:11.317115 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 10 10:00:11.325383 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 10 10:00:11.333360 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 10 10:00:11.335450 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 10 10:00:11.335544 google_oslogin_nss_cache[1493]: oslogin_cache_refresh[1493]: Refreshing passwd entry cache May 10 10:00:11.335525 oslogin_cache_refresh[1493]: Refreshing passwd entry cache May 10 10:00:11.335716 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 10 10:00:11.345919 extend-filesystems[1492]: Found loop3 May 10 10:00:11.345919 extend-filesystems[1492]: Found loop4 May 10 10:00:11.345919 extend-filesystems[1492]: Found loop5 May 10 10:00:11.345919 extend-filesystems[1492]: Found sr0 May 10 10:00:11.345919 extend-filesystems[1492]: Found vda May 10 10:00:11.345919 extend-filesystems[1492]: Found vda1 May 10 10:00:11.345919 extend-filesystems[1492]: Found vda2 May 10 10:00:11.345919 extend-filesystems[1492]: Found vda3 May 10 10:00:11.345919 extend-filesystems[1492]: Found usr May 10 10:00:11.345919 extend-filesystems[1492]: Found vda4 May 10 10:00:11.345919 extend-filesystems[1492]: Found vda6 May 10 10:00:11.345919 extend-filesystems[1492]: Found vda7 May 10 10:00:11.345919 extend-filesystems[1492]: Found vda9 May 10 10:00:11.345919 extend-filesystems[1492]: Checking size of /dev/vda9 May 10 10:00:11.345048 oslogin_cache_refresh[1493]: Failure getting users, quitting May 10 10:00:11.376980 google_oslogin_nss_cache[1493]: oslogin_cache_refresh[1493]: Failure getting users, quitting May 10 10:00:11.376980 google_oslogin_nss_cache[1493]: oslogin_cache_refresh[1493]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 10 10:00:11.376980 google_oslogin_nss_cache[1493]: oslogin_cache_refresh[1493]: Refreshing group entry cache May 10 10:00:11.376980 google_oslogin_nss_cache[1493]: oslogin_cache_refresh[1493]: Failure getting groups, quitting May 10 10:00:11.376980 google_oslogin_nss_cache[1493]: oslogin_cache_refresh[1493]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 10 10:00:11.377099 update_engine[1501]: I20250510 10:00:11.358870 1501 main.cc:92] Flatcar Update Engine starting May 10 10:00:11.347659 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 10 10:00:11.377455 extend-filesystems[1492]: Resized partition /dev/vda9 May 10 10:00:11.345075 oslogin_cache_refresh[1493]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 10 10:00:11.348540 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 10 10:00:11.345136 oslogin_cache_refresh[1493]: Refreshing group entry cache May 10 10:00:11.349620 systemd[1]: motdgen.service: Deactivated successfully. May 10 10:00:11.378640 jq[1502]: true May 10 10:00:11.354055 oslogin_cache_refresh[1493]: Failure getting groups, quitting May 10 10:00:11.349884 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 10 10:00:11.354066 oslogin_cache_refresh[1493]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 10 10:00:11.359458 systemd[1]: google-oslogin-cache.service: Deactivated successfully. May 10 10:00:11.359765 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. May 10 10:00:11.370466 (ntainerd)[1512]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 10 10:00:11.381065 jq[1513]: true May 10 10:00:11.392629 extend-filesystems[1523]: resize2fs 1.47.2 (1-Jan-2025) May 10 10:00:11.395936 kernel: kvm_amd: TSC scaling supported May 10 10:00:11.395971 kernel: kvm_amd: Nested Virtualization enabled May 10 10:00:11.395984 kernel: kvm_amd: Nested Paging enabled May 10 10:00:11.397974 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 10 10:00:11.398013 kernel: kvm_amd: LBR virtualization supported May 10 10:00:11.399896 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported May 10 10:00:11.399984 kernel: kvm_amd: Virtual GIF supported May 10 10:00:11.403873 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1447) May 10 10:00:11.425909 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 10 10:00:11.433884 systemd-logind[1499]: Watching system buttons on /dev/input/event2 (Power Button) May 10 10:00:11.433917 systemd-logind[1499]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 10 10:00:11.435879 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 10 10:00:11.437782 systemd-logind[1499]: New seat seat0. May 10 10:00:11.440536 systemd[1]: Started systemd-logind.service - User Login Management. May 10 10:00:11.458723 extend-filesystems[1523]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 10 10:00:11.458723 extend-filesystems[1523]: old_desc_blocks = 1, new_desc_blocks = 1 May 10 10:00:11.458723 extend-filesystems[1523]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 10 10:00:11.462940 dbus-daemon[1489]: [system] SELinux support is enabled May 10 10:00:11.463901 extend-filesystems[1492]: Resized filesystem in /dev/vda9 May 10 10:00:11.465326 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 10 10:00:11.470029 systemd[1]: extend-filesystems.service: Deactivated successfully. May 10 10:00:11.470355 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 10 10:00:11.473239 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). May 10 10:00:11.473301 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 10 10:00:11.475362 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). May 10 10:00:11.475389 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 10 10:00:11.491447 update_engine[1501]: I20250510 10:00:11.491381 1501 update_check_scheduler.cc:74] Next update check in 3m5s May 10 10:00:11.491653 systemd[1]: Started update-engine.service - Update Engine. May 10 10:00:11.495737 dbus-daemon[1489]: [system] Successfully activated service 'org.freedesktop.systemd1' May 10 10:00:11.496996 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 10 10:00:11.499907 kernel: EDAC MC: Ver: 3.0.0 May 10 10:00:11.513467 bash[1545]: Updated "/home/core/.ssh/authorized_keys" May 10 10:00:11.514863 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 10 10:00:11.517635 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 10 10:00:11.544671 locksmithd[1547]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 10 10:00:11.565612 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 10 10:00:11.620549 containerd[1512]: time="2025-05-10T10:00:11Z" 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 May 10 10:00:11.622139 containerd[1512]: time="2025-05-10T10:00:11.622082204Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 10 10:00:11.630170 containerd[1512]: time="2025-05-10T10:00:11.630120990Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="10.009µs" May 10 10:00:11.630170 containerd[1512]: time="2025-05-10T10:00:11.630154633Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 10 10:00:11.630170 containerd[1512]: time="2025-05-10T10:00:11.630171895Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 10 10:00:11.630386 containerd[1512]: time="2025-05-10T10:00:11.630352814Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 10 10:00:11.630386 containerd[1512]: time="2025-05-10T10:00:11.630373283Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 10 10:00:11.630437 containerd[1512]: time="2025-05-10T10:00:11.630399452Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 10 10:00:11.630486 containerd[1512]: time="2025-05-10T10:00:11.630464814Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 10 10:00:11.630486 containerd[1512]: time="2025-05-10T10:00:11.630479121Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 10 10:00:11.630824 containerd[1512]: time="2025-05-10T10:00:11.630790215Z" 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 May 10 10:00:11.630824 containerd[1512]: time="2025-05-10T10:00:11.630809451Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 10 10:00:11.630824 containerd[1512]: time="2025-05-10T10:00:11.630819960Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 10 10:00:11.630924 containerd[1512]: time="2025-05-10T10:00:11.630829378Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 10 10:00:11.630970 containerd[1512]: time="2025-05-10T10:00:11.630948371Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 10 10:00:11.631223 containerd[1512]: time="2025-05-10T10:00:11.631190656Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 10 10:00:11.631264 containerd[1512]: time="2025-05-10T10:00:11.631231212Z" 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 May 10 10:00:11.631264 containerd[1512]: time="2025-05-10T10:00:11.631254986Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 10 10:00:11.631324 containerd[1512]: time="2025-05-10T10:00:11.631293118Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 10 10:00:11.631602 containerd[1512]: time="2025-05-10T10:00:11.631570849Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 10 10:00:11.631689 containerd[1512]: time="2025-05-10T10:00:11.631662060Z" level=info msg="metadata content store policy set" policy=shared May 10 10:00:11.637206 containerd[1512]: time="2025-05-10T10:00:11.637168096Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 10 10:00:11.637206 containerd[1512]: time="2025-05-10T10:00:11.637207039Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 10 10:00:11.637307 containerd[1512]: time="2025-05-10T10:00:11.637220945Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 10 10:00:11.637307 containerd[1512]: time="2025-05-10T10:00:11.637242605Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 10 10:00:11.637307 containerd[1512]: time="2025-05-10T10:00:11.637263905Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 10 10:00:11.637307 containerd[1512]: time="2025-05-10T10:00:11.637275397Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 10 10:00:11.637307 containerd[1512]: time="2025-05-10T10:00:11.637288241Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 10 10:00:11.637307 containerd[1512]: time="2025-05-10T10:00:11.637300163Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 10 10:00:11.637438 containerd[1512]: time="2025-05-10T10:00:11.637337653Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 10 10:00:11.637438 containerd[1512]: time="2025-05-10T10:00:11.637359965Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 10 10:00:11.637438 containerd[1512]: time="2025-05-10T10:00:11.637370565Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 10 10:00:11.637438 containerd[1512]: time="2025-05-10T10:00:11.637383540Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 10 10:00:11.637516 containerd[1512]: time="2025-05-10T10:00:11.637501571Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 10 10:00:11.637545 containerd[1512]: time="2025-05-10T10:00:11.637519765Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 10 10:00:11.637545 containerd[1512]: time="2025-05-10T10:00:11.637534503Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 10 10:00:11.637545 containerd[1512]: time="2025-05-10T10:00:11.637545333Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 10 10:00:11.637607 containerd[1512]: time="2025-05-10T10:00:11.637556364Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 10 10:00:11.637607 containerd[1512]: time="2025-05-10T10:00:11.637566713Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 10 10:00:11.637607 containerd[1512]: time="2025-05-10T10:00:11.637577684Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 10 10:00:11.637607 containerd[1512]: time="2025-05-10T10:00:11.637590347Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 10 10:00:11.637695 containerd[1512]: time="2025-05-10T10:00:11.637609093Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 10 10:00:11.637695 containerd[1512]: time="2025-05-10T10:00:11.637622397Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 10 10:00:11.637695 containerd[1512]: time="2025-05-10T10:00:11.637633719Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 10 10:00:11.637762 containerd[1512]: time="2025-05-10T10:00:11.637705032Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 10 10:00:11.637762 containerd[1512]: time="2025-05-10T10:00:11.637720431Z" level=info msg="Start snapshots syncer" May 10 10:00:11.637762 containerd[1512]: time="2025-05-10T10:00:11.637744406Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 10 10:00:11.638047 containerd[1512]: time="2025-05-10T10:00:11.637991269Z" 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\"}" May 10 10:00:11.638172 containerd[1512]: time="2025-05-10T10:00:11.638061381Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.638912968Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639115407Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639145804Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639159400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639174879Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639191610Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639203583Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639217980Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639267022Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639282731Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 10 10:00:11.639513 containerd[1512]: time="2025-05-10T10:00:11.639299843Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 10 10:00:11.640301 containerd[1512]: time="2025-05-10T10:00:11.640274311Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 10 10:00:11.640387 containerd[1512]: time="2025-05-10T10:00:11.640365171Z" 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 May 10 10:00:11.640451 containerd[1512]: time="2025-05-10T10:00:11.640433740Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 10 10:00:11.640532 containerd[1512]: time="2025-05-10T10:00:11.640513089Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 10 10:00:11.640593 containerd[1512]: time="2025-05-10T10:00:11.640578521Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 10 10:00:11.640668 containerd[1512]: time="2025-05-10T10:00:11.640649254Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 10 10:00:11.640737 containerd[1512]: time="2025-05-10T10:00:11.640721379Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 10 10:00:11.640812 containerd[1512]: time="2025-05-10T10:00:11.640795488Z" level=info msg="runtime interface created" May 10 10:00:11.640886 containerd[1512]: time="2025-05-10T10:00:11.640870148Z" level=info msg="created NRI interface" May 10 10:00:11.640967 containerd[1512]: time="2025-05-10T10:00:11.640948024Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 10 10:00:11.641037 containerd[1512]: time="2025-05-10T10:00:11.641022023Z" level=info msg="Connect containerd service" May 10 10:00:11.641135 containerd[1512]: time="2025-05-10T10:00:11.641116881Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 10 10:00:11.642208 containerd[1512]: time="2025-05-10T10:00:11.642144338Z" 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" May 10 10:00:11.704581 sshd_keygen[1524]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 10 10:00:11.731586 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 10 10:00:11.735334 systemd[1]: Starting issuegen.service - Generate /run/issue... May 10 10:00:11.745119 containerd[1512]: time="2025-05-10T10:00:11.745075390Z" level=info msg="Start subscribing containerd event" May 10 10:00:11.745223 containerd[1512]: time="2025-05-10T10:00:11.745132628Z" level=info msg="Start recovering state" May 10 10:00:11.745280 containerd[1512]: time="2025-05-10T10:00:11.745238486Z" level=info msg="Start event monitor" May 10 10:00:11.745302 containerd[1512]: time="2025-05-10T10:00:11.745283040Z" level=info msg="Start cni network conf syncer for default" May 10 10:00:11.745302 containerd[1512]: time="2025-05-10T10:00:11.745294080Z" level=info msg="Start streaming server" May 10 10:00:11.745338 containerd[1512]: time="2025-05-10T10:00:11.745303328Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 10 10:00:11.745338 containerd[1512]: time="2025-05-10T10:00:11.745311794Z" level=info msg="runtime interface starting up..." May 10 10:00:11.745338 containerd[1512]: time="2025-05-10T10:00:11.745317785Z" level=info msg="starting plugins..." May 10 10:00:11.745390 containerd[1512]: time="2025-05-10T10:00:11.745340387Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 10 10:00:11.745438 containerd[1512]: time="2025-05-10T10:00:11.745396693Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 10 10:00:11.745519 containerd[1512]: time="2025-05-10T10:00:11.745494256Z" level=info msg=serving... address=/run/containerd/containerd.sock May 10 10:00:11.745714 containerd[1512]: time="2025-05-10T10:00:11.745693129Z" level=info msg="containerd successfully booted in 0.127473s" May 10 10:00:11.747052 systemd[1]: Started containerd.service - containerd container runtime. May 10 10:00:11.760609 systemd[1]: issuegen.service: Deactivated successfully. May 10 10:00:11.760950 systemd[1]: Finished issuegen.service - Generate /run/issue. May 10 10:00:11.765664 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 10 10:00:11.793768 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 10 10:00:11.797598 systemd[1]: Started getty@tty1.service - Getty on tty1. May 10 10:00:11.800401 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 10 10:00:11.801943 systemd[1]: Reached target getty.target - Login Prompts. May 10 10:00:12.688144 systemd-networkd[1451]: eth0: Gained IPv6LL May 10 10:00:12.692742 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 10 10:00:12.694681 systemd[1]: Reached target network-online.target - Network is Online. May 10 10:00:12.697517 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 10 10:00:12.700942 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 10 10:00:12.703659 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 10 10:00:12.732487 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 10 10:00:12.734427 systemd[1]: coreos-metadata.service: Deactivated successfully. May 10 10:00:12.734805 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 10 10:00:12.738851 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 10 10:00:14.152436 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 10 10:00:14.154518 systemd[1]: Reached target multi-user.target - Multi-User System. May 10 10:00:14.155861 systemd[1]: Startup finished in 3.670s (kernel) + 5.175s (initrd) + 5.553s (userspace) = 14.400s. May 10 10:00:14.162362 (kubelet)[1621]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 10 10:00:14.778614 kubelet[1621]: E0510 10:00:14.778519 1621 run.go:72] "command failed" err="failed to load kubelet config file, path: /var/lib/kubelet/config.yaml, error: failed to load Kubelet config file /var/lib/kubelet/config.yaml, error failed to read kubelet config file \"/var/lib/kubelet/config.yaml\", error: open /var/lib/kubelet/config.yaml: no such file or directory" May 10 10:00:14.783986 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 10 10:00:14.784224 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 10 10:00:14.784717 systemd[1]: kubelet.service: Consumed 1.928s CPU time, 237.8M memory peak. May 10 10:00:16.853360 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 10 10:00:16.855083 systemd[1]: Started sshd@0-10.0.0.72:22-10.0.0.1:46144.service - OpenSSH per-connection server daemon (10.0.0.1:46144). May 10 10:00:16.915314 sshd[1635]: Accepted publickey for core from 10.0.0.1 port 46144 ssh2: RSA SHA256:HiFs9aRUincaUTO+9jtR3GckoRttYstOQjP1S2tfHYw May 10 10:00:16.917946 sshd-session[1635]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:00:16.925678 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 10 10:00:16.926939 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 10 10:00:16.935007 systemd-logind[1499]: New session 1 of user core. May 10 10:00:16.952535 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 10 10:00:16.957081 systemd[1]: Starting user@500.service - User Manager for UID 500... May 10 10:00:16.973203 (systemd)[1639]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 10 10:00:16.976290 systemd-logind[1499]: New session c1 of user core. May 10 10:00:17.139270 systemd[1639]: Queued start job for default target default.target. May 10 10:00:17.151192 systemd[1639]: Created slice app.slice - User Application Slice. May 10 10:00:17.151217 systemd[1639]: Reached target paths.target - Paths. May 10 10:00:17.151257 systemd[1639]: Reached target timers.target - Timers. May 10 10:00:17.153033 systemd[1639]: Starting dbus.socket - D-Bus User Message Bus Socket... May 10 10:00:17.165027 systemd[1639]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 10 10:00:17.165180 systemd[1639]: Reached target sockets.target - Sockets. May 10 10:00:17.165224 systemd[1639]: Reached target basic.target - Basic System. May 10 10:00:17.165268 systemd[1639]: Reached target default.target - Main User Target. May 10 10:00:17.165302 systemd[1639]: Startup finished in 179ms. May 10 10:00:17.165960 systemd[1]: Started user@500.service - User Manager for UID 500. May 10 10:00:17.175976 systemd[1]: Started session-1.scope - Session 1 of User core. May 10 10:00:17.241244 systemd[1]: Started sshd@1-10.0.0.72:22-10.0.0.1:46158.service - OpenSSH per-connection server daemon (10.0.0.1:46158). May 10 10:00:17.298540 sshd[1650]: Accepted publickey for core from 10.0.0.1 port 46158 ssh2: RSA SHA256:HiFs9aRUincaUTO+9jtR3GckoRttYstOQjP1S2tfHYw May 10 10:00:17.300431 sshd-session[1650]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:00:17.305330 systemd-logind[1499]: New session 2 of user core. May 10 10:00:17.315065 systemd[1]: Started session-2.scope - Session 2 of User core. May 10 10:00:17.370334 sshd[1652]: Connection closed by 10.0.0.1 port 46158 May 10 10:00:17.370732 sshd-session[1650]: pam_unix(sshd:session): session closed for user core May 10 10:00:17.389202 systemd[1]: sshd@1-10.0.0.72:22-10.0.0.1:46158.service: Deactivated successfully. May 10 10:00:17.391441 systemd[1]: session-2.scope: Deactivated successfully. May 10 10:00:17.393494 systemd-logind[1499]: Session 2 logged out. Waiting for processes to exit. May 10 10:00:17.395336 systemd[1]: Started sshd@2-10.0.0.72:22-10.0.0.1:46170.service - OpenSSH per-connection server daemon (10.0.0.1:46170). May 10 10:00:17.396359 systemd-logind[1499]: Removed session 2. May 10 10:00:17.460682 sshd[1657]: Accepted publickey for core from 10.0.0.1 port 46170 ssh2: RSA SHA256:HiFs9aRUincaUTO+9jtR3GckoRttYstOQjP1S2tfHYw May 10 10:00:17.462469 sshd-session[1657]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:00:17.467719 systemd-logind[1499]: New session 3 of user core. May 10 10:00:17.477971 systemd[1]: Started session-3.scope - Session 3 of User core. May 10 10:00:17.530661 sshd[1660]: Connection closed by 10.0.0.1 port 46170 May 10 10:00:17.531110 sshd-session[1657]: pam_unix(sshd:session): session closed for user core May 10 10:00:17.550288 systemd[1]: sshd@2-10.0.0.72:22-10.0.0.1:46170.service: Deactivated successfully. May 10 10:00:17.552608 systemd[1]: session-3.scope: Deactivated successfully. May 10 10:00:17.555120 systemd-logind[1499]: Session 3 logged out. Waiting for processes to exit. May 10 10:00:17.556794 systemd[1]: Started sshd@3-10.0.0.72:22-10.0.0.1:46184.service - OpenSSH per-connection server daemon (10.0.0.1:46184). May 10 10:00:17.557922 systemd-logind[1499]: Removed session 3. May 10 10:00:17.618480 sshd[1665]: Accepted publickey for core from 10.0.0.1 port 46184 ssh2: RSA SHA256:HiFs9aRUincaUTO+9jtR3GckoRttYstOQjP1S2tfHYw May 10 10:00:17.620271 sshd-session[1665]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:00:17.625623 systemd-logind[1499]: New session 4 of user core. May 10 10:00:17.642118 systemd[1]: Started session-4.scope - Session 4 of User core. May 10 10:00:17.697966 sshd[1668]: Connection closed by 10.0.0.1 port 46184 May 10 10:00:17.698387 sshd-session[1665]: pam_unix(sshd:session): session closed for user core May 10 10:00:17.712097 systemd[1]: sshd@3-10.0.0.72:22-10.0.0.1:46184.service: Deactivated successfully. May 10 10:00:17.714507 systemd[1]: session-4.scope: Deactivated successfully. May 10 10:00:17.716394 systemd-logind[1499]: Session 4 logged out. Waiting for processes to exit. May 10 10:00:17.718084 systemd[1]: Started sshd@4-10.0.0.72:22-10.0.0.1:46198.service - OpenSSH per-connection server daemon (10.0.0.1:46198). May 10 10:00:17.719102 systemd-logind[1499]: Removed session 4. May 10 10:00:17.774105 sshd[1673]: Accepted publickey for core from 10.0.0.1 port 46198 ssh2: RSA SHA256:HiFs9aRUincaUTO+9jtR3GckoRttYstOQjP1S2tfHYw May 10 10:00:17.775775 sshd-session[1673]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 10 10:00:17.782519 systemd-logind[1499]: New session 5 of user core. May 10 10:00:17.790021 systemd[1]: Started session-5.scope - Session 5 of User core. May 10 10:00:17.852354 sudo[1677]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 10 10:00:17.852732 sudo[1677]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 10 10:00:17.868362 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 10 10:00:17.907775 systemd[1]: coreos-metadata.service: Deactivated successfully. May 10 10:00:17.908201 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 10 10:00:18.499878 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 10 10:00:18.500102 systemd[1]: kubelet.service: Consumed 1.928s CPU time, 237.8M memory peak. May 10 10:00:18.502611 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 10 10:00:18.537111 systemd[1]: Reload requested from client PID 1717 ('systemctl') (unit session-5.scope)... May 10 10:00:18.537130 systemd[1]: Reloading... May 10 10:00:18.679596 zram_generator::config[1758]: No configuration found. May 10 10:00:19.161362 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. May 10 10:00:19.287547 systemd[1]: Reloading finished in 749 ms. May 10 10:00:19.349505 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 10 10:00:19.352881 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... May 10 10:00:19.356651 systemd[1]: kubelet.service: Deactivated successfully. May 10 10:00:19.356959 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 10 10:00:19.357013 systemd[1]: kubelet.service: Consumed 248ms CPU time, 83.6M memory peak. May 10 10:00:19.358797 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 10 10:00:19.550559 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 10 10:00:19.560383 (kubelet)[1808]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 10 10:00:19.655055 kubelet[1808]: 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. May 10 10:00:19.655055 kubelet[1808]: 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. May 10 10:00:19.655055 kubelet[1808]: 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. May 10 10:00:19.656160 kubelet[1808]: I0510 10:00:19.656103 1808 server.go:206] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" May 10 10:00:19.943693 kubelet[1808]: I0510 10:00:19.943547 1808 server.go:486] "Kubelet version" kubeletVersion="v1.31.0" May 10 10:00:19.943693 kubelet[1808]: I0510 10:00:19.943597 1808 server.go:488] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 10 10:00:19.943993 kubelet[1808]: I0510 10:00:19.943964 1808 server.go:929] "Client rotation is on, will bootstrap in background" May 10 10:00:20.023464 kubelet[1808]: I0510 10:00:20.023144 1808 dynamic_cafile_content.go:160] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 10 10:00:20.034053 kubelet[1808]: I0510 10:00:20.033990 1808 server.go:1426] "Using cgroup driver setting received from the CRI runtime" cgroupDriver="systemd" May 10 10:00:20.041969 kubelet[1808]: I0510 10:00:20.041904 1808 server.go:744] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 10 10:00:20.043192 kubelet[1808]: I0510 10:00:20.043142 1808 swap_util.go:113] "Swap is on" /proc/swaps contents="Filename\t\t\t\tType\t\tSize\t\tUsed\t\tPriority" May 10 10:00:20.043376 kubelet[1808]: I0510 10:00:20.043321 1808 container_manager_linux.go:264] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 10 10:00:20.043584 kubelet[1808]: I0510 10:00:20.043369 1808 container_manager_linux.go:269] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.72","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} May 10 10:00:20.043719 kubelet[1808]: I0510 10:00:20.043589 1808 topology_manager.go:138] "Creating topology manager with none policy" May 10 10:00:20.043719 kubelet[1808]: I0510 10:00:20.043600 1808 container_manager_linux.go:300] "Creating device plugin manager" May 10 10:00:20.043783 kubelet[1808]: I0510 10:00:20.043750 1808 state_mem.go:36] "Initialized new in-memory state store" May 10 10:00:20.045089 kubelet[1808]: I0510 10:00:20.045050 1808 kubelet.go:408] "Attempting to sync node with API server" May 10 10:00:20.045089 kubelet[1808]: I0510 10:00:20.045077 1808 kubelet.go:303] "Adding static pod path" path="/etc/kubernetes/manifests" May 10 10:00:20.045164 kubelet[1808]: I0510 10:00:20.045126 1808 kubelet.go:314] "Adding apiserver pod source" May 10 10:00:20.045164 kubelet[1808]: I0510 10:00:20.045153 1808 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 10 10:00:20.045233 kubelet[1808]: E0510 10:00:20.045187 1808 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:20.045265 kubelet[1808]: E0510 10:00:20.045243 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:20.050439 kubelet[1808]: I0510 10:00:20.050391 1808 kuberuntime_manager.go:262] "Container runtime initialized" containerRuntime="containerd" version="v2.0.4" apiVersion="v1" May 10 10:00:20.052490 kubelet[1808]: I0510 10:00:20.052461 1808 kubelet.go:837] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 10 10:00:20.052602 kubelet[1808]: W0510 10:00:20.052579 1808 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 10 10:00:20.053820 kubelet[1808]: I0510 10:00:20.053547 1808 server.go:1269] "Started kubelet" May 10 10:00:20.054707 kubelet[1808]: I0510 10:00:20.053801 1808 server.go:163] "Starting to listen" address="0.0.0.0" port=10250 May 10 10:00:20.054707 kubelet[1808]: I0510 10:00:20.053981 1808 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 10 10:00:20.054707 kubelet[1808]: I0510 10:00:20.054560 1808 server.go:236] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 10 10:00:20.055378 kubelet[1808]: I0510 10:00:20.055337 1808 server.go:460] "Adding debug handlers to kubelet server" May 10 10:00:20.056844 kubelet[1808]: I0510 10:00:20.056801 1808 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 10 10:00:20.057235 kubelet[1808]: I0510 10:00:20.057048 1808 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" May 10 10:00:20.058504 kubelet[1808]: I0510 10:00:20.058239 1808 volume_manager.go:289] "Starting Kubelet Volume Manager" May 10 10:00:20.058504 kubelet[1808]: I0510 10:00:20.058356 1808 desired_state_of_world_populator.go:146] "Desired state populator starts to run" May 10 10:00:20.058504 kubelet[1808]: I0510 10:00:20.058440 1808 reconciler.go:26] "Reconciler: start to sync state" May 10 10:00:20.059801 kubelet[1808]: E0510 10:00:20.058786 1808 kubelet.go:1478] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 10 10:00:20.059801 kubelet[1808]: I0510 10:00:20.059120 1808 factory.go:221] Registration of the systemd container factory successfully May 10 10:00:20.059801 kubelet[1808]: I0510 10:00:20.059210 1808 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 May 10 10:00:20.060446 kubelet[1808]: I0510 10:00:20.060412 1808 factory.go:221] Registration of the containerd container factory successfully May 10 10:00:20.060610 kubelet[1808]: E0510 10:00:20.060584 1808 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.72\" not found" May 10 10:00:20.068258 kubelet[1808]: E0510 10:00:20.067894 1808 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.72\" not found" node="10.0.0.72" May 10 10:00:20.081024 kubelet[1808]: I0510 10:00:20.080623 1808 cpu_manager.go:214] "Starting CPU manager" policy="none" May 10 10:00:20.081024 kubelet[1808]: I0510 10:00:20.080650 1808 cpu_manager.go:215] "Reconciling" reconcilePeriod="10s" May 10 10:00:20.081024 kubelet[1808]: I0510 10:00:20.080678 1808 state_mem.go:36] "Initialized new in-memory state store" May 10 10:00:20.160931 kubelet[1808]: E0510 10:00:20.160822 1808 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.72\" not found" May 10 10:00:20.261645 kubelet[1808]: E0510 10:00:20.261482 1808 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.72\" not found" May 10 10:00:20.362212 kubelet[1808]: E0510 10:00:20.362144 1808 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.72\" not found" May 10 10:00:20.460310 kubelet[1808]: I0510 10:00:20.460248 1808 policy_none.go:49] "None policy: Start" May 10 10:00:20.461297 kubelet[1808]: I0510 10:00:20.461267 1808 memory_manager.go:170] "Starting memorymanager" policy="None" May 10 10:00:20.461362 kubelet[1808]: I0510 10:00:20.461310 1808 state_mem.go:35] "Initializing new in-memory state store" May 10 10:00:20.462509 kubelet[1808]: E0510 10:00:20.462476 1808 kubelet_node_status.go:453] "Error getting the current node from lister" err="node \"10.0.0.72\" not found" May 10 10:00:20.473087 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 10 10:00:20.477032 kubelet[1808]: E0510 10:00:20.476991 1808 csi_plugin.go:305] Failed to initialize CSINode: error updating CSINode annotation: timed out waiting for the condition; caused by: nodes "10.0.0.72" not found May 10 10:00:20.484076 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 10 10:00:20.487655 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 10 10:00:20.491793 kubelet[1808]: I0510 10:00:20.491753 1808 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 10 10:00:20.493381 kubelet[1808]: I0510 10:00:20.493338 1808 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 10 10:00:20.493381 kubelet[1808]: I0510 10:00:20.493390 1808 status_manager.go:217] "Starting to sync pod status with apiserver" May 10 10:00:20.493515 kubelet[1808]: I0510 10:00:20.493411 1808 kubelet.go:2321] "Starting kubelet main sync loop" May 10 10:00:20.493515 kubelet[1808]: E0510 10:00:20.493456 1808 kubelet.go:2345] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" May 10 10:00:20.496631 kubelet[1808]: I0510 10:00:20.496054 1808 manager.go:510] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 10 10:00:20.496631 kubelet[1808]: I0510 10:00:20.496283 1808 eviction_manager.go:189] "Eviction manager: starting control loop" May 10 10:00:20.496631 kubelet[1808]: I0510 10:00:20.496296 1808 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 10 10:00:20.496631 kubelet[1808]: I0510 10:00:20.496547 1808 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 10 10:00:20.497760 kubelet[1808]: E0510 10:00:20.497729 1808 eviction_manager.go:285] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.72\" not found" May 10 10:00:20.597761 kubelet[1808]: I0510 10:00:20.597634 1808 kubelet_node_status.go:72] "Attempting to register node" node="10.0.0.72" May 10 10:00:20.604018 kubelet[1808]: I0510 10:00:20.603976 1808 kubelet_node_status.go:75] "Successfully registered node" node="10.0.0.72" May 10 10:00:20.604018 kubelet[1808]: E0510 10:00:20.604016 1808 kubelet_node_status.go:535] "Error updating node status, will retry" err="error getting node \"10.0.0.72\": node \"10.0.0.72\" not found" May 10 10:00:20.618964 kubelet[1808]: I0510 10:00:20.618899 1808 kuberuntime_manager.go:1633] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 10 10:00:20.619451 containerd[1512]: time="2025-05-10T10:00:20.619320628Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 10 10:00:20.619941 kubelet[1808]: I0510 10:00:20.619639 1808 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 10 10:00:20.946241 kubelet[1808]: I0510 10:00:20.946040 1808 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 10 10:00:20.947061 kubelet[1808]: W0510 10:00:20.946425 1808 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 10 10:00:20.947061 kubelet[1808]: W0510 10:00:20.946573 1808 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 10 10:00:20.947061 kubelet[1808]: W0510 10:00:20.946582 1808 reflector.go:484] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 10 10:00:21.046086 kubelet[1808]: I0510 10:00:21.046038 1808 apiserver.go:52] "Watching apiserver" May 10 10:00:21.046269 kubelet[1808]: E0510 10:00:21.046053 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:21.058959 kubelet[1808]: I0510 10:00:21.058901 1808 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" May 10 10:00:21.062525 systemd[1]: Created slice kubepods-besteffort-pod39061a22_30ea_4c20_a0d1_52e052d829ad.slice - libcontainer container kubepods-besteffort-pod39061a22_30ea_4c20_a0d1_52e052d829ad.slice. May 10 10:00:21.063490 kubelet[1808]: I0510 10:00:21.063438 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/69c93ace-b718-4587-8469-b7ec7b3e90f1-xtables-lock\") pod \"kube-flannel-ds-nvdm7\" (UID: \"69c93ace-b718-4587-8469-b7ec7b3e90f1\") " pod="kube-flannel/kube-flannel-ds-nvdm7" May 10 10:00:21.063490 kubelet[1808]: I0510 10:00:21.063475 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-znblh\" (UniqueName: \"kubernetes.io/projected/69c93ace-b718-4587-8469-b7ec7b3e90f1-kube-api-access-znblh\") pod \"kube-flannel-ds-nvdm7\" (UID: \"69c93ace-b718-4587-8469-b7ec7b3e90f1\") " pod="kube-flannel/kube-flannel-ds-nvdm7" May 10 10:00:21.063678 kubelet[1808]: I0510 10:00:21.063503 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/39061a22-30ea-4c20-a0d1-52e052d829ad-kube-proxy\") pod \"kube-proxy-7md75\" (UID: \"39061a22-30ea-4c20-a0d1-52e052d829ad\") " pod="kube-system/kube-proxy-7md75" May 10 10:00:21.063678 kubelet[1808]: I0510 10:00:21.063520 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/39061a22-30ea-4c20-a0d1-52e052d829ad-xtables-lock\") pod \"kube-proxy-7md75\" (UID: \"39061a22-30ea-4c20-a0d1-52e052d829ad\") " pod="kube-system/kube-proxy-7md75" May 10 10:00:21.063678 kubelet[1808]: I0510 10:00:21.063535 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/39061a22-30ea-4c20-a0d1-52e052d829ad-lib-modules\") pod \"kube-proxy-7md75\" (UID: \"39061a22-30ea-4c20-a0d1-52e052d829ad\") " pod="kube-system/kube-proxy-7md75" May 10 10:00:21.063678 kubelet[1808]: I0510 10:00:21.063551 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/69c93ace-b718-4587-8469-b7ec7b3e90f1-run\") pod \"kube-flannel-ds-nvdm7\" (UID: \"69c93ace-b718-4587-8469-b7ec7b3e90f1\") " pod="kube-flannel/kube-flannel-ds-nvdm7" May 10 10:00:21.063678 kubelet[1808]: I0510 10:00:21.063563 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/69c93ace-b718-4587-8469-b7ec7b3e90f1-cni\") pod \"kube-flannel-ds-nvdm7\" (UID: \"69c93ace-b718-4587-8469-b7ec7b3e90f1\") " pod="kube-flannel/kube-flannel-ds-nvdm7" May 10 10:00:21.063678 kubelet[1808]: I0510 10:00:21.063576 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/69c93ace-b718-4587-8469-b7ec7b3e90f1-flannel-cfg\") pod \"kube-flannel-ds-nvdm7\" (UID: \"69c93ace-b718-4587-8469-b7ec7b3e90f1\") " pod="kube-flannel/kube-flannel-ds-nvdm7" May 10 10:00:21.063928 kubelet[1808]: I0510 10:00:21.063606 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-r7jxw\" (UniqueName: \"kubernetes.io/projected/39061a22-30ea-4c20-a0d1-52e052d829ad-kube-api-access-r7jxw\") pod \"kube-proxy-7md75\" (UID: \"39061a22-30ea-4c20-a0d1-52e052d829ad\") " pod="kube-system/kube-proxy-7md75" May 10 10:00:21.063928 kubelet[1808]: I0510 10:00:21.063628 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/69c93ace-b718-4587-8469-b7ec7b3e90f1-cni-plugin\") pod \"kube-flannel-ds-nvdm7\" (UID: \"69c93ace-b718-4587-8469-b7ec7b3e90f1\") " pod="kube-flannel/kube-flannel-ds-nvdm7" May 10 10:00:21.076018 systemd[1]: Created slice kubepods-burstable-pod69c93ace_b718_4587_8469_b7ec7b3e90f1.slice - libcontainer container kubepods-burstable-pod69c93ace_b718_4587_8469_b7ec7b3e90f1.slice. May 10 10:00:21.082886 sudo[1677]: pam_unix(sudo:session): session closed for user root May 10 10:00:21.084897 sshd[1676]: Connection closed by 10.0.0.1 port 46198 May 10 10:00:21.085199 sshd-session[1673]: pam_unix(sshd:session): session closed for user core May 10 10:00:21.090225 systemd[1]: sshd@4-10.0.0.72:22-10.0.0.1:46198.service: Deactivated successfully. May 10 10:00:21.092978 systemd[1]: session-5.scope: Deactivated successfully. May 10 10:00:21.093259 systemd[1]: session-5.scope: Consumed 665ms CPU time, 77M memory peak. May 10 10:00:21.094770 systemd-logind[1499]: Session 5 logged out. Waiting for processes to exit. May 10 10:00:21.095902 systemd-logind[1499]: Removed session 5. May 10 10:00:21.373148 containerd[1512]: time="2025-05-10T10:00:21.373001198Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-7md75,Uid:39061a22-30ea-4c20-a0d1-52e052d829ad,Namespace:kube-system,Attempt:0,}" May 10 10:00:21.386678 containerd[1512]: time="2025-05-10T10:00:21.386628253Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-nvdm7,Uid:69c93ace-b718-4587-8469-b7ec7b3e90f1,Namespace:kube-flannel,Attempt:0,}" May 10 10:00:22.047410 kubelet[1808]: E0510 10:00:22.047315 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:22.244276 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3637148178.mount: Deactivated successfully. May 10 10:00:22.256372 containerd[1512]: time="2025-05-10T10:00:22.256295971Z" 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\"}" May 10 10:00:22.258548 containerd[1512]: time="2025-05-10T10:00:22.258516966Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=321138" May 10 10:00:22.259852 containerd[1512]: time="2025-05-10T10:00:22.259799892Z" 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\"}" May 10 10:00:22.260795 containerd[1512]: time="2025-05-10T10:00:22.260722111Z" level=info msg="ImageCreate event name:\"sha256:873ed75102791e5b0b8a7fcd41606c92fcec98d56d05ead4ac5131650004c136\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 10 10:00:22.261806 containerd[1512]: time="2025-05-10T10:00:22.261769205Z" level=info msg="stop pulling image registry.k8s.io/pause:3.10: active requests=0, bytes read=0" May 10 10:00:22.263615 containerd[1512]: time="2025-05-10T10:00:22.263573759Z" 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\"}" May 10 10:00:22.264431 containerd[1512]: time="2025-05-10T10:00:22.264390591Z" 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 870.372559ms" May 10 10:00:22.265630 containerd[1512]: time="2025-05-10T10:00:22.265596523Z" 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 885.466455ms" May 10 10:00:22.295131 containerd[1512]: time="2025-05-10T10:00:22.295017568Z" level=info msg="connecting to shim bc112de76234258919f6796edeb11615984d4ce4f399d0c0299f76c06417ba7b" address="unix:///run/containerd/s/c1c96675589e53cab0fc3247a99ba1eb5248da4f4da6b708827a587489385587" namespace=k8s.io protocol=ttrpc version=3 May 10 10:00:22.332523 containerd[1512]: time="2025-05-10T10:00:22.332367672Z" level=info msg="connecting to shim ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424" address="unix:///run/containerd/s/048bafd7650671bf41f775d3590ef8767219be51964eef1986571520228622a2" namespace=k8s.io protocol=ttrpc version=3 May 10 10:00:22.342067 systemd[1]: Started cri-containerd-bc112de76234258919f6796edeb11615984d4ce4f399d0c0299f76c06417ba7b.scope - libcontainer container bc112de76234258919f6796edeb11615984d4ce4f399d0c0299f76c06417ba7b. May 10 10:00:22.459017 systemd[1]: Started cri-containerd-ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424.scope - libcontainer container ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424. May 10 10:00:22.512642 containerd[1512]: time="2025-05-10T10:00:22.512602096Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-7md75,Uid:39061a22-30ea-4c20-a0d1-52e052d829ad,Namespace:kube-system,Attempt:0,} returns sandbox id \"bc112de76234258919f6796edeb11615984d4ce4f399d0c0299f76c06417ba7b\"" May 10 10:00:22.515265 containerd[1512]: time="2025-05-10T10:00:22.515227269Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\"" May 10 10:00:22.645204 containerd[1512]: time="2025-05-10T10:00:22.645055514Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-nvdm7,Uid:69c93ace-b718-4587-8469-b7ec7b3e90f1,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424\"" May 10 10:00:23.048469 kubelet[1808]: E0510 10:00:23.048288 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:24.050702 kubelet[1808]: E0510 10:00:24.048710 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:24.050988 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount218241408.mount: Deactivated successfully. May 10 10:00:24.570251 containerd[1512]: time="2025-05-10T10:00:24.570185740Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.31.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:24.571071 containerd[1512]: time="2025-05-10T10:00:24.571001760Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.31.8: active requests=0, bytes read=30354625" May 10 10:00:24.572746 containerd[1512]: time="2025-05-10T10:00:24.572704724Z" level=info msg="ImageCreate event name:\"sha256:7d73f013cedcf301aef42272c93e4c1174dab1a8eccd96840091ef04b63480f2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:24.574631 containerd[1512]: time="2025-05-10T10:00:24.574593105Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:24.575271 containerd[1512]: time="2025-05-10T10:00:24.575239568Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.31.8\" with image id \"sha256:7d73f013cedcf301aef42272c93e4c1174dab1a8eccd96840091ef04b63480f2\", repo tag \"registry.k8s.io/kube-proxy:v1.31.8\", repo digest \"registry.k8s.io/kube-proxy@sha256:dd0c9a37670f209947b1ed880f06a2e93e1d41da78c037f52f94b13858769838\", size \"30353644\" in 2.059967825s" May 10 10:00:24.575298 containerd[1512]: time="2025-05-10T10:00:24.575272650Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.31.8\" returns image reference \"sha256:7d73f013cedcf301aef42272c93e4c1174dab1a8eccd96840091ef04b63480f2\"" May 10 10:00:24.576326 containerd[1512]: time="2025-05-10T10:00:24.576299465Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 10 10:00:24.578068 containerd[1512]: time="2025-05-10T10:00:24.578032085Z" level=info msg="CreateContainer within sandbox \"bc112de76234258919f6796edeb11615984d4ce4f399d0c0299f76c06417ba7b\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 10 10:00:24.590312 containerd[1512]: time="2025-05-10T10:00:24.590238306Z" level=info msg="Container 129b64cd662fab27845f1ea865a78d21d9032ac4b3eb6d461853378d6e848571: CDI devices from CRI Config.CDIDevices: []" May 10 10:00:24.602205 containerd[1512]: time="2025-05-10T10:00:24.602151668Z" level=info msg="CreateContainer within sandbox \"bc112de76234258919f6796edeb11615984d4ce4f399d0c0299f76c06417ba7b\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"129b64cd662fab27845f1ea865a78d21d9032ac4b3eb6d461853378d6e848571\"" May 10 10:00:24.602929 containerd[1512]: time="2025-05-10T10:00:24.602873341Z" level=info msg="StartContainer for \"129b64cd662fab27845f1ea865a78d21d9032ac4b3eb6d461853378d6e848571\"" May 10 10:00:24.604760 containerd[1512]: time="2025-05-10T10:00:24.604700287Z" level=info msg="connecting to shim 129b64cd662fab27845f1ea865a78d21d9032ac4b3eb6d461853378d6e848571" address="unix:///run/containerd/s/c1c96675589e53cab0fc3247a99ba1eb5248da4f4da6b708827a587489385587" protocol=ttrpc version=3 May 10 10:00:24.632069 systemd[1]: Started cri-containerd-129b64cd662fab27845f1ea865a78d21d9032ac4b3eb6d461853378d6e848571.scope - libcontainer container 129b64cd662fab27845f1ea865a78d21d9032ac4b3eb6d461853378d6e848571. May 10 10:00:24.687850 containerd[1512]: time="2025-05-10T10:00:24.687768329Z" level=info msg="StartContainer for \"129b64cd662fab27845f1ea865a78d21d9032ac4b3eb6d461853378d6e848571\" returns successfully" May 10 10:00:25.049942 kubelet[1808]: E0510 10:00:25.049820 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:25.592879 kubelet[1808]: I0510 10:00:25.592741 1808 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-7md75" podStartSLOduration=3.531207395 podStartE2EDuration="5.592715318s" podCreationTimestamp="2025-05-10 10:00:20 +0000 UTC" firstStartedPulling="2025-05-10 10:00:22.514674904 +0000 UTC m=+2.948229554" lastFinishedPulling="2025-05-10 10:00:24.576182837 +0000 UTC m=+5.009737477" observedRunningTime="2025-05-10 10:00:25.592650857 +0000 UTC m=+6.026205497" watchObservedRunningTime="2025-05-10 10:00:25.592715318 +0000 UTC m=+6.026269958" May 10 10:00:26.050319 kubelet[1808]: E0510 10:00:26.050170 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:26.483751 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2248751189.mount: Deactivated successfully. May 10 10:00:26.558764 containerd[1512]: time="2025-05-10T10:00:26.558662464Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:26.559775 containerd[1512]: time="2025-05-10T10:00:26.559735787Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" May 10 10:00:26.561224 containerd[1512]: time="2025-05-10T10:00:26.561163173Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:26.563960 containerd[1512]: time="2025-05-10T10:00:26.563882794Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:26.565940 containerd[1512]: time="2025-05-10T10:00:26.565888064Z" 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.989552972s" May 10 10:00:26.566003 containerd[1512]: time="2025-05-10T10:00:26.565940823Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" May 10 10:00:26.569884 containerd[1512]: time="2025-05-10T10:00:26.569797586Z" level=info msg="CreateContainer within sandbox \"ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 10 10:00:26.580143 containerd[1512]: time="2025-05-10T10:00:26.580099696Z" level=info msg="Container 2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b: CDI devices from CRI Config.CDIDevices: []" May 10 10:00:26.586311 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2531306606.mount: Deactivated successfully. May 10 10:00:26.594791 containerd[1512]: time="2025-05-10T10:00:26.594716798Z" level=info msg="CreateContainer within sandbox \"ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b\"" May 10 10:00:26.595705 containerd[1512]: time="2025-05-10T10:00:26.595676879Z" level=info msg="StartContainer for \"2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b\"" May 10 10:00:26.596768 containerd[1512]: time="2025-05-10T10:00:26.596742728Z" level=info msg="connecting to shim 2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b" address="unix:///run/containerd/s/048bafd7650671bf41f775d3590ef8767219be51964eef1986571520228622a2" protocol=ttrpc version=3 May 10 10:00:26.631098 systemd[1]: Started cri-containerd-2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b.scope - libcontainer container 2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b. May 10 10:00:26.678143 systemd[1]: cri-containerd-2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b.scope: Deactivated successfully. May 10 10:00:26.678664 containerd[1512]: time="2025-05-10T10:00:26.678485264Z" level=info msg="StartContainer for \"2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b\" returns successfully" May 10 10:00:26.679639 containerd[1512]: time="2025-05-10T10:00:26.679604984Z" level=info msg="received exit event container_id:\"2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b\" id:\"2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b\" pid:2136 exited_at:{seconds:1746871226 nanos:679091501}" May 10 10:00:26.679760 containerd[1512]: time="2025-05-10T10:00:26.679735349Z" level=info msg="TaskExit event in podsandbox handler container_id:\"2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b\" id:\"2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b\" pid:2136 exited_at:{seconds:1746871226 nanos:679091501}" May 10 10:00:27.051274 kubelet[1808]: E0510 10:00:27.051189 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:27.402589 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-2619ad8aa4875969f623e098181f4192485bc84cc130a01aa52786fcf5b69e6b-rootfs.mount: Deactivated successfully. May 10 10:00:27.513422 containerd[1512]: time="2025-05-10T10:00:27.513351486Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 10 10:00:28.054059 kubelet[1808]: E0510 10:00:28.054010 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:29.054389 kubelet[1808]: E0510 10:00:29.054326 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:29.413467 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount195195453.mount: Deactivated successfully. May 10 10:00:30.054549 kubelet[1808]: E0510 10:00:30.054455 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:30.414585 containerd[1512]: time="2025-05-10T10:00:30.414435274Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:30.415610 containerd[1512]: time="2025-05-10T10:00:30.415572126Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" May 10 10:00:30.417214 containerd[1512]: time="2025-05-10T10:00:30.417155405Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:30.420084 containerd[1512]: time="2025-05-10T10:00:30.420044313Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:30.423026 containerd[1512]: time="2025-05-10T10:00:30.422261601Z" 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.908864169s" May 10 10:00:30.423026 containerd[1512]: time="2025-05-10T10:00:30.422312817Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" May 10 10:00:30.426112 containerd[1512]: time="2025-05-10T10:00:30.426065345Z" level=info msg="CreateContainer within sandbox \"ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 10 10:00:30.438577 containerd[1512]: time="2025-05-10T10:00:30.438520673Z" level=info msg="Container 6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3: CDI devices from CRI Config.CDIDevices: []" May 10 10:00:30.447582 containerd[1512]: time="2025-05-10T10:00:30.447517705Z" level=info msg="CreateContainer within sandbox \"ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3\"" May 10 10:00:30.448233 containerd[1512]: time="2025-05-10T10:00:30.448179817Z" level=info msg="StartContainer for \"6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3\"" May 10 10:00:30.449507 containerd[1512]: time="2025-05-10T10:00:30.449454037Z" level=info msg="connecting to shim 6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3" address="unix:///run/containerd/s/048bafd7650671bf41f775d3590ef8767219be51964eef1986571520228622a2" protocol=ttrpc version=3 May 10 10:00:30.493071 systemd[1]: Started cri-containerd-6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3.scope - libcontainer container 6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3. May 10 10:00:30.584316 systemd[1]: cri-containerd-6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3.scope: Deactivated successfully. May 10 10:00:30.585331 containerd[1512]: time="2025-05-10T10:00:30.585196172Z" level=info msg="TaskExit event in podsandbox handler container_id:\"6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3\" id:\"6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3\" pid:2210 exited_at:{seconds:1746871230 nanos:584532848}" May 10 10:00:30.588261 containerd[1512]: time="2025-05-10T10:00:30.588211667Z" level=info msg="received exit event container_id:\"6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3\" id:\"6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3\" pid:2210 exited_at:{seconds:1746871230 nanos:584532848}" May 10 10:00:30.589690 containerd[1512]: time="2025-05-10T10:00:30.589630177Z" level=info msg="StartContainer for \"6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3\" returns successfully" May 10 10:00:30.616600 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-6c0a993b36977cc0640169cba43584e77d7cc24b32f6c75809be7ebe1285c2c3-rootfs.mount: Deactivated successfully. May 10 10:00:30.635053 kubelet[1808]: I0510 10:00:30.634980 1808 kubelet_node_status.go:488] "Fast updating node status as it just became ready" May 10 10:00:31.055010 kubelet[1808]: E0510 10:00:31.054924 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:31.529102 containerd[1512]: time="2025-05-10T10:00:31.529000151Z" level=info msg="CreateContainer within sandbox \"ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 10 10:00:31.784859 containerd[1512]: time="2025-05-10T10:00:31.784668450Z" level=info msg="Container e62c393ffd5c0d608c55ac34e612da09aa131bdc8a484b13860331f36d0dfb12: CDI devices from CRI Config.CDIDevices: []" May 10 10:00:32.055363 kubelet[1808]: E0510 10:00:32.055191 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:32.156709 containerd[1512]: time="2025-05-10T10:00:32.156652874Z" level=info msg="CreateContainer within sandbox \"ca9978b6e546fc3fbccebdb075a6d26465531e64aa150eaf3c3be9709c01e424\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"e62c393ffd5c0d608c55ac34e612da09aa131bdc8a484b13860331f36d0dfb12\"" May 10 10:00:32.157235 containerd[1512]: time="2025-05-10T10:00:32.157202885Z" level=info msg="StartContainer for \"e62c393ffd5c0d608c55ac34e612da09aa131bdc8a484b13860331f36d0dfb12\"" May 10 10:00:32.158056 containerd[1512]: time="2025-05-10T10:00:32.158010791Z" level=info msg="connecting to shim e62c393ffd5c0d608c55ac34e612da09aa131bdc8a484b13860331f36d0dfb12" address="unix:///run/containerd/s/048bafd7650671bf41f775d3590ef8767219be51964eef1986571520228622a2" protocol=ttrpc version=3 May 10 10:00:32.179969 systemd[1]: Started cri-containerd-e62c393ffd5c0d608c55ac34e612da09aa131bdc8a484b13860331f36d0dfb12.scope - libcontainer container e62c393ffd5c0d608c55ac34e612da09aa131bdc8a484b13860331f36d0dfb12. May 10 10:00:32.385668 containerd[1512]: time="2025-05-10T10:00:32.385526904Z" level=info msg="StartContainer for \"e62c393ffd5c0d608c55ac34e612da09aa131bdc8a484b13860331f36d0dfb12\" returns successfully" May 10 10:00:32.537714 systemd[1]: Created slice kubepods-besteffort-pod1cbeb4fe_d3c0_44ed_a49e_b4bb6d94fb91.slice - libcontainer container kubepods-besteffort-pod1cbeb4fe_d3c0_44ed_a49e_b4bb6d94fb91.slice. May 10 10:00:32.542816 kubelet[1808]: I0510 10:00:32.542760 1808 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-nvdm7" podStartSLOduration=4.764837812 podStartE2EDuration="12.54274264s" podCreationTimestamp="2025-05-10 10:00:20 +0000 UTC" firstStartedPulling="2025-05-10 10:00:22.64656262 +0000 UTC m=+3.080117260" lastFinishedPulling="2025-05-10 10:00:30.424467448 +0000 UTC m=+10.858022088" observedRunningTime="2025-05-10 10:00:32.542377515 +0000 UTC m=+12.975932155" watchObservedRunningTime="2025-05-10 10:00:32.54274264 +0000 UTC m=+12.976297280" May 10 10:00:32.547981 kubelet[1808]: I0510 10:00:32.547940 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-x2b6x\" (UniqueName: \"kubernetes.io/projected/1cbeb4fe-d3c0-44ed-a49e-b4bb6d94fb91-kube-api-access-x2b6x\") pod \"nginx-deployment-8587fbcb89-dcwgg\" (UID: \"1cbeb4fe-d3c0-44ed-a49e-b4bb6d94fb91\") " pod="default/nginx-deployment-8587fbcb89-dcwgg" May 10 10:00:32.841218 containerd[1512]: time="2025-05-10T10:00:32.841078807Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-dcwgg,Uid:1cbeb4fe-d3c0-44ed-a49e-b4bb6d94fb91,Namespace:default,Attempt:0,}" May 10 10:00:32.922934 containerd[1512]: time="2025-05-10T10:00:32.922858132Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-dcwgg,Uid:1cbeb4fe-d3c0-44ed-a49e-b4bb6d94fb91,Namespace:default,Attempt:0,} failed, error" error="rpc error: code = Unknown desc = failed to setup network for sandbox \"ef1bd4bdffa3c92218bb290e9a367deaa0ae1aca3473fdc0cef5a9151398a190\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" May 10 10:00:32.923247 kubelet[1808]: E0510 10:00:32.923199 1808 log.go:32] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"ef1bd4bdffa3c92218bb290e9a367deaa0ae1aca3473fdc0cef5a9151398a190\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" May 10 10:00:32.923282 systemd[1]: run-netns-cni\x2dfb1a5109\x2d37c4\x2dc457\x2d1db6\x2dc19b7cc788c3.mount: Deactivated successfully. May 10 10:00:32.923388 kubelet[1808]: E0510 10:00:32.923347 1808 kuberuntime_sandbox.go:72] "Failed to create sandbox for pod" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"ef1bd4bdffa3c92218bb290e9a367deaa0ae1aca3473fdc0cef5a9151398a190\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-8587fbcb89-dcwgg" May 10 10:00:32.923388 kubelet[1808]: E0510 10:00:32.923376 1808 kuberuntime_manager.go:1168] "CreatePodSandbox for pod failed" err="rpc error: code = Unknown desc = failed to setup network for sandbox \"ef1bd4bdffa3c92218bb290e9a367deaa0ae1aca3473fdc0cef5a9151398a190\": plugin type=\"flannel\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory" pod="default/nginx-deployment-8587fbcb89-dcwgg" May 10 10:00:32.923466 kubelet[1808]: E0510 10:00:32.923442 1808 pod_workers.go:1301] "Error syncing pod, skipping" err="failed to \"CreatePodSandbox\" for \"nginx-deployment-8587fbcb89-dcwgg_default(1cbeb4fe-d3c0-44ed-a49e-b4bb6d94fb91)\" with CreatePodSandboxError: \"Failed to create sandbox for pod \\\"nginx-deployment-8587fbcb89-dcwgg_default(1cbeb4fe-d3c0-44ed-a49e-b4bb6d94fb91)\\\": rpc error: code = Unknown desc = failed to setup network for sandbox \\\"ef1bd4bdffa3c92218bb290e9a367deaa0ae1aca3473fdc0cef5a9151398a190\\\": plugin type=\\\"flannel\\\" failed (add): loadFlannelSubnetEnv failed: open /run/flannel/subnet.env: no such file or directory\"" pod="default/nginx-deployment-8587fbcb89-dcwgg" podUID="1cbeb4fe-d3c0-44ed-a49e-b4bb6d94fb91" May 10 10:00:33.056169 kubelet[1808]: E0510 10:00:33.056099 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:33.267299 systemd-networkd[1451]: flannel.1: Link UP May 10 10:00:33.267313 systemd-networkd[1451]: flannel.1: Gained carrier May 10 10:00:34.056428 kubelet[1808]: E0510 10:00:34.056311 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:34.640023 systemd-networkd[1451]: flannel.1: Gained IPv6LL May 10 10:00:35.057587 kubelet[1808]: E0510 10:00:35.057371 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:36.058061 kubelet[1808]: E0510 10:00:36.057978 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:37.060979 kubelet[1808]: E0510 10:00:37.060882 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:38.061796 kubelet[1808]: E0510 10:00:38.061743 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:39.061968 kubelet[1808]: E0510 10:00:39.061897 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:40.045370 kubelet[1808]: E0510 10:00:40.045275 1808 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:40.062128 kubelet[1808]: E0510 10:00:40.062049 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:41.063086 kubelet[1808]: E0510 10:00:41.063020 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:42.063749 kubelet[1808]: E0510 10:00:42.063687 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:43.064440 kubelet[1808]: E0510 10:00:43.064292 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:44.065556 kubelet[1808]: E0510 10:00:44.065472 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:44.495392 containerd[1512]: time="2025-05-10T10:00:44.495306360Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-dcwgg,Uid:1cbeb4fe-d3c0-44ed-a49e-b4bb6d94fb91,Namespace:default,Attempt:0,}" May 10 10:00:44.507490 systemd-networkd[1451]: cni0: Link UP May 10 10:00:44.507499 systemd-networkd[1451]: cni0: Gained carrier May 10 10:00:44.512785 systemd-networkd[1451]: cni0: Lost carrier May 10 10:00:44.516813 systemd-networkd[1451]: veth8c64604c: Link UP May 10 10:00:44.520357 kernel: cni0: port 1(veth8c64604c) entered blocking state May 10 10:00:44.520430 kernel: cni0: port 1(veth8c64604c) entered disabled state May 10 10:00:44.520452 kernel: veth8c64604c: entered allmulticast mode May 10 10:00:44.520473 kernel: veth8c64604c: entered promiscuous mode May 10 10:00:44.521659 kernel: cni0: port 1(veth8c64604c) entered blocking state May 10 10:00:44.521712 kernel: cni0: port 1(veth8c64604c) entered forwarding state May 10 10:00:44.523547 kernel: cni0: port 1(veth8c64604c) entered disabled state May 10 10:00:44.531024 kernel: cni0: port 1(veth8c64604c) entered blocking state May 10 10:00:44.531098 kernel: cni0: port 1(veth8c64604c) entered forwarding state May 10 10:00:44.530987 systemd-networkd[1451]: veth8c64604c: Gained carrier May 10 10:00:44.531298 systemd-networkd[1451]: cni0: Gained carrier May 10 10:00:44.534407 containerd[1512]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00001e938), "name":"cbr0", "type":"bridge"} May 10 10:00:44.534407 containerd[1512]: delegateAdd: netconf sent to delegate plugin: May 10 10:00:44.561238 containerd[1512]: {"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-05-10T10:00:44.560991365Z" level=info msg="connecting to shim 164054a5a9783dcaf7a998283bb1f5638289b42dd3101d30ac493eb3be870651" address="unix:///run/containerd/s/89fd9bd1daa1456f73bdddd0a9ba5130e8f32f0eece1e7ebbde799b73a7268ee" namespace=k8s.io protocol=ttrpc version=3 May 10 10:00:44.589975 systemd[1]: Started cri-containerd-164054a5a9783dcaf7a998283bb1f5638289b42dd3101d30ac493eb3be870651.scope - libcontainer container 164054a5a9783dcaf7a998283bb1f5638289b42dd3101d30ac493eb3be870651. May 10 10:00:44.602953 systemd-resolved[1369]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 10 10:00:44.695586 containerd[1512]: time="2025-05-10T10:00:44.695516492Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-8587fbcb89-dcwgg,Uid:1cbeb4fe-d3c0-44ed-a49e-b4bb6d94fb91,Namespace:default,Attempt:0,} returns sandbox id \"164054a5a9783dcaf7a998283bb1f5638289b42dd3101d30ac493eb3be870651\"" May 10 10:00:44.697332 containerd[1512]: time="2025-05-10T10:00:44.697275005Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 10 10:00:45.066367 kubelet[1808]: E0510 10:00:45.066309 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:45.647992 systemd-networkd[1451]: cni0: Gained IPv6LL May 10 10:00:46.067175 kubelet[1808]: E0510 10:00:46.067022 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:46.096006 systemd-networkd[1451]: veth8c64604c: Gained IPv6LL May 10 10:00:47.067488 kubelet[1808]: E0510 10:00:47.067416 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:48.068312 kubelet[1808]: E0510 10:00:48.068230 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:49.068702 kubelet[1808]: E0510 10:00:49.068648 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:49.384942 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2497576072.mount: Deactivated successfully. May 10 10:00:50.069011 kubelet[1808]: E0510 10:00:50.068966 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:50.383216 containerd[1512]: time="2025-05-10T10:00:50.383150200Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:50.383974 containerd[1512]: time="2025-05-10T10:00:50.383944980Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73306220" May 10 10:00:50.385194 containerd[1512]: time="2025-05-10T10:00:50.385157097Z" level=info msg="ImageCreate event name:\"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:50.388272 containerd[1512]: time="2025-05-10T10:00:50.388215945Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:00:50.389241 containerd[1512]: time="2025-05-10T10:00:50.389181531Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"73306098\" in 5.691867201s" May 10 10:00:50.389241 containerd[1512]: time="2025-05-10T10:00:50.389231576Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 10 10:00:50.391355 containerd[1512]: time="2025-05-10T10:00:50.391324909Z" level=info msg="CreateContainer within sandbox \"164054a5a9783dcaf7a998283bb1f5638289b42dd3101d30ac493eb3be870651\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 10 10:00:50.400714 containerd[1512]: time="2025-05-10T10:00:50.400635880Z" level=info msg="Container e2e4f2954a253223a6189a2cb07a33cd87ce33de4953a8f5db430febb606a52c: CDI devices from CRI Config.CDIDevices: []" May 10 10:00:50.404954 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1245557892.mount: Deactivated successfully. May 10 10:00:50.409532 containerd[1512]: time="2025-05-10T10:00:50.409492012Z" level=info msg="CreateContainer within sandbox \"164054a5a9783dcaf7a998283bb1f5638289b42dd3101d30ac493eb3be870651\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"e2e4f2954a253223a6189a2cb07a33cd87ce33de4953a8f5db430febb606a52c\"" May 10 10:00:50.410139 containerd[1512]: time="2025-05-10T10:00:50.410103692Z" level=info msg="StartContainer for \"e2e4f2954a253223a6189a2cb07a33cd87ce33de4953a8f5db430febb606a52c\"" May 10 10:00:50.411191 containerd[1512]: time="2025-05-10T10:00:50.411164991Z" level=info msg="connecting to shim e2e4f2954a253223a6189a2cb07a33cd87ce33de4953a8f5db430febb606a52c" address="unix:///run/containerd/s/89fd9bd1daa1456f73bdddd0a9ba5130e8f32f0eece1e7ebbde799b73a7268ee" protocol=ttrpc version=3 May 10 10:00:50.438052 systemd[1]: Started cri-containerd-e2e4f2954a253223a6189a2cb07a33cd87ce33de4953a8f5db430febb606a52c.scope - libcontainer container e2e4f2954a253223a6189a2cb07a33cd87ce33de4953a8f5db430febb606a52c. May 10 10:00:50.474874 containerd[1512]: time="2025-05-10T10:00:50.474798239Z" level=info msg="StartContainer for \"e2e4f2954a253223a6189a2cb07a33cd87ce33de4953a8f5db430febb606a52c\" returns successfully" May 10 10:00:51.069279 kubelet[1808]: E0510 10:00:51.069209 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:52.070456 kubelet[1808]: E0510 10:00:52.070332 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:53.071330 kubelet[1808]: E0510 10:00:53.071260 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:54.072512 kubelet[1808]: E0510 10:00:54.072430 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:55.073446 kubelet[1808]: E0510 10:00:55.073375 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:55.074042 kubelet[1808]: I0510 10:00:55.073972 1808 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-8587fbcb89-dcwgg" podStartSLOduration=17.380871524 podStartE2EDuration="23.073954244s" podCreationTimestamp="2025-05-10 10:00:32 +0000 UTC" firstStartedPulling="2025-05-10 10:00:44.696983913 +0000 UTC m=+25.130538553" lastFinishedPulling="2025-05-10 10:00:50.390066633 +0000 UTC m=+30.823621273" observedRunningTime="2025-05-10 10:00:50.572386895 +0000 UTC m=+31.005941556" watchObservedRunningTime="2025-05-10 10:00:55.073954244 +0000 UTC m=+35.507508894" May 10 10:00:55.084122 systemd[1]: Created slice kubepods-besteffort-pod14bb83ee_c6ed_41cc_8d42_aa8ddcaac9d1.slice - libcontainer container kubepods-besteffort-pod14bb83ee_c6ed_41cc_8d42_aa8ddcaac9d1.slice. May 10 10:00:55.178084 kubelet[1808]: I0510 10:00:55.177996 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/14bb83ee-c6ed-41cc-8d42-aa8ddcaac9d1-data\") pod \"nfs-server-provisioner-0\" (UID: \"14bb83ee-c6ed-41cc-8d42-aa8ddcaac9d1\") " pod="default/nfs-server-provisioner-0" May 10 10:00:55.178084 kubelet[1808]: I0510 10:00:55.178060 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dlbsp\" (UniqueName: \"kubernetes.io/projected/14bb83ee-c6ed-41cc-8d42-aa8ddcaac9d1-kube-api-access-dlbsp\") pod \"nfs-server-provisioner-0\" (UID: \"14bb83ee-c6ed-41cc-8d42-aa8ddcaac9d1\") " pod="default/nfs-server-provisioner-0" May 10 10:00:55.389649 containerd[1512]: time="2025-05-10T10:00:55.389582203Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:14bb83ee-c6ed-41cc-8d42-aa8ddcaac9d1,Namespace:default,Attempt:0,}" May 10 10:00:55.402760 systemd-networkd[1451]: veth27a6bddd: Link UP May 10 10:00:55.405849 kernel: cni0: port 2(veth27a6bddd) entered blocking state May 10 10:00:55.405923 kernel: cni0: port 2(veth27a6bddd) entered disabled state May 10 10:00:55.405952 kernel: veth27a6bddd: entered allmulticast mode May 10 10:00:55.405978 kernel: veth27a6bddd: entered promiscuous mode May 10 10:00:55.411931 kernel: cni0: port 2(veth27a6bddd) entered blocking state May 10 10:00:55.412009 kernel: cni0: port 2(veth27a6bddd) entered forwarding state May 10 10:00:55.412273 systemd-networkd[1451]: veth27a6bddd: Gained carrier May 10 10:00:55.415570 containerd[1512]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00001a938), "name":"cbr0", "type":"bridge"} May 10 10:00:55.415570 containerd[1512]: delegateAdd: netconf sent to delegate plugin: May 10 10:00:55.447458 containerd[1512]: {"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-05-10T10:00:55.447401221Z" level=info msg="connecting to shim ea48cba06f6e1e1f9bc2ba5b1c21b59218c53b3e34dd6403d588ea55a183b3bc" address="unix:///run/containerd/s/ff1cb329d669e5577361bc86d50bd4fe2ac1a3dc183463d3a31c376b05230980" namespace=k8s.io protocol=ttrpc version=3 May 10 10:00:55.478988 systemd[1]: Started cri-containerd-ea48cba06f6e1e1f9bc2ba5b1c21b59218c53b3e34dd6403d588ea55a183b3bc.scope - libcontainer container ea48cba06f6e1e1f9bc2ba5b1c21b59218c53b3e34dd6403d588ea55a183b3bc. May 10 10:00:55.497861 systemd-resolved[1369]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 10 10:00:56.073997 kubelet[1808]: E0510 10:00:56.073932 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:56.414169 containerd[1512]: time="2025-05-10T10:00:56.414108433Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:14bb83ee-c6ed-41cc-8d42-aa8ddcaac9d1,Namespace:default,Attempt:0,} returns sandbox id \"ea48cba06f6e1e1f9bc2ba5b1c21b59218c53b3e34dd6403d588ea55a183b3bc\"" May 10 10:00:56.415900 containerd[1512]: time="2025-05-10T10:00:56.415871704Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 10 10:00:56.720154 systemd-networkd[1451]: veth27a6bddd: Gained IPv6LL May 10 10:00:56.756101 update_engine[1501]: I20250510 10:00:56.755975 1501 update_attempter.cc:509] Updating boot flags... May 10 10:00:56.783862 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2661) May 10 10:00:56.814986 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2661) May 10 10:00:57.075150 kubelet[1808]: E0510 10:00:57.075012 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:58.076035 kubelet[1808]: E0510 10:00:58.075947 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:00:59.027141 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3140840619.mount: Deactivated successfully. May 10 10:00:59.077026 kubelet[1808]: E0510 10:00:59.076941 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:00.045287 kubelet[1808]: E0510 10:01:00.045226 1808 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:00.077710 kubelet[1808]: E0510 10:01:00.077674 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:01.078365 kubelet[1808]: E0510 10:01:01.078310 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:01.238520 containerd[1512]: time="2025-05-10T10:01:01.238453139Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:01:01.239426 containerd[1512]: time="2025-05-10T10:01:01.239357360Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" May 10 10:01:01.241571 containerd[1512]: time="2025-05-10T10:01:01.241522409Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:01:01.243874 containerd[1512]: time="2025-05-10T10:01:01.243813457Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:01:01.245044 containerd[1512]: time="2025-05-10T10:01:01.244990475Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 4.829087431s" May 10 10:01:01.245044 containerd[1512]: time="2025-05-10T10:01:01.245028157Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" May 10 10:01:01.247247 containerd[1512]: time="2025-05-10T10:01:01.247205679Z" level=info msg="CreateContainer within sandbox \"ea48cba06f6e1e1f9bc2ba5b1c21b59218c53b3e34dd6403d588ea55a183b3bc\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 10 10:01:01.256169 containerd[1512]: time="2025-05-10T10:01:01.256127752Z" level=info msg="Container b571121ec746d37649cda6ade409708c1f4bf95266f05b3d8f71c826cf0a81f1: CDI devices from CRI Config.CDIDevices: []" May 10 10:01:01.265685 containerd[1512]: time="2025-05-10T10:01:01.265641024Z" level=info msg="CreateContainer within sandbox \"ea48cba06f6e1e1f9bc2ba5b1c21b59218c53b3e34dd6403d588ea55a183b3bc\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"b571121ec746d37649cda6ade409708c1f4bf95266f05b3d8f71c826cf0a81f1\"" May 10 10:01:01.266306 containerd[1512]: time="2025-05-10T10:01:01.266252571Z" level=info msg="StartContainer for \"b571121ec746d37649cda6ade409708c1f4bf95266f05b3d8f71c826cf0a81f1\"" May 10 10:01:01.267511 containerd[1512]: time="2025-05-10T10:01:01.267477761Z" level=info msg="connecting to shim b571121ec746d37649cda6ade409708c1f4bf95266f05b3d8f71c826cf0a81f1" address="unix:///run/containerd/s/ff1cb329d669e5577361bc86d50bd4fe2ac1a3dc183463d3a31c376b05230980" protocol=ttrpc version=3 May 10 10:01:01.291064 systemd[1]: Started cri-containerd-b571121ec746d37649cda6ade409708c1f4bf95266f05b3d8f71c826cf0a81f1.scope - libcontainer container b571121ec746d37649cda6ade409708c1f4bf95266f05b3d8f71c826cf0a81f1. May 10 10:01:01.331813 containerd[1512]: time="2025-05-10T10:01:01.331668043Z" level=info msg="StartContainer for \"b571121ec746d37649cda6ade409708c1f4bf95266f05b3d8f71c826cf0a81f1\" returns successfully" May 10 10:01:01.595493 kubelet[1808]: I0510 10:01:01.595277 1808 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.765047219 podStartE2EDuration="6.595255465s" podCreationTimestamp="2025-05-10 10:00:55 +0000 UTC" firstStartedPulling="2025-05-10 10:00:56.415529164 +0000 UTC m=+36.849083804" lastFinishedPulling="2025-05-10 10:01:01.2457374 +0000 UTC m=+41.679292050" observedRunningTime="2025-05-10 10:01:01.595173479 +0000 UTC m=+42.028728130" watchObservedRunningTime="2025-05-10 10:01:01.595255465 +0000 UTC m=+42.028810105" May 10 10:01:02.079433 kubelet[1808]: E0510 10:01:02.079371 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:03.080232 kubelet[1808]: E0510 10:01:03.080158 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:04.080717 kubelet[1808]: E0510 10:01:04.080623 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:05.081925 kubelet[1808]: E0510 10:01:05.081854 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:06.082991 kubelet[1808]: E0510 10:01:06.082906 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:07.083606 kubelet[1808]: E0510 10:01:07.083516 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:08.084137 kubelet[1808]: E0510 10:01:08.084010 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:09.084608 kubelet[1808]: E0510 10:01:09.084526 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:10.085237 kubelet[1808]: E0510 10:01:10.085148 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:11.085863 kubelet[1808]: E0510 10:01:11.085757 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:11.258390 systemd[1]: Created slice kubepods-besteffort-podab793fcb_b138_43a5_98f0_54de042c378e.slice - libcontainer container kubepods-besteffort-podab793fcb_b138_43a5_98f0_54de042c378e.slice. May 10 10:01:11.277742 kubelet[1808]: I0510 10:01:11.277672 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-6f9b87a3-c598-4ee2-899a-1e9886b18ee7\" (UniqueName: \"kubernetes.io/nfs/ab793fcb-b138-43a5-98f0-54de042c378e-pvc-6f9b87a3-c598-4ee2-899a-1e9886b18ee7\") pod \"test-pod-1\" (UID: \"ab793fcb-b138-43a5-98f0-54de042c378e\") " pod="default/test-pod-1" May 10 10:01:11.277936 kubelet[1808]: I0510 10:01:11.277761 1808 reconciler_common.go:245] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-96j77\" (UniqueName: \"kubernetes.io/projected/ab793fcb-b138-43a5-98f0-54de042c378e-kube-api-access-96j77\") pod \"test-pod-1\" (UID: \"ab793fcb-b138-43a5-98f0-54de042c378e\") " pod="default/test-pod-1" May 10 10:01:11.407892 kernel: FS-Cache: Loaded May 10 10:01:11.478513 kernel: RPC: Registered named UNIX socket transport module. May 10 10:01:11.478664 kernel: RPC: Registered udp transport module. May 10 10:01:11.478701 kernel: RPC: Registered tcp transport module. May 10 10:01:11.478728 kernel: RPC: Registered tcp-with-tls transport module. May 10 10:01:11.479231 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 10 10:01:11.780005 kernel: NFS: Registering the id_resolver key type May 10 10:01:11.780168 kernel: Key type id_resolver registered May 10 10:01:11.780200 kernel: Key type id_legacy registered May 10 10:01:11.885883 nfsidmap[2904]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf May 10 10:01:11.886693 nfsidmap[2904]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 10 10:01:11.889897 nfsidmap[2905]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf May 10 10:01:11.890215 nfsidmap[2905]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 10 10:01:11.901765 nfsrahead[2907]: setting /var/lib/kubelet/pods/ab793fcb-b138-43a5-98f0-54de042c378e/volumes/kubernetes.io~nfs/pvc-6f9b87a3-c598-4ee2-899a-1e9886b18ee7 readahead to 128 May 10 10:01:12.086635 kubelet[1808]: E0510 10:01:12.086544 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:12.162868 containerd[1512]: time="2025-05-10T10:01:12.162675611Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ab793fcb-b138-43a5-98f0-54de042c378e,Namespace:default,Attempt:0,}" May 10 10:01:12.188993 systemd-networkd[1451]: vetheb687e4e: Link UP May 10 10:01:12.191153 kernel: cni0: port 3(vetheb687e4e) entered blocking state May 10 10:01:12.191223 kernel: cni0: port 3(vetheb687e4e) entered disabled state May 10 10:01:12.191250 kernel: vetheb687e4e: entered allmulticast mode May 10 10:01:12.192897 kernel: vetheb687e4e: entered promiscuous mode May 10 10:01:12.201775 kernel: cni0: port 3(vetheb687e4e) entered blocking state May 10 10:01:12.201912 kernel: cni0: port 3(vetheb687e4e) entered forwarding state May 10 10:01:12.201903 systemd-networkd[1451]: vetheb687e4e: Gained carrier May 10 10:01:12.203956 containerd[1512]: 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"} May 10 10:01:12.203956 containerd[1512]: delegateAdd: netconf sent to delegate plugin: May 10 10:01:12.354427 containerd[1512]: {"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-05-10T10:01:12.354369357Z" level=info msg="connecting to shim 9bc151a40dc94a7f5bf4e47e1927a4460dc51c38506eaadfb074c855e129be57" address="unix:///run/containerd/s/4f276a7ef7bb91375b99adda40506aec0749117db6723a7b76e788232c17c1a7" namespace=k8s.io protocol=ttrpc version=3 May 10 10:01:12.394121 systemd[1]: Started cri-containerd-9bc151a40dc94a7f5bf4e47e1927a4460dc51c38506eaadfb074c855e129be57.scope - libcontainer container 9bc151a40dc94a7f5bf4e47e1927a4460dc51c38506eaadfb074c855e129be57. May 10 10:01:12.410234 systemd-resolved[1369]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 10 10:01:12.514996 containerd[1512]: time="2025-05-10T10:01:12.514869609Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ab793fcb-b138-43a5-98f0-54de042c378e,Namespace:default,Attempt:0,} returns sandbox id \"9bc151a40dc94a7f5bf4e47e1927a4460dc51c38506eaadfb074c855e129be57\"" May 10 10:01:12.516536 containerd[1512]: time="2025-05-10T10:01:12.516451370Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 10 10:01:13.026273 containerd[1512]: time="2025-05-10T10:01:13.026170810Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 10 10:01:13.027181 containerd[1512]: time="2025-05-10T10:01:13.027145476Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 10 10:01:13.029761 containerd[1512]: time="2025-05-10T10:01:13.029729795Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\", size \"73306098\" in 513.24369ms" May 10 10:01:13.029828 containerd[1512]: time="2025-05-10T10:01:13.029764950Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 10 10:01:13.031657 containerd[1512]: time="2025-05-10T10:01:13.031630906Z" level=info msg="CreateContainer within sandbox \"9bc151a40dc94a7f5bf4e47e1927a4460dc51c38506eaadfb074c855e129be57\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 10 10:01:13.042180 containerd[1512]: time="2025-05-10T10:01:13.042114523Z" level=info msg="Container 5acfc7622674034bfc5f3a0f2f0786f595501cab649787db69c73d04dbd3f111: CDI devices from CRI Config.CDIDevices: []" May 10 10:01:13.046740 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3855619620.mount: Deactivated successfully. May 10 10:01:13.051966 containerd[1512]: time="2025-05-10T10:01:13.051933428Z" level=info msg="CreateContainer within sandbox \"9bc151a40dc94a7f5bf4e47e1927a4460dc51c38506eaadfb074c855e129be57\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"5acfc7622674034bfc5f3a0f2f0786f595501cab649787db69c73d04dbd3f111\"" May 10 10:01:13.052390 containerd[1512]: time="2025-05-10T10:01:13.052355943Z" level=info msg="StartContainer for \"5acfc7622674034bfc5f3a0f2f0786f595501cab649787db69c73d04dbd3f111\"" May 10 10:01:13.053205 containerd[1512]: time="2025-05-10T10:01:13.053172712Z" level=info msg="connecting to shim 5acfc7622674034bfc5f3a0f2f0786f595501cab649787db69c73d04dbd3f111" address="unix:///run/containerd/s/4f276a7ef7bb91375b99adda40506aec0749117db6723a7b76e788232c17c1a7" protocol=ttrpc version=3 May 10 10:01:13.084068 systemd[1]: Started cri-containerd-5acfc7622674034bfc5f3a0f2f0786f595501cab649787db69c73d04dbd3f111.scope - libcontainer container 5acfc7622674034bfc5f3a0f2f0786f595501cab649787db69c73d04dbd3f111. May 10 10:01:13.087391 kubelet[1808]: E0510 10:01:13.087339 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:13.129303 containerd[1512]: time="2025-05-10T10:01:13.129240907Z" level=info msg="StartContainer for \"5acfc7622674034bfc5f3a0f2f0786f595501cab649787db69c73d04dbd3f111\" returns successfully" May 10 10:01:13.649871 kubelet[1808]: I0510 10:01:13.649792 1808 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=18.135554741 podStartE2EDuration="18.649771494s" podCreationTimestamp="2025-05-10 10:00:55 +0000 UTC" firstStartedPulling="2025-05-10 10:01:12.516220114 +0000 UTC m=+52.949774754" lastFinishedPulling="2025-05-10 10:01:13.030436867 +0000 UTC m=+53.463991507" observedRunningTime="2025-05-10 10:01:13.649569965 +0000 UTC m=+54.083124606" watchObservedRunningTime="2025-05-10 10:01:13.649771494 +0000 UTC m=+54.083326134" May 10 10:01:13.872130 systemd-networkd[1451]: vetheb687e4e: Gained IPv6LL May 10 10:01:14.088154 kubelet[1808]: E0510 10:01:14.087946 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:15.088886 kubelet[1808]: E0510 10:01:15.088714 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:16.089822 kubelet[1808]: E0510 10:01:16.089685 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:17.090519 kubelet[1808]: E0510 10:01:17.090433 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 10 10:01:18.091547 kubelet[1808]: E0510 10:01:18.091431 1808 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"