May 8 00:44:33.890360 kernel: Linux version 6.6.88-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Wed May 7 22:54:21 -00 2025 May 8 00:44:33.890387 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=86cfbfcc89a9c46f6cbba5bdb3509d1ce1367f0c93b0b0e4c6bdcad1a2064c90 May 8 00:44:33.890402 kernel: BIOS-provided physical RAM map: May 8 00:44:33.890410 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable May 8 00:44:33.890419 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable May 8 00:44:33.890437 kernel: BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS May 8 00:44:33.890447 kernel: BIOS-e820: [mem 0x0000000000808000-0x000000000080afff] usable May 8 00:44:33.890456 kernel: BIOS-e820: [mem 0x000000000080b000-0x000000000080bfff] ACPI NVS May 8 00:44:33.890464 kernel: BIOS-e820: [mem 0x000000000080c000-0x000000000080ffff] usable May 8 00:44:33.890473 kernel: BIOS-e820: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS May 8 00:44:33.890485 kernel: BIOS-e820: [mem 0x0000000000900000-0x000000009c8eefff] usable May 8 00:44:33.890493 kernel: BIOS-e820: [mem 0x000000009c8ef000-0x000000009c9eefff] reserved May 8 00:44:33.890502 kernel: BIOS-e820: [mem 0x000000009c9ef000-0x000000009caeefff] type 20 May 8 00:44:33.890510 kernel: BIOS-e820: [mem 0x000000009caef000-0x000000009cb6efff] reserved May 8 00:44:33.890521 kernel: BIOS-e820: [mem 0x000000009cb6f000-0x000000009cb7efff] ACPI data May 8 00:44:33.890530 kernel: BIOS-e820: [mem 0x000000009cb7f000-0x000000009cbfefff] ACPI NVS May 8 00:44:33.890542 kernel: BIOS-e820: [mem 0x000000009cbff000-0x000000009cf3ffff] usable May 8 00:44:33.890551 kernel: BIOS-e820: [mem 0x000000009cf40000-0x000000009cf5ffff] reserved May 8 00:44:33.890560 kernel: BIOS-e820: [mem 0x000000009cf60000-0x000000009cffffff] ACPI NVS May 8 00:44:33.890569 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved May 8 00:44:33.890579 kernel: NX (Execute Disable) protection: active May 8 00:44:33.890588 kernel: APIC: Static calls initialized May 8 00:44:33.890597 kernel: efi: EFI v2.7 by EDK II May 8 00:44:33.890606 kernel: efi: SMBIOS=0x9c9ab000 ACPI=0x9cb7e000 ACPI 2.0=0x9cb7e014 MEMATTR=0x9b675198 May 8 00:44:33.890614 kernel: SMBIOS 2.8 present. May 8 00:44:33.890623 kernel: DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 0.0.0 02/06/2015 May 8 00:44:33.890631 kernel: Hypervisor detected: KVM May 8 00:44:33.890643 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 May 8 00:44:33.890652 kernel: kvm-clock: using sched offset of 4034055965 cycles May 8 00:44:33.890661 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 8 00:44:33.890670 kernel: tsc: Detected 2794.748 MHz processor May 8 00:44:33.890680 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 8 00:44:33.890690 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 8 00:44:33.890699 kernel: last_pfn = 0x9cf40 max_arch_pfn = 0x400000000 May 8 00:44:33.890708 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs May 8 00:44:33.890718 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 8 00:44:33.890743 kernel: Using GB pages for direct mapping May 8 00:44:33.890753 kernel: Secure boot disabled May 8 00:44:33.890762 kernel: ACPI: Early table checksum verification disabled May 8 00:44:33.890772 kernel: ACPI: RSDP 0x000000009CB7E014 000024 (v02 BOCHS ) May 8 00:44:33.890788 kernel: ACPI: XSDT 0x000000009CB7D0E8 000054 (v01 BOCHS BXPC 00000001 01000013) May 8 00:44:33.890798 kernel: ACPI: FACP 0x000000009CB79000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) May 8 00:44:33.890807 kernel: ACPI: DSDT 0x000000009CB7A000 0021A8 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 8 00:44:33.890820 kernel: ACPI: FACS 0x000000009CBDD000 000040 May 8 00:44:33.890830 kernel: ACPI: APIC 0x000000009CB78000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 8 00:44:33.890839 kernel: ACPI: HPET 0x000000009CB77000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 8 00:44:33.890849 kernel: ACPI: MCFG 0x000000009CB76000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) May 8 00:44:33.890859 kernel: ACPI: WAET 0x000000009CB75000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) May 8 00:44:33.890868 kernel: ACPI: BGRT 0x000000009CB74000 000038 (v01 INTEL EDK2 00000002 01000013) May 8 00:44:33.890878 kernel: ACPI: Reserving FACP table memory at [mem 0x9cb79000-0x9cb790f3] May 8 00:44:33.890891 kernel: ACPI: Reserving DSDT table memory at [mem 0x9cb7a000-0x9cb7c1a7] May 8 00:44:33.890901 kernel: ACPI: Reserving FACS table memory at [mem 0x9cbdd000-0x9cbdd03f] May 8 00:44:33.890922 kernel: ACPI: Reserving APIC table memory at [mem 0x9cb78000-0x9cb7808f] May 8 00:44:33.890932 kernel: ACPI: Reserving HPET table memory at [mem 0x9cb77000-0x9cb77037] May 8 00:44:33.891039 kernel: ACPI: Reserving MCFG table memory at [mem 0x9cb76000-0x9cb7603b] May 8 00:44:33.891049 kernel: ACPI: Reserving WAET table memory at [mem 0x9cb75000-0x9cb75027] May 8 00:44:33.891058 kernel: ACPI: Reserving BGRT table memory at [mem 0x9cb74000-0x9cb74037] May 8 00:44:33.891067 kernel: No NUMA configuration found May 8 00:44:33.891077 kernel: Faking a node at [mem 0x0000000000000000-0x000000009cf3ffff] May 8 00:44:33.891090 kernel: NODE_DATA(0) allocated [mem 0x9cea6000-0x9ceabfff] May 8 00:44:33.891100 kernel: Zone ranges: May 8 00:44:33.891109 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 8 00:44:33.891118 kernel: DMA32 [mem 0x0000000001000000-0x000000009cf3ffff] May 8 00:44:33.891128 kernel: Normal empty May 8 00:44:33.891138 kernel: Movable zone start for each node May 8 00:44:33.891148 kernel: Early memory node ranges May 8 00:44:33.891157 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] May 8 00:44:33.891167 kernel: node 0: [mem 0x0000000000100000-0x00000000007fffff] May 8 00:44:33.891176 kernel: node 0: [mem 0x0000000000808000-0x000000000080afff] May 8 00:44:33.891190 kernel: node 0: [mem 0x000000000080c000-0x000000000080ffff] May 8 00:44:33.891200 kernel: node 0: [mem 0x0000000000900000-0x000000009c8eefff] May 8 00:44:33.891209 kernel: node 0: [mem 0x000000009cbff000-0x000000009cf3ffff] May 8 00:44:33.891220 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000009cf3ffff] May 8 00:44:33.891229 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 8 00:44:33.891240 kernel: On node 0, zone DMA: 96 pages in unavailable ranges May 8 00:44:33.891250 kernel: On node 0, zone DMA: 8 pages in unavailable ranges May 8 00:44:33.891259 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 8 00:44:33.891269 kernel: On node 0, zone DMA: 240 pages in unavailable ranges May 8 00:44:33.891282 kernel: On node 0, zone DMA32: 784 pages in unavailable ranges May 8 00:44:33.891292 kernel: On node 0, zone DMA32: 12480 pages in unavailable ranges May 8 00:44:33.891302 kernel: ACPI: PM-Timer IO Port: 0x608 May 8 00:44:33.891312 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) May 8 00:44:33.891322 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 8 00:44:33.891332 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) May 8 00:44:33.891342 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) May 8 00:44:33.891352 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 8 00:44:33.891361 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) May 8 00:44:33.891371 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) May 8 00:44:33.891385 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 8 00:44:33.891396 kernel: ACPI: HPET id: 0x8086a201 base: 0xfed00000 May 8 00:44:33.891405 kernel: TSC deadline timer available May 8 00:44:33.891415 kernel: smpboot: Allowing 4 CPUs, 0 hotplug CPUs May 8 00:44:33.891425 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() May 8 00:44:33.891445 kernel: kvm-guest: KVM setup pv remote TLB flush May 8 00:44:33.891455 kernel: kvm-guest: setup PV sched yield May 8 00:44:33.891465 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices May 8 00:44:33.891475 kernel: Booting paravirtualized kernel on KVM May 8 00:44:33.891489 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 8 00:44:33.891499 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 May 8 00:44:33.891509 kernel: percpu: Embedded 58 pages/cpu s197096 r8192 d32280 u524288 May 8 00:44:33.891519 kernel: pcpu-alloc: s197096 r8192 d32280 u524288 alloc=1*2097152 May 8 00:44:33.891528 kernel: pcpu-alloc: [0] 0 1 2 3 May 8 00:44:33.891537 kernel: kvm-guest: PV spinlocks enabled May 8 00:44:33.891547 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 8 00:44:33.891558 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=86cfbfcc89a9c46f6cbba5bdb3509d1ce1367f0c93b0b0e4c6bdcad1a2064c90 May 8 00:44:33.891572 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 8 00:44:33.891582 kernel: random: crng init done May 8 00:44:33.891592 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 8 00:44:33.891602 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 8 00:44:33.891612 kernel: Fallback order for Node 0: 0 May 8 00:44:33.891623 kernel: Built 1 zonelists, mobility grouping on. Total pages: 629759 May 8 00:44:33.891633 kernel: Policy zone: DMA32 May 8 00:44:33.891642 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 8 00:44:33.891652 kernel: Memory: 2400600K/2567000K available (12288K kernel code, 2295K rwdata, 22740K rodata, 42856K init, 2336K bss, 166140K reserved, 0K cma-reserved) May 8 00:44:33.891666 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 8 00:44:33.891677 kernel: ftrace: allocating 37944 entries in 149 pages May 8 00:44:33.891688 kernel: ftrace: allocated 149 pages with 4 groups May 8 00:44:33.891697 kernel: Dynamic Preempt: voluntary May 8 00:44:33.891716 kernel: rcu: Preemptible hierarchical RCU implementation. May 8 00:44:33.891729 kernel: rcu: RCU event tracing is enabled. May 8 00:44:33.891740 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. May 8 00:44:33.891750 kernel: Trampoline variant of Tasks RCU enabled. May 8 00:44:33.891760 kernel: Rude variant of Tasks RCU enabled. May 8 00:44:33.891770 kernel: Tracing variant of Tasks RCU enabled. May 8 00:44:33.891780 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 8 00:44:33.891791 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 May 8 00:44:33.891804 kernel: NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16 May 8 00:44:33.891815 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 8 00:44:33.891824 kernel: Console: colour dummy device 80x25 May 8 00:44:33.891834 kernel: printk: console [ttyS0] enabled May 8 00:44:33.891845 kernel: ACPI: Core revision 20230628 May 8 00:44:33.891859 kernel: clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns May 8 00:44:33.891869 kernel: APIC: Switch to symmetric I/O mode setup May 8 00:44:33.891879 kernel: x2apic enabled May 8 00:44:33.891890 kernel: APIC: Switched APIC routing to: physical x2apic May 8 00:44:33.891900 kernel: kvm-guest: APIC: send_IPI_mask() replaced with kvm_send_ipi_mask() May 8 00:44:33.891910 kernel: kvm-guest: APIC: send_IPI_mask_allbutself() replaced with kvm_send_ipi_mask_allbutself() May 8 00:44:33.891921 kernel: kvm-guest: setup PV IPIs May 8 00:44:33.891931 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 May 8 00:44:33.891957 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized May 8 00:44:33.891972 kernel: Calibrating delay loop (skipped) preset value.. 5589.49 BogoMIPS (lpj=2794748) May 8 00:44:33.891983 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated May 8 00:44:33.891993 kernel: Last level iTLB entries: 4KB 512, 2MB 255, 4MB 127 May 8 00:44:33.892003 kernel: Last level dTLB entries: 4KB 512, 2MB 255, 4MB 127, 1GB 0 May 8 00:44:33.892014 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 8 00:44:33.892024 kernel: Spectre V2 : Mitigation: Retpolines May 8 00:44:33.892034 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch May 8 00:44:33.892043 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT May 8 00:44:33.892051 kernel: Spectre V2 : Enabling Speculation Barrier for firmware calls May 8 00:44:33.892061 kernel: RETBleed: Mitigation: untrained return thunk May 8 00:44:33.892069 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier May 8 00:44:33.892076 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl May 8 00:44:33.892084 kernel: Speculative Return Stack Overflow: IBPB-extending microcode not applied! May 8 00:44:33.892092 kernel: Speculative Return Stack Overflow: WARNING: See https://kernel.org/doc/html/latest/admin-guide/hw-vuln/srso.html for mitigation options. May 8 00:44:33.892100 kernel: Speculative Return Stack Overflow: Vulnerable: Safe RET, no microcode May 8 00:44:33.892107 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 8 00:44:33.892115 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 8 00:44:33.892124 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 8 00:44:33.892134 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 8 00:44:33.892172 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'compacted' format. May 8 00:44:33.892200 kernel: Freeing SMP alternatives memory: 32K May 8 00:44:33.892211 kernel: pid_max: default: 32768 minimum: 301 May 8 00:44:33.892221 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 8 00:44:33.892229 kernel: landlock: Up and running. May 8 00:44:33.892236 kernel: SELinux: Initializing. May 8 00:44:33.892247 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 8 00:44:33.892261 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 8 00:44:33.892272 kernel: smpboot: CPU0: AMD EPYC 7402P 24-Core Processor (family: 0x17, model: 0x31, stepping: 0x0) May 8 00:44:33.892288 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 8 00:44:33.892298 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 8 00:44:33.892309 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 8 00:44:33.892319 kernel: Performance Events: Fam17h+ core perfctr, AMD PMU driver. May 8 00:44:33.892328 kernel: ... version: 0 May 8 00:44:33.892338 kernel: ... bit width: 48 May 8 00:44:33.892348 kernel: ... generic registers: 6 May 8 00:44:33.892363 kernel: ... value mask: 0000ffffffffffff May 8 00:44:33.892373 kernel: ... max period: 00007fffffffffff May 8 00:44:33.892383 kernel: ... fixed-purpose events: 0 May 8 00:44:33.892394 kernel: ... event mask: 000000000000003f May 8 00:44:33.892404 kernel: signal: max sigframe size: 1776 May 8 00:44:33.892415 kernel: rcu: Hierarchical SRCU implementation. May 8 00:44:33.892437 kernel: rcu: Max phase no-delay instances is 400. May 8 00:44:33.892449 kernel: smp: Bringing up secondary CPUs ... May 8 00:44:33.892459 kernel: smpboot: x86: Booting SMP configuration: May 8 00:44:33.892474 kernel: .... node #0, CPUs: #1 #2 #3 May 8 00:44:33.892484 kernel: smp: Brought up 1 node, 4 CPUs May 8 00:44:33.892493 kernel: smpboot: Max logical packages: 1 May 8 00:44:33.892501 kernel: smpboot: Total of 4 processors activated (22357.98 BogoMIPS) May 8 00:44:33.892510 kernel: devtmpfs: initialized May 8 00:44:33.892517 kernel: x86/mm: Memory block size: 128MB May 8 00:44:33.892527 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) May 8 00:44:33.892537 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x0080b000-0x0080bfff] (4096 bytes) May 8 00:44:33.892547 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x00810000-0x008fffff] (983040 bytes) May 8 00:44:33.892560 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cb7f000-0x9cbfefff] (524288 bytes) May 8 00:44:33.892570 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x9cf60000-0x9cffffff] (655360 bytes) May 8 00:44:33.892581 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 8 00:44:33.892590 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 8 00:44:33.892597 kernel: pinctrl core: initialized pinctrl subsystem May 8 00:44:33.892606 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 8 00:44:33.892622 kernel: audit: initializing netlink subsys (disabled) May 8 00:44:33.892632 kernel: audit: type=2000 audit(1746665073.858:1): state=initialized audit_enabled=0 res=1 May 8 00:44:33.892640 kernel: thermal_sys: Registered thermal governor 'step_wise' May 8 00:44:33.892651 kernel: thermal_sys: Registered thermal governor 'user_space' May 8 00:44:33.892659 kernel: cpuidle: using governor menu May 8 00:44:33.892666 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 8 00:44:33.892673 kernel: dca service started, version 1.12.1 May 8 00:44:33.892681 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) May 8 00:44:33.892688 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry May 8 00:44:33.892698 kernel: PCI: Using configuration type 1 for base access May 8 00:44:33.892707 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 8 00:44:33.892714 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 8 00:44:33.892725 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 8 00:44:33.892732 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 8 00:44:33.892740 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 8 00:44:33.892747 kernel: ACPI: Added _OSI(Module Device) May 8 00:44:33.892754 kernel: ACPI: Added _OSI(Processor Device) May 8 00:44:33.892762 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 8 00:44:33.892769 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 8 00:44:33.892776 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 8 00:44:33.892784 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC May 8 00:44:33.892794 kernel: ACPI: Interpreter enabled May 8 00:44:33.892801 kernel: ACPI: PM: (supports S0 S3 S5) May 8 00:44:33.892808 kernel: ACPI: Using IOAPIC for interrupt routing May 8 00:44:33.892816 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 8 00:44:33.892823 kernel: PCI: Using E820 reservations for host bridge windows May 8 00:44:33.892830 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F May 8 00:44:33.892838 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) May 8 00:44:33.893062 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] May 8 00:44:33.893240 kernel: acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] May 8 00:44:33.893384 kernel: acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] May 8 00:44:33.893400 kernel: PCI host bridge to bus 0000:00 May 8 00:44:33.893551 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] May 8 00:44:33.893663 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] May 8 00:44:33.893778 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] May 8 00:44:33.893897 kernel: pci_bus 0000:00: root bus resource [mem 0x9d000000-0xafffffff window] May 8 00:44:33.894038 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] May 8 00:44:33.894169 kernel: pci_bus 0000:00: root bus resource [mem 0x800000000-0xfffffffff window] May 8 00:44:33.894280 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 8 00:44:33.894416 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 May 8 00:44:33.894562 kernel: pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 May 8 00:44:33.894706 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] May 8 00:44:33.894837 kernel: pci 0000:00:01.0: reg 0x18: [mem 0xc1044000-0xc1044fff] May 8 00:44:33.894971 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] May 8 00:44:33.895117 kernel: pci 0000:00:01.0: BAR 0: assigned to efifb May 8 00:44:33.895243 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] May 8 00:44:33.895371 kernel: pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00 May 8 00:44:33.895502 kernel: pci 0000:00:02.0: reg 0x10: [io 0x6100-0x611f] May 8 00:44:33.895622 kernel: pci 0000:00:02.0: reg 0x14: [mem 0xc1043000-0xc1043fff] May 8 00:44:33.895747 kernel: pci 0000:00:02.0: reg 0x20: [mem 0x800000000-0x800003fff 64bit pref] May 8 00:44:33.895875 kernel: pci 0000:00:03.0: [1af4:1001] type 00 class 0x010000 May 8 00:44:33.896059 kernel: pci 0000:00:03.0: reg 0x10: [io 0x6000-0x607f] May 8 00:44:33.896224 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xc1042000-0xc1042fff] May 8 00:44:33.896352 kernel: pci 0000:00:03.0: reg 0x20: [mem 0x800004000-0x800007fff 64bit pref] May 8 00:44:33.896500 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 May 8 00:44:33.896622 kernel: pci 0000:00:04.0: reg 0x10: [io 0x60e0-0x60ff] May 8 00:44:33.896747 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xc1041000-0xc1041fff] May 8 00:44:33.896867 kernel: pci 0000:00:04.0: reg 0x20: [mem 0x800008000-0x80000bfff 64bit pref] May 8 00:44:33.897019 kernel: pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] May 8 00:44:33.897175 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 May 8 00:44:33.897299 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO May 8 00:44:33.897435 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 May 8 00:44:33.897585 kernel: pci 0000:00:1f.2: reg 0x20: [io 0x60c0-0x60df] May 8 00:44:33.897727 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xc1040000-0xc1040fff] May 8 00:44:33.897867 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 May 8 00:44:33.898040 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x6080-0x60bf] May 8 00:44:33.898053 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 May 8 00:44:33.898062 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 May 8 00:44:33.898071 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 May 8 00:44:33.898080 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 May 8 00:44:33.898093 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 May 8 00:44:33.898102 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 May 8 00:44:33.898111 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 May 8 00:44:33.898120 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 May 8 00:44:33.898129 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 May 8 00:44:33.898138 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 May 8 00:44:33.898147 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 May 8 00:44:33.898156 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 May 8 00:44:33.898165 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 May 8 00:44:33.898176 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 May 8 00:44:33.898185 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 May 8 00:44:33.898194 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 May 8 00:44:33.898202 kernel: iommu: Default domain type: Translated May 8 00:44:33.898211 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 8 00:44:33.898220 kernel: efivars: Registered efivars operations May 8 00:44:33.898229 kernel: PCI: Using ACPI for IRQ routing May 8 00:44:33.898238 kernel: PCI: pci_cache_line_size set to 64 bytes May 8 00:44:33.898247 kernel: e820: reserve RAM buffer [mem 0x0080b000-0x008fffff] May 8 00:44:33.898258 kernel: e820: reserve RAM buffer [mem 0x00810000-0x008fffff] May 8 00:44:33.898267 kernel: e820: reserve RAM buffer [mem 0x9c8ef000-0x9fffffff] May 8 00:44:33.898276 kernel: e820: reserve RAM buffer [mem 0x9cf40000-0x9fffffff] May 8 00:44:33.898403 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device May 8 00:44:33.898541 kernel: pci 0000:00:01.0: vgaarb: bridge control possible May 8 00:44:33.898668 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none May 8 00:44:33.898679 kernel: vgaarb: loaded May 8 00:44:33.898689 kernel: hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 May 8 00:44:33.898698 kernel: hpet0: 3 comparators, 64-bit 100.000000 MHz counter May 8 00:44:33.898710 kernel: clocksource: Switched to clocksource kvm-clock May 8 00:44:33.898719 kernel: VFS: Disk quotas dquot_6.6.0 May 8 00:44:33.898729 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 8 00:44:33.898738 kernel: pnp: PnP ACPI init May 8 00:44:33.898873 kernel: system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved May 8 00:44:33.898886 kernel: pnp: PnP ACPI: found 6 devices May 8 00:44:33.898895 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 8 00:44:33.898904 kernel: NET: Registered PF_INET protocol family May 8 00:44:33.898916 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 8 00:44:33.898925 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 8 00:44:33.898947 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 8 00:44:33.898957 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 8 00:44:33.898966 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 8 00:44:33.898975 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 8 00:44:33.898984 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 8 00:44:33.898993 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 8 00:44:33.899002 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 8 00:44:33.899014 kernel: NET: Registered PF_XDP protocol family May 8 00:44:33.899144 kernel: pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window May 8 00:44:33.899273 kernel: pci 0000:00:04.0: BAR 6: assigned [mem 0x9d000000-0x9d03ffff pref] May 8 00:44:33.899391 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] May 8 00:44:33.899525 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] May 8 00:44:33.899644 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] May 8 00:44:33.899761 kernel: pci_bus 0000:00: resource 7 [mem 0x9d000000-0xafffffff window] May 8 00:44:33.899876 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] May 8 00:44:33.900087 kernel: pci_bus 0000:00: resource 9 [mem 0x800000000-0xfffffffff window] May 8 00:44:33.900101 kernel: PCI: CLS 0 bytes, default 64 May 8 00:44:33.900110 kernel: Initialise system trusted keyrings May 8 00:44:33.900119 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 8 00:44:33.900128 kernel: Key type asymmetric registered May 8 00:44:33.900137 kernel: Asymmetric key parser 'x509' registered May 8 00:44:33.900146 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 8 00:44:33.900155 kernel: io scheduler mq-deadline registered May 8 00:44:33.900164 kernel: io scheduler kyber registered May 8 00:44:33.900178 kernel: io scheduler bfq registered May 8 00:44:33.900187 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 8 00:44:33.900198 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 May 8 00:44:33.900208 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 May 8 00:44:33.900219 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 May 8 00:44:33.900229 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 8 00:44:33.900239 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 8 00:44:33.900250 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 May 8 00:44:33.900259 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 May 8 00:44:33.900271 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 May 8 00:44:33.900406 kernel: rtc_cmos 00:04: RTC can wake from S4 May 8 00:44:33.900419 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 May 8 00:44:33.900556 kernel: rtc_cmos 00:04: registered as rtc0 May 8 00:44:33.900675 kernel: rtc_cmos 00:04: setting system clock to 2025-05-08T00:44:33 UTC (1746665073) May 8 00:44:33.900794 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs May 8 00:44:33.900805 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled May 8 00:44:33.900818 kernel: efifb: probing for efifb May 8 00:44:33.900827 kernel: efifb: framebuffer at 0xc0000000, using 1408k, total 1408k May 8 00:44:33.900836 kernel: efifb: mode is 800x600x24, linelength=2400, pages=1 May 8 00:44:33.900845 kernel: efifb: scrolling: redraw May 8 00:44:33.900854 kernel: efifb: Truecolor: size=0:8:8:8, shift=0:16:8:0 May 8 00:44:33.900863 kernel: Console: switching to colour frame buffer device 100x37 May 8 00:44:33.900891 kernel: fb0: EFI VGA frame buffer device May 8 00:44:33.900903 kernel: pstore: Using crash dump compression: deflate May 8 00:44:33.900913 kernel: pstore: Registered efi_pstore as persistent store backend May 8 00:44:33.900922 kernel: NET: Registered PF_INET6 protocol family May 8 00:44:33.900933 kernel: Segment Routing with IPv6 May 8 00:44:33.900956 kernel: In-situ OAM (IOAM) with IPv6 May 8 00:44:33.900965 kernel: NET: Registered PF_PACKET protocol family May 8 00:44:33.900974 kernel: Key type dns_resolver registered May 8 00:44:33.900984 kernel: IPI shorthand broadcast: enabled May 8 00:44:33.900993 kernel: sched_clock: Marking stable (636002236, 128600498)->(819334517, -54731783) May 8 00:44:33.901002 kernel: registered taskstats version 1 May 8 00:44:33.901011 kernel: Loading compiled-in X.509 certificates May 8 00:44:33.901021 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: 75e4e434c57439d3f2eaf7797bbbcdd698dafd0e' May 8 00:44:33.901033 kernel: Key type .fscrypt registered May 8 00:44:33.901042 kernel: Key type fscrypt-provisioning registered May 8 00:44:33.901051 kernel: ima: No TPM chip found, activating TPM-bypass! May 8 00:44:33.901061 kernel: ima: Allocated hash algorithm: sha1 May 8 00:44:33.901070 kernel: ima: No architecture policies found May 8 00:44:33.901079 kernel: clk: Disabling unused clocks May 8 00:44:33.901088 kernel: Freeing unused kernel image (initmem) memory: 42856K May 8 00:44:33.901107 kernel: Write protecting the kernel read-only data: 36864k May 8 00:44:33.901120 kernel: Freeing unused kernel image (rodata/data gap) memory: 1836K May 8 00:44:33.901129 kernel: Run /init as init process May 8 00:44:33.901139 kernel: with arguments: May 8 00:44:33.901148 kernel: /init May 8 00:44:33.901157 kernel: with environment: May 8 00:44:33.901167 kernel: HOME=/ May 8 00:44:33.901176 kernel: TERM=linux May 8 00:44:33.901185 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 8 00:44:33.901197 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) May 8 00:44:33.901211 systemd[1]: Detected virtualization kvm. May 8 00:44:33.901221 systemd[1]: Detected architecture x86-64. May 8 00:44:33.901231 systemd[1]: Running in initrd. May 8 00:44:33.901243 systemd[1]: No hostname configured, using default hostname. May 8 00:44:33.901254 systemd[1]: Hostname set to . May 8 00:44:33.901265 systemd[1]: Initializing machine ID from VM UUID. May 8 00:44:33.901274 systemd[1]: Queued start job for default target initrd.target. May 8 00:44:33.901284 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 8 00:44:33.901294 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 8 00:44:33.901307 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 8 00:44:33.901317 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 8 00:44:33.901327 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 8 00:44:33.901340 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 8 00:44:33.901352 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 8 00:44:33.901362 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 8 00:44:33.901372 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 8 00:44:33.901382 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 8 00:44:33.901391 systemd[1]: Reached target paths.target - Path Units. May 8 00:44:33.901401 systemd[1]: Reached target slices.target - Slice Units. May 8 00:44:33.901413 systemd[1]: Reached target swap.target - Swaps. May 8 00:44:33.901423 systemd[1]: Reached target timers.target - Timer Units. May 8 00:44:33.901442 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 8 00:44:33.901452 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 8 00:44:33.901462 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 8 00:44:33.901472 systemd[1]: Listening on systemd-journald.socket - Journal Socket. May 8 00:44:33.901482 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 8 00:44:33.901491 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 8 00:44:33.901501 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 8 00:44:33.901514 systemd[1]: Reached target sockets.target - Socket Units. May 8 00:44:33.901524 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 8 00:44:33.901533 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 8 00:44:33.901544 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 8 00:44:33.901553 systemd[1]: Starting systemd-fsck-usr.service... May 8 00:44:33.901563 systemd[1]: Starting systemd-journald.service - Journal Service... May 8 00:44:33.901573 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 8 00:44:33.901583 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 8 00:44:33.901595 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 8 00:44:33.901605 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 8 00:44:33.901615 systemd[1]: Finished systemd-fsck-usr.service. May 8 00:44:33.901646 systemd-journald[193]: Collecting audit messages is disabled. May 8 00:44:33.901672 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 8 00:44:33.901683 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 8 00:44:33.901693 systemd-journald[193]: Journal started May 8 00:44:33.901716 systemd-journald[193]: Runtime Journal (/run/log/journal/00d7fa9cf11045fc958d4983c91b527b) is 6.0M, max 48.3M, 42.2M free. May 8 00:44:33.891006 systemd-modules-load[194]: Inserted module 'overlay' May 8 00:44:33.904389 systemd[1]: Started systemd-journald.service - Journal Service. May 8 00:44:33.905858 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:44:33.915111 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 8 00:44:33.916882 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 8 00:44:33.922089 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 8 00:44:33.924336 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 8 00:44:33.926538 systemd-modules-load[194]: Inserted module 'br_netfilter' May 8 00:44:33.927796 kernel: Bridge firewalling registered May 8 00:44:33.928728 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 8 00:44:33.932229 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 8 00:44:33.934286 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 8 00:44:33.942586 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 8 00:44:33.945504 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 8 00:44:33.953093 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 8 00:44:33.954503 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 8 00:44:33.957451 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 8 00:44:33.974812 dracut-cmdline[231]: dracut-dracut-053 May 8 00:44:33.978140 dracut-cmdline[231]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected verity.usrhash=86cfbfcc89a9c46f6cbba5bdb3509d1ce1367f0c93b0b0e4c6bdcad1a2064c90 May 8 00:44:33.988024 systemd-resolved[228]: Positive Trust Anchors: May 8 00:44:33.988041 systemd-resolved[228]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 8 00:44:33.988071 systemd-resolved[228]: 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 8 00:44:33.990689 systemd-resolved[228]: Defaulting to hostname 'linux'. May 8 00:44:33.991779 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 8 00:44:33.999915 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 8 00:44:34.085975 kernel: SCSI subsystem initialized May 8 00:44:34.095006 kernel: Loading iSCSI transport class v2.0-870. May 8 00:44:34.105967 kernel: iscsi: registered transport (tcp) May 8 00:44:34.129244 kernel: iscsi: registered transport (qla4xxx) May 8 00:44:34.129321 kernel: QLogic iSCSI HBA Driver May 8 00:44:34.181259 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 8 00:44:34.192181 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 8 00:44:34.222627 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 8 00:44:34.222710 kernel: device-mapper: uevent: version 1.0.3 May 8 00:44:34.222722 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 8 00:44:34.264972 kernel: raid6: avx2x4 gen() 27370 MB/s May 8 00:44:34.281968 kernel: raid6: avx2x2 gen() 31047 MB/s May 8 00:44:34.299121 kernel: raid6: avx2x1 gen() 24171 MB/s May 8 00:44:34.299185 kernel: raid6: using algorithm avx2x2 gen() 31047 MB/s May 8 00:44:34.317089 kernel: raid6: .... xor() 19951 MB/s, rmw enabled May 8 00:44:34.317125 kernel: raid6: using avx2x2 recovery algorithm May 8 00:44:34.336970 kernel: xor: automatically using best checksumming function avx May 8 00:44:34.504972 kernel: Btrfs loaded, zoned=no, fsverity=no May 8 00:44:34.518566 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 8 00:44:34.529094 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 8 00:44:34.542502 systemd-udevd[413]: Using default interface naming scheme 'v255'. May 8 00:44:34.547192 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 8 00:44:34.596127 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 8 00:44:34.610505 dracut-pre-trigger[427]: rd.md=0: removing MD RAID activation May 8 00:44:34.645723 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 8 00:44:34.658157 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 8 00:44:34.722861 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 8 00:44:34.734120 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 8 00:44:34.748759 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 8 00:44:34.749966 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 8 00:44:34.752201 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 8 00:44:34.753554 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 8 00:44:34.764136 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 8 00:44:34.774967 kernel: virtio_blk virtio1: 4/0/0 default/read/poll queues May 8 00:44:34.792067 kernel: cryptd: max_cpu_qlen set to 1000 May 8 00:44:34.792089 kernel: virtio_blk virtio1: [vda] 19775488 512-byte logical blocks (10.1 GB/9.43 GiB) May 8 00:44:34.792628 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. May 8 00:44:34.792652 kernel: GPT:9289727 != 19775487 May 8 00:44:34.792666 kernel: GPT:Alternate GPT header not at the end of the disk. May 8 00:44:34.792680 kernel: GPT:9289727 != 19775487 May 8 00:44:34.792692 kernel: GPT: Use GNU Parted to correct GPT errors. May 8 00:44:34.792705 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 8 00:44:34.792866 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 8 00:44:34.798102 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 8 00:44:34.799401 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 8 00:44:34.802961 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 8 00:44:34.805054 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 8 00:44:34.807982 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:44:34.812234 kernel: AVX2 version of gcm_enc/dec engaged. May 8 00:44:34.812285 kernel: libata version 3.00 loaded. May 8 00:44:34.812313 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 8 00:44:34.818962 kernel: AES CTR mode by8 optimization enabled May 8 00:44:34.819465 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 8 00:44:34.824117 kernel: BTRFS: device fsid 28014d97-e6d7-4db4-b1d9-76a980e09972 devid 1 transid 39 /dev/vda3 scanned by (udev-worker) (477) May 8 00:44:34.824137 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (461) May 8 00:44:34.834967 kernel: ahci 0000:00:1f.2: version 3.0 May 8 00:44:34.857481 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 May 8 00:44:34.857502 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode May 8 00:44:34.857694 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only May 8 00:44:34.857866 kernel: scsi host0: ahci May 8 00:44:34.858076 kernel: scsi host1: ahci May 8 00:44:34.858252 kernel: scsi host2: ahci May 8 00:44:34.858459 kernel: scsi host3: ahci May 8 00:44:34.858636 kernel: scsi host4: ahci May 8 00:44:34.858811 kernel: scsi host5: ahci May 8 00:44:34.859051 kernel: ata1: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040100 irq 34 May 8 00:44:34.859067 kernel: ata2: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040180 irq 34 May 8 00:44:34.859080 kernel: ata3: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040200 irq 34 May 8 00:44:34.859093 kernel: ata4: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040280 irq 34 May 8 00:44:34.859112 kernel: ata5: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040300 irq 34 May 8 00:44:34.859128 kernel: ata6: SATA max UDMA/133 abar m4096@0xc1040000 port 0xc1040380 irq 34 May 8 00:44:34.845202 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. May 8 00:44:34.847471 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:44:34.859085 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. May 8 00:44:34.867883 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. May 8 00:44:34.870511 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. May 8 00:44:34.878451 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 8 00:44:34.897122 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 8 00:44:34.900653 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 8 00:44:34.918958 disk-uuid[557]: Primary Header is updated. May 8 00:44:34.918958 disk-uuid[557]: Secondary Entries is updated. May 8 00:44:34.918958 disk-uuid[557]: Secondary Header is updated. May 8 00:44:34.921967 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 8 00:44:34.924735 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 8 00:44:34.928278 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 8 00:44:35.167394 kernel: ata1: SATA link down (SStatus 0 SControl 300) May 8 00:44:35.167494 kernel: ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) May 8 00:44:35.167509 kernel: ata4: SATA link down (SStatus 0 SControl 300) May 8 00:44:35.168964 kernel: ata5: SATA link down (SStatus 0 SControl 300) May 8 00:44:35.169973 kernel: ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 May 8 00:44:35.169986 kernel: ata3.00: applying bridge limits May 8 00:44:35.170966 kernel: ata6: SATA link down (SStatus 0 SControl 300) May 8 00:44:35.171971 kernel: ata2: SATA link down (SStatus 0 SControl 300) May 8 00:44:35.172975 kernel: ata3.00: configured for UDMA/100 May 8 00:44:35.172997 kernel: scsi 2:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 May 8 00:44:35.220981 kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray May 8 00:44:35.235573 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 8 00:44:35.235587 kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 May 8 00:44:35.952980 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 May 8 00:44:35.953509 disk-uuid[567]: The operation has completed successfully. May 8 00:44:35.981029 systemd[1]: disk-uuid.service: Deactivated successfully. May 8 00:44:35.981158 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 8 00:44:36.008230 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 8 00:44:36.015863 sh[595]: Success May 8 00:44:36.030968 kernel: device-mapper: verity: sha256 using implementation "sha256-ni" May 8 00:44:36.070572 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 8 00:44:36.096902 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 8 00:44:36.101015 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 8 00:44:36.111609 kernel: BTRFS info (device dm-0): first mount of filesystem 28014d97-e6d7-4db4-b1d9-76a980e09972 May 8 00:44:36.111668 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 8 00:44:36.111691 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 8 00:44:36.113427 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 8 00:44:36.113461 kernel: BTRFS info (device dm-0): using free space tree May 8 00:44:36.118453 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 8 00:44:36.121341 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 8 00:44:36.135232 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 8 00:44:36.138565 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 8 00:44:36.147411 kernel: BTRFS info (device vda6): first mount of filesystem a884989d-7a9b-4fbd-878f-8ac586ff8595 May 8 00:44:36.147490 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 8 00:44:36.147502 kernel: BTRFS info (device vda6): using free space tree May 8 00:44:36.151136 kernel: BTRFS info (device vda6): auto enabling async discard May 8 00:44:36.162734 systemd[1]: mnt-oem.mount: Deactivated successfully. May 8 00:44:36.165982 kernel: BTRFS info (device vda6): last unmount of filesystem a884989d-7a9b-4fbd-878f-8ac586ff8595 May 8 00:44:36.176193 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 8 00:44:36.184148 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 8 00:44:36.238737 ignition[687]: Ignition 2.19.0 May 8 00:44:36.238753 ignition[687]: Stage: fetch-offline May 8 00:44:36.238799 ignition[687]: no configs at "/usr/lib/ignition/base.d" May 8 00:44:36.238813 ignition[687]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 8 00:44:36.238975 ignition[687]: parsed url from cmdline: "" May 8 00:44:36.238981 ignition[687]: no config URL provided May 8 00:44:36.238989 ignition[687]: reading system config file "/usr/lib/ignition/user.ign" May 8 00:44:36.239003 ignition[687]: no config at "/usr/lib/ignition/user.ign" May 8 00:44:36.239038 ignition[687]: op(1): [started] loading QEMU firmware config module May 8 00:44:36.239055 ignition[687]: op(1): executing: "modprobe" "qemu_fw_cfg" May 8 00:44:36.247886 ignition[687]: op(1): [finished] loading QEMU firmware config module May 8 00:44:36.249466 ignition[687]: parsing config with SHA512: e97eca8cfa224cff53870f8218222527f386071ea994ed42b19b2362105220c4b5876b01721bd2ad0b08cfc34a6b1db69f7b5ae03251933c749e57fe3c6aa585 May 8 00:44:36.252029 unknown[687]: fetched base config from "system" May 8 00:44:36.252042 unknown[687]: fetched user config from "qemu" May 8 00:44:36.252277 ignition[687]: fetch-offline: fetch-offline passed May 8 00:44:36.252333 ignition[687]: Ignition finished successfully May 8 00:44:36.257710 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 8 00:44:36.273910 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 8 00:44:36.291132 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 8 00:44:36.318831 systemd-networkd[785]: lo: Link UP May 8 00:44:36.318845 systemd-networkd[785]: lo: Gained carrier May 8 00:44:36.320853 systemd-networkd[785]: Enumeration completed May 8 00:44:36.320977 systemd[1]: Started systemd-networkd.service - Network Configuration. May 8 00:44:36.321327 systemd-networkd[785]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 8 00:44:36.321332 systemd-networkd[785]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 8 00:44:36.323034 systemd[1]: Reached target network.target - Network. May 8 00:44:36.323176 systemd-networkd[785]: eth0: Link UP May 8 00:44:36.323181 systemd-networkd[785]: eth0: Gained carrier May 8 00:44:36.323189 systemd-networkd[785]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 8 00:44:36.324924 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). May 8 00:44:36.336084 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 8 00:44:36.342007 systemd-networkd[785]: eth0: DHCPv4 address 10.0.0.125/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 8 00:44:36.407756 ignition[787]: Ignition 2.19.0 May 8 00:44:36.407769 ignition[787]: Stage: kargs May 8 00:44:36.408041 ignition[787]: no configs at "/usr/lib/ignition/base.d" May 8 00:44:36.408055 ignition[787]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 8 00:44:36.437725 ignition[787]: kargs: kargs passed May 8 00:44:36.437806 ignition[787]: Ignition finished successfully May 8 00:44:36.443818 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 8 00:44:36.454084 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 8 00:44:36.561653 ignition[796]: Ignition 2.19.0 May 8 00:44:36.561665 ignition[796]: Stage: disks May 8 00:44:36.561829 ignition[796]: no configs at "/usr/lib/ignition/base.d" May 8 00:44:36.561840 ignition[796]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 8 00:44:36.562483 ignition[796]: disks: disks passed May 8 00:44:36.562536 ignition[796]: Ignition finished successfully May 8 00:44:36.569612 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 8 00:44:36.570538 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 8 00:44:36.626474 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 8 00:44:36.626743 systemd[1]: Reached target local-fs.target - Local File Systems. May 8 00:44:36.627267 systemd[1]: Reached target sysinit.target - System Initialization. May 8 00:44:36.634592 systemd[1]: Reached target basic.target - Basic System. May 8 00:44:36.651206 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 8 00:44:36.687500 systemd-fsck[806]: ROOT: clean, 14/553520 files, 52654/553472 blocks May 8 00:44:37.084852 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 8 00:44:37.141046 systemd[1]: Mounting sysroot.mount - /sysroot... May 8 00:44:37.315978 kernel: EXT4-fs (vda9): mounted filesystem 36960c89-ba45-4808-a41c-bf61ce9470a3 r/w with ordered data mode. Quota mode: none. May 8 00:44:37.316144 systemd[1]: Mounted sysroot.mount - /sysroot. May 8 00:44:37.316999 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 8 00:44:37.349068 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 8 00:44:37.351060 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 8 00:44:37.352467 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. May 8 00:44:37.358293 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (814) May 8 00:44:37.358322 kernel: BTRFS info (device vda6): first mount of filesystem a884989d-7a9b-4fbd-878f-8ac586ff8595 May 8 00:44:37.352511 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 8 00:44:37.374610 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 8 00:44:37.374632 kernel: BTRFS info (device vda6): using free space tree May 8 00:44:37.374645 kernel: BTRFS info (device vda6): auto enabling async discard May 8 00:44:37.352535 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 8 00:44:37.361445 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 8 00:44:37.375781 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 8 00:44:37.384286 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 8 00:44:37.432741 initrd-setup-root[838]: cut: /sysroot/etc/passwd: No such file or directory May 8 00:44:37.438283 initrd-setup-root[845]: cut: /sysroot/etc/group: No such file or directory May 8 00:44:37.444494 initrd-setup-root[852]: cut: /sysroot/etc/shadow: No such file or directory May 8 00:44:37.448858 initrd-setup-root[859]: cut: /sysroot/etc/gshadow: No such file or directory May 8 00:44:37.541328 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 8 00:44:37.551044 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 8 00:44:37.552329 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 8 00:44:37.563830 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 8 00:44:37.565541 kernel: BTRFS info (device vda6): last unmount of filesystem a884989d-7a9b-4fbd-878f-8ac586ff8595 May 8 00:44:37.579533 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 8 00:44:37.648143 ignition[932]: INFO : Ignition 2.19.0 May 8 00:44:37.648143 ignition[932]: INFO : Stage: mount May 8 00:44:37.650158 ignition[932]: INFO : no configs at "/usr/lib/ignition/base.d" May 8 00:44:37.650158 ignition[932]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 8 00:44:37.650158 ignition[932]: INFO : mount: mount passed May 8 00:44:37.650158 ignition[932]: INFO : Ignition finished successfully May 8 00:44:37.651116 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 8 00:44:37.664078 systemd[1]: Starting ignition-files.service - Ignition (files)... May 8 00:44:38.264181 systemd-networkd[785]: eth0: Gained IPv6LL May 8 00:44:38.329233 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 8 00:44:38.339964 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (940) May 8 00:44:38.348336 kernel: BTRFS info (device vda6): first mount of filesystem a884989d-7a9b-4fbd-878f-8ac586ff8595 May 8 00:44:38.348366 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm May 8 00:44:38.348381 kernel: BTRFS info (device vda6): using free space tree May 8 00:44:38.351957 kernel: BTRFS info (device vda6): auto enabling async discard May 8 00:44:38.354072 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 8 00:44:38.414730 ignition[957]: INFO : Ignition 2.19.0 May 8 00:44:38.414730 ignition[957]: INFO : Stage: files May 8 00:44:38.416862 ignition[957]: INFO : no configs at "/usr/lib/ignition/base.d" May 8 00:44:38.416862 ignition[957]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 8 00:44:38.420010 ignition[957]: DEBUG : files: compiled without relabeling support, skipping May 8 00:44:38.421936 ignition[957]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 8 00:44:38.421936 ignition[957]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 8 00:44:38.425864 ignition[957]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 8 00:44:38.427419 ignition[957]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 8 00:44:38.429187 unknown[957]: wrote ssh authorized keys file for user: core May 8 00:44:38.430513 ignition[957]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 8 00:44:38.432429 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" May 8 00:44:38.434493 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" May 8 00:44:38.434493 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" May 8 00:44:38.434493 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 8 00:44:38.434493 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" May 8 00:44:38.434493 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" May 8 00:44:38.434493 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" May 8 00:44:38.434493 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.32.0-x86-64.raw: attempt #1 May 8 00:44:38.838380 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK May 8 00:44:39.625739 ignition[957]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" May 8 00:44:39.625739 ignition[957]: INFO : files: op(7): [started] processing unit "coreos-metadata.service" May 8 00:44:39.630297 ignition[957]: INFO : files: op(7): op(8): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 8 00:44:39.630297 ignition[957]: INFO : files: op(7): op(8): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" May 8 00:44:39.630297 ignition[957]: INFO : files: op(7): [finished] processing unit "coreos-metadata.service" May 8 00:44:39.630297 ignition[957]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" May 8 00:44:39.662782 ignition[957]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" May 8 00:44:39.667465 ignition[957]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" May 8 00:44:39.669337 ignition[957]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" May 8 00:44:39.671023 ignition[957]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" May 8 00:44:39.673066 ignition[957]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" May 8 00:44:39.674974 ignition[957]: INFO : files: files passed May 8 00:44:39.675818 ignition[957]: INFO : Ignition finished successfully May 8 00:44:39.678788 systemd[1]: Finished ignition-files.service - Ignition (files). May 8 00:44:39.691196 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 8 00:44:39.693556 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 8 00:44:39.696958 systemd[1]: ignition-quench.service: Deactivated successfully. May 8 00:44:39.697128 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 8 00:44:39.705993 initrd-setup-root-after-ignition[985]: grep: /sysroot/oem/oem-release: No such file or directory May 8 00:44:39.708881 initrd-setup-root-after-ignition[987]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 8 00:44:39.708881 initrd-setup-root-after-ignition[987]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 8 00:44:39.712434 initrd-setup-root-after-ignition[991]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 8 00:44:39.715905 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 8 00:44:39.718884 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 8 00:44:39.731076 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 8 00:44:39.757965 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 8 00:44:39.759579 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 8 00:44:39.762327 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 8 00:44:39.764410 systemd[1]: Reached target initrd.target - Initrd Default Target. May 8 00:44:39.766568 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 8 00:44:39.780262 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 8 00:44:39.793995 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 8 00:44:39.795720 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 8 00:44:39.811014 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 8 00:44:39.811637 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 8 00:44:39.811997 systemd[1]: Stopped target timers.target - Timer Units. May 8 00:44:39.812484 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 8 00:44:39.812612 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 8 00:44:39.818209 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 8 00:44:39.818565 systemd[1]: Stopped target basic.target - Basic System. May 8 00:44:39.818898 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 8 00:44:39.819472 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 8 00:44:39.819806 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 8 00:44:39.820326 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 8 00:44:39.820650 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 8 00:44:39.821010 systemd[1]: Stopped target sysinit.target - System Initialization. May 8 00:44:39.821540 systemd[1]: Stopped target local-fs.target - Local File Systems. May 8 00:44:39.821884 systemd[1]: Stopped target swap.target - Swaps. May 8 00:44:39.822393 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 8 00:44:39.822544 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 8 00:44:39.840928 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 8 00:44:39.841474 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 8 00:44:39.841766 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 8 00:44:39.848641 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 8 00:44:39.851360 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 8 00:44:39.851526 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 8 00:44:39.855647 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 8 00:44:39.855822 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 8 00:44:39.857830 systemd[1]: Stopped target paths.target - Path Units. May 8 00:44:39.859682 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 8 00:44:39.865020 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 8 00:44:39.867785 systemd[1]: Stopped target slices.target - Slice Units. May 8 00:44:39.869660 systemd[1]: Stopped target sockets.target - Socket Units. May 8 00:44:39.871524 systemd[1]: iscsid.socket: Deactivated successfully. May 8 00:44:39.872408 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 8 00:44:39.874351 systemd[1]: iscsiuio.socket: Deactivated successfully. May 8 00:44:39.875238 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 8 00:44:39.877964 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 8 00:44:39.879356 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 8 00:44:39.882202 systemd[1]: ignition-files.service: Deactivated successfully. May 8 00:44:39.883358 systemd[1]: Stopped ignition-files.service - Ignition (files). May 8 00:44:39.897141 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 8 00:44:39.899251 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 8 00:44:39.900446 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 8 00:44:39.903998 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 8 00:44:39.906084 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 8 00:44:39.906257 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 8 00:44:39.910211 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 8 00:44:39.911488 ignition[1011]: INFO : Ignition 2.19.0 May 8 00:44:39.911488 ignition[1011]: INFO : Stage: umount May 8 00:44:39.911488 ignition[1011]: INFO : no configs at "/usr/lib/ignition/base.d" May 8 00:44:39.911488 ignition[1011]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" May 8 00:44:39.911488 ignition[1011]: INFO : umount: umount passed May 8 00:44:39.911488 ignition[1011]: INFO : Ignition finished successfully May 8 00:44:39.911610 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 8 00:44:39.917455 systemd[1]: ignition-mount.service: Deactivated successfully. May 8 00:44:39.917585 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 8 00:44:39.918611 systemd[1]: Stopped target network.target - Network. May 8 00:44:39.920558 systemd[1]: ignition-disks.service: Deactivated successfully. May 8 00:44:39.920674 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 8 00:44:39.924842 systemd[1]: ignition-kargs.service: Deactivated successfully. May 8 00:44:39.924959 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 8 00:44:39.925496 systemd[1]: ignition-setup.service: Deactivated successfully. May 8 00:44:39.925607 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 8 00:44:39.928246 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 8 00:44:39.928353 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 8 00:44:39.930580 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 8 00:44:39.932783 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 8 00:44:39.935967 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 8 00:44:39.936997 systemd-networkd[785]: eth0: DHCPv6 lease lost May 8 00:44:39.939495 systemd[1]: systemd-networkd.service: Deactivated successfully. May 8 00:44:39.939641 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 8 00:44:39.940849 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 8 00:44:39.940916 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 8 00:44:39.950032 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 8 00:44:39.951215 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 8 00:44:39.951294 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 8 00:44:39.954482 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 8 00:44:39.956806 systemd[1]: systemd-resolved.service: Deactivated successfully. May 8 00:44:39.956952 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 8 00:44:39.967103 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 8 00:44:39.968170 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 8 00:44:39.981399 systemd[1]: systemd-udevd.service: Deactivated successfully. May 8 00:44:39.982586 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 8 00:44:39.986413 systemd[1]: network-cleanup.service: Deactivated successfully. May 8 00:44:39.987474 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 8 00:44:39.991575 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 8 00:44:39.991646 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 8 00:44:39.995119 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 8 00:44:39.996190 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 8 00:44:39.998355 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 8 00:44:39.998411 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 8 00:44:40.001635 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 8 00:44:40.002689 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 8 00:44:40.004959 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 8 00:44:40.005042 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 8 00:44:40.021102 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 8 00:44:40.036671 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 8 00:44:40.036811 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 8 00:44:40.038502 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 8 00:44:40.038579 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 8 00:44:40.041294 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 8 00:44:40.041368 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 8 00:44:40.043571 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 8 00:44:40.043646 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 8 00:44:40.046607 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 8 00:44:40.046667 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:44:40.049783 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 8 00:44:40.049898 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 8 00:44:40.109524 systemd[1]: sysroot-boot.service: Deactivated successfully. May 8 00:44:40.109689 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 8 00:44:40.111188 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 8 00:44:40.112932 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 8 00:44:40.113072 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 8 00:44:40.124143 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 8 00:44:40.136292 systemd[1]: Switching root. May 8 00:44:40.169230 systemd-journald[193]: Journal stopped May 8 00:44:41.395468 systemd-journald[193]: Received SIGTERM from PID 1 (systemd). May 8 00:44:41.395567 kernel: SELinux: policy capability network_peer_controls=1 May 8 00:44:41.395586 kernel: SELinux: policy capability open_perms=1 May 8 00:44:41.395598 kernel: SELinux: policy capability extended_socket_class=1 May 8 00:44:41.395618 kernel: SELinux: policy capability always_check_network=0 May 8 00:44:41.395634 kernel: SELinux: policy capability cgroup_seclabel=1 May 8 00:44:41.395645 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 8 00:44:41.395656 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 8 00:44:41.395667 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 8 00:44:41.395678 kernel: audit: type=1403 audit(1746665080.607:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 8 00:44:41.395707 systemd[1]: Successfully loaded SELinux policy in 42.047ms. May 8 00:44:41.395736 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 12.982ms. May 8 00:44:41.395755 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) May 8 00:44:41.395772 systemd[1]: Detected virtualization kvm. May 8 00:44:41.395788 systemd[1]: Detected architecture x86-64. May 8 00:44:41.395804 systemd[1]: Detected first boot. May 8 00:44:41.395819 systemd[1]: Initializing machine ID from VM UUID. May 8 00:44:41.395834 zram_generator::config[1055]: No configuration found. May 8 00:44:41.395854 systemd[1]: Populated /etc with preset unit settings. May 8 00:44:41.395871 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 8 00:44:41.395888 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 8 00:44:41.395909 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 8 00:44:41.395927 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 8 00:44:41.396193 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 8 00:44:41.396217 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 8 00:44:41.396233 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 8 00:44:41.396259 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 8 00:44:41.396278 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 8 00:44:41.396295 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 8 00:44:41.396312 systemd[1]: Created slice user.slice - User and Session Slice. May 8 00:44:41.396329 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 8 00:44:41.396346 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 8 00:44:41.396367 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 8 00:44:41.396383 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 8 00:44:41.396400 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 8 00:44:41.396415 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 8 00:44:41.396431 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 8 00:44:41.396453 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 8 00:44:41.396470 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 8 00:44:41.396490 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 8 00:44:41.396506 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 8 00:44:41.396526 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 8 00:44:41.396542 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 8 00:44:41.396559 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 8 00:44:41.396575 systemd[1]: Reached target slices.target - Slice Units. May 8 00:44:41.396591 systemd[1]: Reached target swap.target - Swaps. May 8 00:44:41.396607 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 8 00:44:41.396624 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 8 00:44:41.396641 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 8 00:44:41.396661 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 8 00:44:41.396677 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 8 00:44:41.396699 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 8 00:44:41.396714 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 8 00:44:41.396730 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 8 00:44:41.396745 systemd[1]: Mounting media.mount - External Media Directory... May 8 00:44:41.396762 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:44:41.396779 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 8 00:44:41.396795 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 8 00:44:41.396815 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 8 00:44:41.396831 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 8 00:44:41.396849 systemd[1]: Reached target machines.target - Containers. May 8 00:44:41.396865 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 8 00:44:41.396882 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 8 00:44:41.396898 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 8 00:44:41.396914 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 8 00:44:41.396931 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 8 00:44:41.396967 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 8 00:44:41.396984 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 8 00:44:41.397001 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 8 00:44:41.397018 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 8 00:44:41.397035 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 8 00:44:41.397051 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 8 00:44:41.397068 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 8 00:44:41.397085 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 8 00:44:41.397102 systemd[1]: Stopped systemd-fsck-usr.service. May 8 00:44:41.397123 kernel: fuse: init (API version 7.39) May 8 00:44:41.397138 kernel: loop: module loaded May 8 00:44:41.397154 systemd[1]: Starting systemd-journald.service - Journal Service... May 8 00:44:41.397170 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 8 00:44:41.397185 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 8 00:44:41.397238 systemd-journald[1122]: Collecting audit messages is disabled. May 8 00:44:41.397281 kernel: ACPI: bus type drm_connector registered May 8 00:44:41.397302 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 8 00:44:41.397320 systemd-journald[1122]: Journal started May 8 00:44:41.397351 systemd-journald[1122]: Runtime Journal (/run/log/journal/00d7fa9cf11045fc958d4983c91b527b) is 6.0M, max 48.3M, 42.2M free. May 8 00:44:41.140090 systemd[1]: Queued start job for default target multi-user.target. May 8 00:44:41.161634 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. May 8 00:44:41.162167 systemd[1]: systemd-journald.service: Deactivated successfully. May 8 00:44:41.401185 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 8 00:44:41.401218 systemd[1]: verity-setup.service: Deactivated successfully. May 8 00:44:41.402972 systemd[1]: Stopped verity-setup.service. May 8 00:44:41.406966 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:44:41.410155 systemd[1]: Started systemd-journald.service - Journal Service. May 8 00:44:41.412321 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 8 00:44:41.413850 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 8 00:44:41.415443 systemd[1]: Mounted media.mount - External Media Directory. May 8 00:44:41.417024 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 8 00:44:41.418573 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 8 00:44:41.420174 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 8 00:44:41.421840 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 8 00:44:41.423895 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 8 00:44:41.425930 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 8 00:44:41.426195 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 8 00:44:41.428155 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 8 00:44:41.428393 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 8 00:44:41.430280 systemd[1]: modprobe@drm.service: Deactivated successfully. May 8 00:44:41.430513 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 8 00:44:41.432182 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 8 00:44:41.432399 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 8 00:44:41.434155 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 8 00:44:41.434340 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 8 00:44:41.435842 systemd[1]: modprobe@loop.service: Deactivated successfully. May 8 00:44:41.436036 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 8 00:44:41.437485 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 8 00:44:41.439073 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 8 00:44:41.441121 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 8 00:44:41.457715 systemd[1]: Reached target network-pre.target - Preparation for Network. May 8 00:44:41.470120 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 8 00:44:41.472836 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 8 00:44:41.474235 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 8 00:44:41.474292 systemd[1]: Reached target local-fs.target - Local File Systems. May 8 00:44:41.476739 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). May 8 00:44:41.479674 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 8 00:44:41.482381 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 8 00:44:41.483749 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 8 00:44:41.487489 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 8 00:44:41.492186 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 8 00:44:41.494549 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 8 00:44:41.497141 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 8 00:44:41.499403 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 8 00:44:41.504064 systemd-journald[1122]: Time spent on flushing to /var/log/journal/00d7fa9cf11045fc958d4983c91b527b is 23.972ms for 971 entries. May 8 00:44:41.504064 systemd-journald[1122]: System Journal (/var/log/journal/00d7fa9cf11045fc958d4983c91b527b) is 8.0M, max 195.6M, 187.6M free. May 8 00:44:41.543007 systemd-journald[1122]: Received client request to flush runtime journal. May 8 00:44:41.501613 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 8 00:44:41.506976 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 8 00:44:41.520282 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 8 00:44:41.523367 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 8 00:44:41.526157 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 8 00:44:41.528809 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 8 00:44:41.545543 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 8 00:44:41.549368 kernel: loop0: detected capacity change from 0 to 140768 May 8 00:44:41.550983 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 8 00:44:41.582056 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 8 00:44:41.587964 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 8 00:44:41.601493 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 8 00:44:41.604042 udevadm[1178]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 8 00:44:41.612094 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 8 00:44:41.617997 kernel: loop1: detected capacity change from 0 to 218376 May 8 00:44:41.620348 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 8 00:44:41.625260 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 8 00:44:41.627886 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 8 00:44:41.641520 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... May 8 00:44:41.649708 systemd-tmpfiles[1185]: ACLs are not supported, ignoring. May 8 00:44:41.650019 systemd-tmpfiles[1185]: ACLs are not supported, ignoring. May 8 00:44:41.658272 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 8 00:44:41.686047 kernel: loop2: detected capacity change from 0 to 142488 May 8 00:44:41.729982 kernel: loop3: detected capacity change from 0 to 140768 May 8 00:44:41.830037 kernel: loop4: detected capacity change from 0 to 218376 May 8 00:44:41.836972 kernel: loop5: detected capacity change from 0 to 142488 May 8 00:44:41.842845 (sd-merge)[1192]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes'. May 8 00:44:41.844420 (sd-merge)[1192]: Merged extensions into '/usr'. May 8 00:44:41.914510 systemd[1]: Reloading requested from client PID 1169 ('systemd-sysext') (unit systemd-sysext.service)... May 8 00:44:41.914527 systemd[1]: Reloading... May 8 00:44:41.980146 zram_generator::config[1216]: No configuration found. May 8 00:44:42.136463 ldconfig[1164]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 8 00:44:42.159396 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 8 00:44:42.209680 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 8 00:44:42.209903 systemd[1]: Reloading finished in 294 ms. May 8 00:44:42.251730 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 8 00:44:42.253390 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 8 00:44:42.255106 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. May 8 00:44:42.271267 systemd[1]: Starting ensure-sysext.service... May 8 00:44:42.273819 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 8 00:44:42.330472 systemd[1]: Reloading requested from client PID 1257 ('systemctl') (unit ensure-sysext.service)... May 8 00:44:42.330486 systemd[1]: Reloading... May 8 00:44:42.339238 systemd-tmpfiles[1258]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 8 00:44:42.339739 systemd-tmpfiles[1258]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 8 00:44:42.341026 systemd-tmpfiles[1258]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 8 00:44:42.341441 systemd-tmpfiles[1258]: ACLs are not supported, ignoring. May 8 00:44:42.341546 systemd-tmpfiles[1258]: ACLs are not supported, ignoring. May 8 00:44:42.345851 systemd-tmpfiles[1258]: Detected autofs mount point /boot during canonicalization of boot. May 8 00:44:42.345863 systemd-tmpfiles[1258]: Skipping /boot May 8 00:44:42.359749 systemd-tmpfiles[1258]: Detected autofs mount point /boot during canonicalization of boot. May 8 00:44:42.359762 systemd-tmpfiles[1258]: Skipping /boot May 8 00:44:42.387034 zram_generator::config[1288]: No configuration found. May 8 00:44:42.508741 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 8 00:44:42.569253 systemd[1]: Reloading finished in 238 ms. May 8 00:44:42.594176 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 8 00:44:42.606342 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 8 00:44:42.615345 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... May 8 00:44:42.618074 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 8 00:44:42.620896 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 8 00:44:42.625671 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 8 00:44:42.631036 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 8 00:44:42.634721 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 8 00:44:42.639440 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:44:42.639615 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 8 00:44:42.641853 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 8 00:44:42.647246 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 8 00:44:42.650234 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 8 00:44:42.652668 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 8 00:44:42.654545 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 8 00:44:42.655643 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:44:42.659982 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:44:42.660184 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 8 00:44:42.660391 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 8 00:44:42.660517 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:44:42.668527 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 8 00:44:42.670762 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 8 00:44:42.672012 systemd-udevd[1330]: Using default interface naming scheme 'v255'. May 8 00:44:42.672886 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 8 00:44:42.673098 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 8 00:44:42.675184 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 8 00:44:42.675389 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 8 00:44:42.677467 systemd[1]: modprobe@loop.service: Deactivated successfully. May 8 00:44:42.677657 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 8 00:44:42.684651 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:44:42.686453 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 8 00:44:42.694467 augenrules[1355]: No rules May 8 00:44:42.696189 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 8 00:44:42.697630 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 8 00:44:42.697692 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 8 00:44:42.697753 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 8 00:44:42.700096 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 8 00:44:42.701521 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 8 00:44:42.702386 systemd[1]: Finished ensure-sysext.service. May 8 00:44:42.704079 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. May 8 00:44:42.706600 systemd[1]: modprobe@drm.service: Deactivated successfully. May 8 00:44:42.706826 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 8 00:44:42.708396 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 8 00:44:42.709783 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 8 00:44:42.725125 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 8 00:44:42.741115 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... May 8 00:44:42.744400 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 8 00:44:42.746106 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 8 00:44:42.767428 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 8 00:44:42.782964 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1387) May 8 00:44:42.784523 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 8 00:44:42.809759 systemd-resolved[1328]: Positive Trust Anchors: May 8 00:44:42.809778 systemd-resolved[1328]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 8 00:44:42.809809 systemd-resolved[1328]: 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 8 00:44:42.875586 systemd-resolved[1328]: Defaulting to hostname 'linux'. May 8 00:44:42.880196 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 8 00:44:42.881809 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 8 00:44:42.924911 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. May 8 00:44:42.938981 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 May 8 00:44:42.939460 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. May 8 00:44:42.941176 systemd[1]: Reached target time-set.target - System Time Set. May 8 00:44:42.947128 kernel: ACPI: button: Power Button [PWRF] May 8 00:44:42.949166 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 8 00:44:42.955627 systemd-networkd[1377]: lo: Link UP May 8 00:44:42.955991 systemd-networkd[1377]: lo: Gained carrier May 8 00:44:42.961115 systemd-networkd[1377]: Enumeration completed May 8 00:44:42.961268 systemd[1]: Started systemd-networkd.service - Network Configuration. May 8 00:44:42.962927 systemd[1]: Reached target network.target - Network. May 8 00:44:42.966057 systemd-networkd[1377]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 8 00:44:42.966066 systemd-networkd[1377]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 8 00:44:42.967826 systemd-networkd[1377]: eth0: Link UP May 8 00:44:42.967830 systemd-networkd[1377]: eth0: Gained carrier May 8 00:44:42.967854 systemd-networkd[1377]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 8 00:44:42.975191 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 8 00:44:42.983008 systemd-networkd[1377]: eth0: DHCPv4 address 10.0.0.125/16, gateway 10.0.0.1 acquired from 10.0.0.1 May 8 00:44:42.984605 systemd-timesyncd[1388]: Network configuration changed, trying to establish connection. May 8 00:44:44.026443 systemd-resolved[1328]: Clock change detected. Flushing caches. May 8 00:44:44.027162 systemd-timesyncd[1388]: Contacted time server 10.0.0.1:123 (10.0.0.1). May 8 00:44:44.027579 systemd-timesyncd[1388]: Initial clock synchronization to Thu 2025-05-08 00:44:44.026250 UTC. May 8 00:44:44.031365 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 May 8 00:44:44.036465 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 8 00:44:44.052380 kernel: i801_smbus 0000:00:1f.3: Enabling SMBus device May 8 00:44:44.060419 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt May 8 00:44:44.060642 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) May 8 00:44:44.060858 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD May 8 00:44:44.119638 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 8 00:44:44.130843 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 8 00:44:44.131109 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:44:44.136241 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 8 00:44:44.161423 kernel: mousedev: PS/2 mouse device common for all mice May 8 00:44:44.190430 kernel: kvm_amd: TSC scaling supported May 8 00:44:44.190467 kernel: kvm_amd: Nested Virtualization enabled May 8 00:44:44.190505 kernel: kvm_amd: Nested Paging enabled May 8 00:44:44.191915 kernel: kvm_amd: LBR virtualization supported May 8 00:44:44.191944 kernel: kvm_amd: Virtual VMLOAD VMSAVE supported May 8 00:44:44.193434 kernel: kvm_amd: Virtual GIF supported May 8 00:44:44.216418 kernel: EDAC MC: Ver: 3.0.0 May 8 00:44:44.228174 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 8 00:44:44.245554 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 8 00:44:44.263758 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 8 00:44:44.273113 lvm[1420]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 8 00:44:44.308112 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 8 00:44:44.309939 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 8 00:44:44.311223 systemd[1]: Reached target sysinit.target - System Initialization. May 8 00:44:44.312584 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 8 00:44:44.314223 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 8 00:44:44.316228 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 8 00:44:44.317745 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 8 00:44:44.319314 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 8 00:44:44.320895 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 8 00:44:44.320933 systemd[1]: Reached target paths.target - Path Units. May 8 00:44:44.321857 systemd[1]: Reached target timers.target - Timer Units. May 8 00:44:44.323863 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 8 00:44:44.326797 systemd[1]: Starting docker.socket - Docker Socket for the API... May 8 00:44:44.335578 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 8 00:44:44.337998 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 8 00:44:44.339820 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 8 00:44:44.341094 systemd[1]: Reached target sockets.target - Socket Units. May 8 00:44:44.342153 systemd[1]: Reached target basic.target - Basic System. May 8 00:44:44.343189 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 8 00:44:44.343226 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 8 00:44:44.344593 systemd[1]: Starting containerd.service - containerd container runtime... May 8 00:44:44.346986 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 8 00:44:44.352298 lvm[1424]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 8 00:44:44.351464 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 8 00:44:44.355220 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 8 00:44:44.356663 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 8 00:44:44.358512 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 8 00:44:44.362271 jq[1427]: false May 8 00:44:44.361990 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 8 00:44:44.365557 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 8 00:44:44.373559 systemd[1]: Starting systemd-logind.service - User Login Management... May 8 00:44:44.375219 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 8 00:44:44.375849 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 8 00:44:44.376661 systemd[1]: Starting update-engine.service - Update Engine... May 8 00:44:44.381595 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 8 00:44:44.385426 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 8 00:44:44.391150 extend-filesystems[1428]: Found loop3 May 8 00:44:44.391150 extend-filesystems[1428]: Found loop4 May 8 00:44:44.391150 extend-filesystems[1428]: Found loop5 May 8 00:44:44.391150 extend-filesystems[1428]: Found sr0 May 8 00:44:44.391150 extend-filesystems[1428]: Found vda May 8 00:44:44.391150 extend-filesystems[1428]: Found vda1 May 8 00:44:44.391150 extend-filesystems[1428]: Found vda2 May 8 00:44:44.391150 extend-filesystems[1428]: Found vda3 May 8 00:44:44.391150 extend-filesystems[1428]: Found usr May 8 00:44:44.391150 extend-filesystems[1428]: Found vda4 May 8 00:44:44.391150 extend-filesystems[1428]: Found vda6 May 8 00:44:44.391150 extend-filesystems[1428]: Found vda7 May 8 00:44:44.391150 extend-filesystems[1428]: Found vda9 May 8 00:44:44.391150 extend-filesystems[1428]: Checking size of /dev/vda9 May 8 00:44:44.388736 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 8 00:44:44.439375 kernel: EXT4-fs (vda9): resizing filesystem from 553472 to 1864699 blocks May 8 00:44:44.439407 extend-filesystems[1428]: Resized partition /dev/vda9 May 8 00:44:44.443096 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (1387) May 8 00:44:44.417420 dbus-daemon[1426]: [system] SELinux support is enabled May 8 00:44:44.444368 update_engine[1434]: I20250508 00:44:44.404245 1434 main.cc:92] Flatcar Update Engine starting May 8 00:44:44.444368 update_engine[1434]: I20250508 00:44:44.439986 1434 update_check_scheduler.cc:74] Next update check in 10m14s May 8 00:44:44.388958 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 8 00:44:44.444732 extend-filesystems[1455]: resize2fs 1.47.1 (20-May-2024) May 8 00:44:44.389645 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 8 00:44:44.446383 jq[1435]: true May 8 00:44:44.391560 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 8 00:44:44.446664 jq[1445]: true May 8 00:44:44.423173 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 8 00:44:44.423583 (ntainerd)[1448]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 8 00:44:44.426591 systemd[1]: motdgen.service: Deactivated successfully. May 8 00:44:44.426826 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 8 00:44:44.429805 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 8 00:44:44.429828 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 8 00:44:44.431604 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 8 00:44:44.431637 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 8 00:44:44.450644 systemd[1]: Started update-engine.service - Update Engine. May 8 00:44:44.476570 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 8 00:44:44.483535 systemd-logind[1433]: Watching system buttons on /dev/input/event1 (Power Button) May 8 00:44:44.483565 systemd-logind[1433]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 8 00:44:44.484473 systemd-logind[1433]: New seat seat0. May 8 00:44:44.487216 systemd[1]: Started systemd-logind.service - User Login Management. May 8 00:44:44.506058 kernel: EXT4-fs (vda9): resized filesystem to 1864699 May 8 00:44:44.518300 locksmithd[1468]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 8 00:44:44.526385 extend-filesystems[1455]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required May 8 00:44:44.526385 extend-filesystems[1455]: old_desc_blocks = 1, new_desc_blocks = 1 May 8 00:44:44.526385 extend-filesystems[1455]: The filesystem on /dev/vda9 is now 1864699 (4k) blocks long. May 8 00:44:44.533283 extend-filesystems[1428]: Resized filesystem in /dev/vda9 May 8 00:44:44.528551 systemd[1]: extend-filesystems.service: Deactivated successfully. May 8 00:44:44.528849 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 8 00:44:44.540540 bash[1477]: Updated "/home/core/.ssh/authorized_keys" May 8 00:44:44.542446 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 8 00:44:44.546314 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 8 00:44:44.636104 containerd[1448]: time="2025-05-08T00:44:44.635819847Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 May 8 00:44:44.658998 containerd[1448]: time="2025-05-08T00:44:44.658764264Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.660832734Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.88-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.660864994Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.660883229Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.661114803Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.661139209Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.661227965Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.661245278Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.661481360Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.661498142Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.661510766Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 8 00:44:44.662315 containerd[1448]: time="2025-05-08T00:44:44.661527707Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 8 00:44:44.662656 containerd[1448]: time="2025-05-08T00:44:44.661657411Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 8 00:44:44.662656 containerd[1448]: time="2025-05-08T00:44:44.661892101Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 8 00:44:44.662656 containerd[1448]: time="2025-05-08T00:44:44.662008800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 8 00:44:44.662656 containerd[1448]: time="2025-05-08T00:44:44.662022185Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 8 00:44:44.662656 containerd[1448]: time="2025-05-08T00:44:44.662121601Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 8 00:44:44.662656 containerd[1448]: time="2025-05-08T00:44:44.662174491Z" level=info msg="metadata content store policy set" policy=shared May 8 00:44:44.669859 containerd[1448]: time="2025-05-08T00:44:44.669837905Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 8 00:44:44.669959 containerd[1448]: time="2025-05-08T00:44:44.669940517Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 8 00:44:44.670027 containerd[1448]: time="2025-05-08T00:44:44.670012352Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 8 00:44:44.670103 containerd[1448]: time="2025-05-08T00:44:44.670086591Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 8 00:44:44.670178 containerd[1448]: time="2025-05-08T00:44:44.670161292Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 8 00:44:44.670425 containerd[1448]: time="2025-05-08T00:44:44.670403877Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 8 00:44:44.670750 containerd[1448]: time="2025-05-08T00:44:44.670733234Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 8 00:44:44.670911 containerd[1448]: time="2025-05-08T00:44:44.670893435Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 8 00:44:44.670966 containerd[1448]: time="2025-05-08T00:44:44.670954129Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 8 00:44:44.671025 containerd[1448]: time="2025-05-08T00:44:44.671013089Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 8 00:44:44.671072 containerd[1448]: time="2025-05-08T00:44:44.671061700Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 8 00:44:44.671118 containerd[1448]: time="2025-05-08T00:44:44.671107747Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 8 00:44:44.671164 containerd[1448]: time="2025-05-08T00:44:44.671152521Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 8 00:44:44.671218 containerd[1448]: time="2025-05-08T00:44:44.671202715Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 8 00:44:44.671279 containerd[1448]: time="2025-05-08T00:44:44.671264761Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 8 00:44:44.671369 containerd[1448]: time="2025-05-08T00:44:44.671337949Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 8 00:44:44.671436 containerd[1448]: time="2025-05-08T00:44:44.671420513Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 8 00:44:44.671498 containerd[1448]: time="2025-05-08T00:44:44.671482570Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 8 00:44:44.671572 containerd[1448]: time="2025-05-08T00:44:44.671555096Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 8 00:44:44.671652 containerd[1448]: time="2025-05-08T00:44:44.671635717Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 8 00:44:44.671770 containerd[1448]: time="2025-05-08T00:44:44.671750973Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 8 00:44:44.671860 containerd[1448]: time="2025-05-08T00:44:44.671841874Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 8 00:44:44.671931 containerd[1448]: time="2025-05-08T00:44:44.671914200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672002 containerd[1448]: time="2025-05-08T00:44:44.671985734Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672067 containerd[1448]: time="2025-05-08T00:44:44.672052499Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672144 containerd[1448]: time="2025-05-08T00:44:44.672127299Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672210 containerd[1448]: time="2025-05-08T00:44:44.672194295Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672278 containerd[1448]: time="2025-05-08T00:44:44.672263304Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672342 containerd[1448]: time="2025-05-08T00:44:44.672327375Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672437 containerd[1448]: time="2025-05-08T00:44:44.672418746Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672515 containerd[1448]: time="2025-05-08T00:44:44.672498055Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672589 containerd[1448]: time="2025-05-08T00:44:44.672571262Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 8 00:44:44.672703 containerd[1448]: time="2025-05-08T00:44:44.672683853Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672772 containerd[1448]: time="2025-05-08T00:44:44.672756770Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 8 00:44:44.672836 containerd[1448]: time="2025-05-08T00:44:44.672819778Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 8 00:44:44.672946 containerd[1448]: time="2025-05-08T00:44:44.672928672Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 8 00:44:44.673022 containerd[1448]: time="2025-05-08T00:44:44.673003563Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 May 8 00:44:44.673095 containerd[1448]: time="2025-05-08T00:44:44.673079686Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 8 00:44:44.673176 containerd[1448]: time="2025-05-08T00:44:44.673154887Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 8 00:44:44.673241 containerd[1448]: time="2025-05-08T00:44:44.673224277Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 8 00:44:44.673314 containerd[1448]: time="2025-05-08T00:44:44.673298125Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 8 00:44:44.673396 containerd[1448]: time="2025-05-08T00:44:44.673379187Z" level=info msg="NRI interface is disabled by configuration." May 8 00:44:44.673476 containerd[1448]: time="2025-05-08T00:44:44.673458646Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 8 00:44:44.673922 containerd[1448]: time="2025-05-08T00:44:44.673851854Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} 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 8 00:44:44.674378 containerd[1448]: time="2025-05-08T00:44:44.674361039Z" level=info msg="Connect containerd service" May 8 00:44:44.674454 containerd[1448]: time="2025-05-08T00:44:44.674442081Z" level=info msg="using legacy CRI server" May 8 00:44:44.674497 containerd[1448]: time="2025-05-08T00:44:44.674486715Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 8 00:44:44.674623 containerd[1448]: time="2025-05-08T00:44:44.674596300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 8 00:44:44.675409 containerd[1448]: time="2025-05-08T00:44:44.675383116Z" 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 8 00:44:44.675616 containerd[1448]: time="2025-05-08T00:44:44.675569696Z" level=info msg="Start subscribing containerd event" May 8 00:44:44.675717 containerd[1448]: time="2025-05-08T00:44:44.675699610Z" level=info msg="Start recovering state" May 8 00:44:44.676238 containerd[1448]: time="2025-05-08T00:44:44.676218673Z" level=info msg="Start event monitor" May 8 00:44:44.676323 containerd[1448]: time="2025-05-08T00:44:44.676307029Z" level=info msg="Start snapshots syncer" May 8 00:44:44.676398 containerd[1448]: time="2025-05-08T00:44:44.676383382Z" level=info msg="Start cni network conf syncer for default" May 8 00:44:44.676456 containerd[1448]: time="2025-05-08T00:44:44.676442934Z" level=info msg="Start streaming server" May 8 00:44:44.676586 containerd[1448]: time="2025-05-08T00:44:44.676137040Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 8 00:44:44.676692 containerd[1448]: time="2025-05-08T00:44:44.676678125Z" level=info msg=serving... address=/run/containerd/containerd.sock May 8 00:44:44.676886 systemd[1]: Started containerd.service - containerd container runtime. May 8 00:44:44.679138 containerd[1448]: time="2025-05-08T00:44:44.679110067Z" level=info msg="containerd successfully booted in 0.044457s" May 8 00:44:44.701222 sshd_keygen[1440]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 8 00:44:44.723508 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 8 00:44:44.736573 systemd[1]: Starting issuegen.service - Generate /run/issue... May 8 00:44:44.743267 systemd[1]: issuegen.service: Deactivated successfully. May 8 00:44:44.743490 systemd[1]: Finished issuegen.service - Generate /run/issue. May 8 00:44:44.746173 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 8 00:44:44.760072 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 8 00:44:44.762916 systemd[1]: Started getty@tty1.service - Getty on tty1. May 8 00:44:44.765065 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 8 00:44:44.766343 systemd[1]: Reached target getty.target - Login Prompts. May 8 00:44:45.126634 systemd-networkd[1377]: eth0: Gained IPv6LL May 8 00:44:45.130581 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 8 00:44:45.132811 systemd[1]: Reached target network-online.target - Network is Online. May 8 00:44:45.151833 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 8 00:44:45.155312 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 8 00:44:45.158217 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 8 00:44:45.176466 systemd[1]: coreos-metadata.service: Deactivated successfully. May 8 00:44:45.176730 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 8 00:44:45.187999 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 8 00:44:45.190832 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 8 00:44:45.853831 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 8 00:44:45.855917 systemd[1]: Reached target multi-user.target - Multi-User System. May 8 00:44:45.857471 systemd[1]: Startup finished in 765ms (kernel) + 6.904s (initrd) + 4.252s (userspace) = 11.922s. May 8 00:44:45.861616 (kubelet)[1532]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS May 8 00:44:46.657814 kubelet[1532]: E0508 00:44:46.657747 1532 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 8 00:44:46.662072 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE May 8 00:44:46.662302 systemd[1]: kubelet.service: Failed with result 'exit-code'. May 8 00:44:46.662722 systemd[1]: kubelet.service: Consumed 1.359s CPU time. May 8 00:44:47.865947 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 8 00:44:47.867718 systemd[1]: Started sshd@0-10.0.0.125:22-10.0.0.1:59814.service - OpenSSH per-connection server daemon (10.0.0.1:59814). May 8 00:44:47.912896 sshd[1545]: Accepted publickey for core from 10.0.0.1 port 59814 ssh2: RSA SHA256:ekllvhAjptCULlaFKPQUz58VR0uIuOgifX+67B4onhs May 8 00:44:47.915695 sshd[1545]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:44:47.926758 systemd-logind[1433]: New session 1 of user core. May 8 00:44:47.928073 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 8 00:44:47.934590 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 8 00:44:47.948381 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 8 00:44:47.958702 systemd[1]: Starting user@500.service - User Manager for UID 500... May 8 00:44:47.961953 (systemd)[1549]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 8 00:44:48.078051 systemd[1549]: Queued start job for default target default.target. May 8 00:44:48.089631 systemd[1549]: Created slice app.slice - User Application Slice. May 8 00:44:48.089657 systemd[1549]: Reached target paths.target - Paths. May 8 00:44:48.089670 systemd[1549]: Reached target timers.target - Timers. May 8 00:44:48.091235 systemd[1549]: Starting dbus.socket - D-Bus User Message Bus Socket... May 8 00:44:48.106140 systemd[1549]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 8 00:44:48.106302 systemd[1549]: Reached target sockets.target - Sockets. May 8 00:44:48.106323 systemd[1549]: Reached target basic.target - Basic System. May 8 00:44:48.106379 systemd[1549]: Reached target default.target - Main User Target. May 8 00:44:48.106416 systemd[1549]: Startup finished in 136ms. May 8 00:44:48.106799 systemd[1]: Started user@500.service - User Manager for UID 500. May 8 00:44:48.108703 systemd[1]: Started session-1.scope - Session 1 of User core. May 8 00:44:48.172435 systemd[1]: Started sshd@1-10.0.0.125:22-10.0.0.1:59826.service - OpenSSH per-connection server daemon (10.0.0.1:59826). May 8 00:44:48.210169 sshd[1560]: Accepted publickey for core from 10.0.0.1 port 59826 ssh2: RSA SHA256:ekllvhAjptCULlaFKPQUz58VR0uIuOgifX+67B4onhs May 8 00:44:48.211812 sshd[1560]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:44:48.216123 systemd-logind[1433]: New session 2 of user core. May 8 00:44:48.228489 systemd[1]: Started session-2.scope - Session 2 of User core. May 8 00:44:48.282724 sshd[1560]: pam_unix(sshd:session): session closed for user core May 8 00:44:48.292548 systemd[1]: sshd@1-10.0.0.125:22-10.0.0.1:59826.service: Deactivated successfully. May 8 00:44:48.294527 systemd[1]: session-2.scope: Deactivated successfully. May 8 00:44:48.295911 systemd-logind[1433]: Session 2 logged out. Waiting for processes to exit. May 8 00:44:48.310601 systemd[1]: Started sshd@2-10.0.0.125:22-10.0.0.1:59834.service - OpenSSH per-connection server daemon (10.0.0.1:59834). May 8 00:44:48.311443 systemd-logind[1433]: Removed session 2. May 8 00:44:48.339640 sshd[1567]: Accepted publickey for core from 10.0.0.1 port 59834 ssh2: RSA SHA256:ekllvhAjptCULlaFKPQUz58VR0uIuOgifX+67B4onhs May 8 00:44:48.341114 sshd[1567]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:44:48.345322 systemd-logind[1433]: New session 3 of user core. May 8 00:44:48.354462 systemd[1]: Started session-3.scope - Session 3 of User core. May 8 00:44:48.404984 sshd[1567]: pam_unix(sshd:session): session closed for user core May 8 00:44:48.418466 systemd[1]: sshd@2-10.0.0.125:22-10.0.0.1:59834.service: Deactivated successfully. May 8 00:44:48.420254 systemd[1]: session-3.scope: Deactivated successfully. May 8 00:44:48.421770 systemd-logind[1433]: Session 3 logged out. Waiting for processes to exit. May 8 00:44:48.422999 systemd[1]: Started sshd@3-10.0.0.125:22-10.0.0.1:59846.service - OpenSSH per-connection server daemon (10.0.0.1:59846). May 8 00:44:48.423839 systemd-logind[1433]: Removed session 3. May 8 00:44:48.471402 sshd[1574]: Accepted publickey for core from 10.0.0.1 port 59846 ssh2: RSA SHA256:ekllvhAjptCULlaFKPQUz58VR0uIuOgifX+67B4onhs May 8 00:44:48.472874 sshd[1574]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:44:48.476728 systemd-logind[1433]: New session 4 of user core. May 8 00:44:48.482580 systemd[1]: Started session-4.scope - Session 4 of User core. May 8 00:44:48.537843 sshd[1574]: pam_unix(sshd:session): session closed for user core May 8 00:44:48.544894 systemd[1]: sshd@3-10.0.0.125:22-10.0.0.1:59846.service: Deactivated successfully. May 8 00:44:48.546502 systemd[1]: session-4.scope: Deactivated successfully. May 8 00:44:48.547987 systemd-logind[1433]: Session 4 logged out. Waiting for processes to exit. May 8 00:44:48.557597 systemd[1]: Started sshd@4-10.0.0.125:22-10.0.0.1:59850.service - OpenSSH per-connection server daemon (10.0.0.1:59850). May 8 00:44:48.558449 systemd-logind[1433]: Removed session 4. May 8 00:44:48.588074 sshd[1581]: Accepted publickey for core from 10.0.0.1 port 59850 ssh2: RSA SHA256:ekllvhAjptCULlaFKPQUz58VR0uIuOgifX+67B4onhs May 8 00:44:48.589719 sshd[1581]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 8 00:44:48.593829 systemd-logind[1433]: New session 5 of user core. May 8 00:44:48.603527 systemd[1]: Started session-5.scope - Session 5 of User core. May 8 00:44:48.662596 sudo[1584]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh May 8 00:44:48.662960 sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 8 00:44:48.685620 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... May 8 00:44:48.705839 systemd[1]: coreos-metadata.service: Deactivated successfully. May 8 00:44:48.706081 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. May 8 00:44:49.238558 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 8 00:44:49.238713 systemd[1]: kubelet.service: Consumed 1.359s CPU time. May 8 00:44:49.248561 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 8 00:44:49.272419 systemd[1]: Reloading requested from client PID 1626 ('systemctl') (unit session-5.scope)... May 8 00:44:49.272440 systemd[1]: Reloading... May 8 00:44:49.360378 zram_generator::config[1667]: No configuration found. May 8 00:44:49.624915 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 8 00:44:49.703446 systemd[1]: Reloading finished in 430 ms. May 8 00:44:49.753720 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... May 8 00:44:49.758267 systemd[1]: kubelet.service: Deactivated successfully. May 8 00:44:49.758638 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. May 8 00:44:49.760604 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... May 8 00:44:49.928374 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. May 8 00:44:49.933947 (kubelet)[1714]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS May 8 00:44:50.050705 kubelet[1714]: 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 8 00:44:50.050705 kubelet[1714]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. May 8 00:44:50.050705 kubelet[1714]: 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 8 00:44:50.051198 kubelet[1714]: I0508 00:44:50.050752 1714 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" May 8 00:44:50.375888 kubelet[1714]: I0508 00:44:50.375837 1714 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" May 8 00:44:50.375888 kubelet[1714]: I0508 00:44:50.375873 1714 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" May 8 00:44:50.376215 kubelet[1714]: I0508 00:44:50.376195 1714 server.go:954] "Client rotation is on, will bootstrap in background" May 8 00:44:50.402168 kubelet[1714]: I0508 00:44:50.400955 1714 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" May 8 00:44:50.409817 kubelet[1714]: E0508 00:44:50.409756 1714 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" May 8 00:44:50.409817 kubelet[1714]: I0508 00:44:50.409803 1714 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." May 8 00:44:50.414746 kubelet[1714]: I0508 00:44:50.414722 1714 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" May 8 00:44:50.416045 kubelet[1714]: I0508 00:44:50.415996 1714 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] May 8 00:44:50.416215 kubelet[1714]: I0508 00:44:50.416038 1714 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.0.0.125","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 8 00:44:50.416297 kubelet[1714]: I0508 00:44:50.416217 1714 topology_manager.go:138] "Creating topology manager with none policy" May 8 00:44:50.416297 kubelet[1714]: I0508 00:44:50.416225 1714 container_manager_linux.go:304] "Creating device plugin manager" May 8 00:44:50.416411 kubelet[1714]: I0508 00:44:50.416389 1714 state_mem.go:36] "Initialized new in-memory state store" May 8 00:44:50.419396 kubelet[1714]: I0508 00:44:50.419373 1714 kubelet.go:446] "Attempting to sync node with API server" May 8 00:44:50.419396 kubelet[1714]: I0508 00:44:50.419389 1714 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" May 8 00:44:50.419463 kubelet[1714]: I0508 00:44:50.419410 1714 kubelet.go:352] "Adding apiserver pod source" May 8 00:44:50.419463 kubelet[1714]: I0508 00:44:50.419420 1714 apiserver.go:42] "Waiting for node sync before watching apiserver pods" May 8 00:44:50.419602 kubelet[1714]: E0508 00:44:50.419500 1714 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:50.420186 kubelet[1714]: E0508 00:44:50.420152 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:50.424301 kubelet[1714]: I0508 00:44:50.424278 1714 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" May 8 00:44:50.424741 kubelet[1714]: I0508 00:44:50.424712 1714 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" May 8 00:44:50.425451 kubelet[1714]: W0508 00:44:50.425423 1714 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. May 8 00:44:50.428377 kubelet[1714]: W0508 00:44:50.428290 1714 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Service: services is forbidden: User "system:anonymous" cannot list resource "services" in API group "" at the cluster scope May 8 00:44:50.428486 kubelet[1714]: W0508 00:44:50.428449 1714 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.Node: nodes "10.0.0.125" is forbidden: User "system:anonymous" cannot list resource "nodes" in API group "" at the cluster scope May 8 00:44:50.428520 kubelet[1714]: E0508 00:44:50.428493 1714 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Node: failed to list *v1.Node: nodes \"10.0.0.125\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" May 8 00:44:50.428568 kubelet[1714]: E0508 00:44:50.428444 1714 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.Service: failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" May 8 00:44:50.430310 kubelet[1714]: I0508 00:44:50.430282 1714 watchdog_linux.go:99] "Systemd watchdog is not enabled" May 8 00:44:50.430374 kubelet[1714]: I0508 00:44:50.430324 1714 server.go:1287] "Started kubelet" May 8 00:44:50.430521 kubelet[1714]: I0508 00:44:50.430446 1714 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 May 8 00:44:50.432239 kubelet[1714]: I0508 00:44:50.431343 1714 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 May 8 00:44:50.432239 kubelet[1714]: I0508 00:44:50.431556 1714 server.go:490] "Adding debug handlers to kubelet server" May 8 00:44:50.432239 kubelet[1714]: I0508 00:44:50.431807 1714 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" May 8 00:44:50.433231 kubelet[1714]: I0508 00:44:50.433205 1714 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" May 8 00:44:50.433483 kubelet[1714]: I0508 00:44:50.433392 1714 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 8 00:44:50.434041 kubelet[1714]: E0508 00:44:50.433704 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:50.434141 kubelet[1714]: I0508 00:44:50.434129 1714 volume_manager.go:297] "Starting Kubelet Volume Manager" May 8 00:44:50.434639 kubelet[1714]: I0508 00:44:50.434625 1714 desired_state_of_world_populator.go:149] "Desired state populator starts to run" May 8 00:44:50.434740 kubelet[1714]: I0508 00:44:50.434730 1714 reconciler.go:26] "Reconciler: start to sync state" May 8 00:44:50.435190 kubelet[1714]: I0508 00:44:50.435172 1714 factory.go:221] Registration of the systemd container factory successfully May 8 00:44:50.435375 kubelet[1714]: I0508 00:44:50.435342 1714 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 8 00:44:50.436213 kubelet[1714]: E0508 00:44:50.435922 1714 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" May 8 00:44:50.436734 kubelet[1714]: I0508 00:44:50.436716 1714 factory.go:221] Registration of the containerd container factory successfully May 8 00:44:50.446308 kubelet[1714]: E0508 00:44:50.446080 1714 controller.go:145] "Failed to ensure lease exists, will retry" err="leases.coordination.k8s.io \"10.0.0.125\" is forbidden: User \"system:anonymous\" cannot get resource \"leases\" in API group \"coordination.k8s.io\" in the namespace \"kube-node-lease\"" interval="200ms" May 8 00:44:50.446308 kubelet[1714]: W0508 00:44:50.446205 1714 reflector.go:569] k8s.io/client-go/informers/factory.go:160: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User "system:anonymous" cannot list resource "csidrivers" in API group "storage.k8s.io" at the cluster scope May 8 00:44:50.446308 kubelet[1714]: E0508 00:44:50.446233 1714 reflector.go:166] "Unhandled Error" err="k8s.io/client-go/informers/factory.go:160: Failed to watch *v1.CSIDriver: failed to list *v1.CSIDriver: csidrivers.storage.k8s.io is forbidden: User \"system:anonymous\" cannot list resource \"csidrivers\" in API group \"storage.k8s.io\" at the cluster scope" logger="UnhandledError" May 8 00:44:50.446612 kubelet[1714]: E0508 00:44:50.444923 1714 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.125.183d66a540ac6195 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.125,UID:10.0.0.125,APIVersion:,ResourceVersion:,FieldPath:,},Reason:Starting,Message:Starting kubelet.,Source:EventSource{Component:kubelet,Host:10.0.0.125,},FirstTimestamp:2025-05-08 00:44:50.430296469 +0000 UTC m=+0.488880191,LastTimestamp:2025-05-08 00:44:50.430296469 +0000 UTC m=+0.488880191,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.125,}" May 8 00:44:50.451323 kubelet[1714]: E0508 00:44:50.451213 1714 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.125.183d66a5410213d7 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.125,UID:10.0.0.125,APIVersion:,ResourceVersion:,FieldPath:,},Reason:InvalidDiskCapacity,Message:invalid capacity 0 on image filesystem,Source:EventSource{Component:kubelet,Host:10.0.0.125,},FirstTimestamp:2025-05-08 00:44:50.435912663 +0000 UTC m=+0.494496385,LastTimestamp:2025-05-08 00:44:50.435912663 +0000 UTC m=+0.494496385,Count:1,Type:Warning,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.125,}" May 8 00:44:50.453524 kubelet[1714]: I0508 00:44:50.453487 1714 cpu_manager.go:221] "Starting CPU manager" policy="none" May 8 00:44:50.453524 kubelet[1714]: I0508 00:44:50.453505 1714 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" May 8 00:44:50.453524 kubelet[1714]: I0508 00:44:50.453525 1714 state_mem.go:36] "Initialized new in-memory state store" May 8 00:44:50.456269 kubelet[1714]: E0508 00:44:50.456200 1714 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.125.183d66a54200a9e3 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.125,UID:10.0.0.125,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientMemory,Message:Node 10.0.0.125 status is now: NodeHasSufficientMemory,Source:EventSource{Component:kubelet,Host:10.0.0.125,},FirstTimestamp:2025-05-08 00:44:50.452597219 +0000 UTC m=+0.511180941,LastTimestamp:2025-05-08 00:44:50.452597219 +0000 UTC m=+0.511180941,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.125,}" May 8 00:44:50.461082 kubelet[1714]: E0508 00:44:50.460968 1714 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.125.183d66a54200d14b default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.125,UID:10.0.0.125,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasNoDiskPressure,Message:Node 10.0.0.125 status is now: NodeHasNoDiskPressure,Source:EventSource{Component:kubelet,Host:10.0.0.125,},FirstTimestamp:2025-05-08 00:44:50.452607307 +0000 UTC m=+0.511191030,LastTimestamp:2025-05-08 00:44:50.452607307 +0000 UTC m=+0.511191030,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.125,}" May 8 00:44:50.473913 kubelet[1714]: E0508 00:44:50.473773 1714 event.go:359] "Server rejected event (will not retry!)" err="events is forbidden: User \"system:anonymous\" cannot create resource \"events\" in API group \"\" in the namespace \"default\"" event="&Event{ObjectMeta:{10.0.0.125.183d66a54200db46 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.125,UID:10.0.0.125,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientPID,Message:Node 10.0.0.125 status is now: NodeHasSufficientPID,Source:EventSource{Component:kubelet,Host:10.0.0.125,},FirstTimestamp:2025-05-08 00:44:50.452609862 +0000 UTC m=+0.511193584,LastTimestamp:2025-05-08 00:44:50.452609862 +0000 UTC m=+0.511193584,Count:1,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.125,}" May 8 00:44:50.527593 kubelet[1714]: I0508 00:44:50.527566 1714 policy_none.go:49] "None policy: Start" May 8 00:44:50.527593 kubelet[1714]: I0508 00:44:50.527589 1714 memory_manager.go:186] "Starting memorymanager" policy="None" May 8 00:44:50.527593 kubelet[1714]: I0508 00:44:50.527601 1714 state_mem.go:35] "Initializing new in-memory state store" May 8 00:44:50.534607 kubelet[1714]: E0508 00:44:50.534581 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:50.567623 kubelet[1714]: I0508 00:44:50.567544 1714 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" May 8 00:44:50.569125 kubelet[1714]: I0508 00:44:50.569091 1714 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" May 8 00:44:50.569230 kubelet[1714]: I0508 00:44:50.569136 1714 status_manager.go:227] "Starting to sync pod status with apiserver" May 8 00:44:50.569230 kubelet[1714]: I0508 00:44:50.569177 1714 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." May 8 00:44:50.569230 kubelet[1714]: I0508 00:44:50.569185 1714 kubelet.go:2388] "Starting kubelet main sync loop" May 8 00:44:50.569315 kubelet[1714]: E0508 00:44:50.569242 1714 kubelet.go:2412] "Skipping pod synchronization" err="[container runtime status check may not have completed yet, PLEG is not healthy: pleg has yet to be successful]" May 8 00:44:50.585120 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. May 8 00:44:50.599711 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. May 8 00:44:50.603620 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. May 8 00:44:50.613731 kubelet[1714]: I0508 00:44:50.613676 1714 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" May 8 00:44:50.614016 kubelet[1714]: I0508 00:44:50.613937 1714 eviction_manager.go:189] "Eviction manager: starting control loop" May 8 00:44:50.614016 kubelet[1714]: I0508 00:44:50.613968 1714 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" May 8 00:44:50.614630 kubelet[1714]: I0508 00:44:50.614600 1714 plugin_manager.go:118] "Starting Kubelet Plugin Manager" May 8 00:44:50.615007 kubelet[1714]: E0508 00:44:50.614961 1714 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" May 8 00:44:50.615007 kubelet[1714]: E0508 00:44:50.615015 1714 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.0.0.125\" not found" May 8 00:44:50.667258 kubelet[1714]: E0508 00:44:50.667111 1714 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.0.0.125\" not found" node="10.0.0.125" May 8 00:44:50.715299 kubelet[1714]: I0508 00:44:50.715255 1714 kubelet_node_status.go:76] "Attempting to register node" node="10.0.0.125" May 8 00:44:50.740417 kubelet[1714]: I0508 00:44:50.740340 1714 kubelet_node_status.go:79] "Successfully registered node" node="10.0.0.125" May 8 00:44:50.740417 kubelet[1714]: E0508 00:44:50.740389 1714 kubelet_node_status.go:549] "Error updating node status, will retry" err="error getting node \"10.0.0.125\": node \"10.0.0.125\" not found" May 8 00:44:50.763610 kubelet[1714]: E0508 00:44:50.763576 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:50.864203 kubelet[1714]: E0508 00:44:50.864152 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:50.964734 kubelet[1714]: E0508 00:44:50.964589 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:51.064960 kubelet[1714]: E0508 00:44:51.064903 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:51.165626 kubelet[1714]: E0508 00:44:51.165570 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:51.266337 kubelet[1714]: E0508 00:44:51.266260 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:51.367154 kubelet[1714]: E0508 00:44:51.367073 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:51.379320 kubelet[1714]: I0508 00:44:51.379264 1714 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" May 8 00:44:51.379588 kubelet[1714]: W0508 00:44:51.379552 1714 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received May 8 00:44:51.379793 kubelet[1714]: E0508 00:44:51.379658 1714 event.go:368] "Unable to write event (may retry after sleeping)" err="Post \"https://10.0.0.117:6443/api/v1/namespaces/default/events\": read tcp 10.0.0.125:56466->10.0.0.117:6443: use of closed network connection" event="&Event{ObjectMeta:{10.0.0.125.183d66a54200db46 default 0 0001-01-01 00:00:00 +0000 UTC map[] map[] [] [] []},InvolvedObject:ObjectReference{Kind:Node,Namespace:,Name:10.0.0.125,UID:10.0.0.125,APIVersion:,ResourceVersion:,FieldPath:,},Reason:NodeHasSufficientPID,Message:Node 10.0.0.125 status is now: NodeHasSufficientPID,Source:EventSource{Component:kubelet,Host:10.0.0.125,},FirstTimestamp:2025-05-08 00:44:50.452609862 +0000 UTC m=+0.511193584,LastTimestamp:2025-05-08 00:44:50.715220705 +0000 UTC m=+0.773804427,Count:2,Type:Normal,EventTime:0001-01-01 00:00:00 +0000 UTC,Series:nil,Action:,Related:nil,ReportingController:kubelet,ReportingInstance:10.0.0.125,}" May 8 00:44:51.420884 kubelet[1714]: E0508 00:44:51.420814 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:51.467644 kubelet[1714]: E0508 00:44:51.467577 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:51.503323 sudo[1584]: pam_unix(sudo:session): session closed for user root May 8 00:44:51.505466 sshd[1581]: pam_unix(sshd:session): session closed for user core May 8 00:44:51.509913 systemd[1]: sshd@4-10.0.0.125:22-10.0.0.1:59850.service: Deactivated successfully. May 8 00:44:51.512306 systemd[1]: session-5.scope: Deactivated successfully. May 8 00:44:51.513066 systemd-logind[1433]: Session 5 logged out. Waiting for processes to exit. May 8 00:44:51.514169 systemd-logind[1433]: Removed session 5. May 8 00:44:51.568877 kubelet[1714]: E0508 00:44:51.568703 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:51.669187 kubelet[1714]: E0508 00:44:51.669082 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:51.770120 kubelet[1714]: E0508 00:44:51.770043 1714 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.0.0.125\" not found" May 8 00:44:51.871853 kubelet[1714]: I0508 00:44:51.871736 1714 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" May 8 00:44:51.872097 containerd[1448]: time="2025-05-08T00:44:51.872034978Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." May 8 00:44:51.872565 kubelet[1714]: I0508 00:44:51.872248 1714 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" May 8 00:44:52.421110 kubelet[1714]: I0508 00:44:52.421069 1714 apiserver.go:52] "Watching apiserver" May 8 00:44:52.421110 kubelet[1714]: E0508 00:44:52.421102 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:52.430544 systemd[1]: Created slice kubepods-besteffort-pod1f5464e4_0dbb_41fa_8af9_d5f0816d1bab.slice - libcontainer container kubepods-besteffort-pod1f5464e4_0dbb_41fa_8af9_d5f0816d1bab.slice. May 8 00:44:52.435153 kubelet[1714]: I0508 00:44:52.435124 1714 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" May 8 00:44:52.439874 systemd[1]: Created slice kubepods-burstable-pod9aa6a583_91cc_49dd_af14_9b4e64e91760.slice - libcontainer container kubepods-burstable-pod9aa6a583_91cc_49dd_af14_9b4e64e91760.slice. May 8 00:44:52.447973 kubelet[1714]: I0508 00:44:52.447938 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/9aa6a583-91cc-49dd-af14-9b4e64e91760-cni\") pod \"kube-flannel-ds-bl5zj\" (UID: \"9aa6a583-91cc-49dd-af14-9b4e64e91760\") " pod="kube-flannel/kube-flannel-ds-bl5zj" May 8 00:44:52.447973 kubelet[1714]: I0508 00:44:52.447968 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/9aa6a583-91cc-49dd-af14-9b4e64e91760-xtables-lock\") pod \"kube-flannel-ds-bl5zj\" (UID: \"9aa6a583-91cc-49dd-af14-9b4e64e91760\") " pod="kube-flannel/kube-flannel-ds-bl5zj" May 8 00:44:52.448082 kubelet[1714]: I0508 00:44:52.447995 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-vmdwc\" (UniqueName: \"kubernetes.io/projected/9aa6a583-91cc-49dd-af14-9b4e64e91760-kube-api-access-vmdwc\") pod \"kube-flannel-ds-bl5zj\" (UID: \"9aa6a583-91cc-49dd-af14-9b4e64e91760\") " pod="kube-flannel/kube-flannel-ds-bl5zj" May 8 00:44:52.448082 kubelet[1714]: I0508 00:44:52.448011 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/1f5464e4-0dbb-41fa-8af9-d5f0816d1bab-xtables-lock\") pod \"kube-proxy-pg4dk\" (UID: \"1f5464e4-0dbb-41fa-8af9-d5f0816d1bab\") " pod="kube-system/kube-proxy-pg4dk" May 8 00:44:52.448082 kubelet[1714]: I0508 00:44:52.448028 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/1f5464e4-0dbb-41fa-8af9-d5f0816d1bab-lib-modules\") pod \"kube-proxy-pg4dk\" (UID: \"1f5464e4-0dbb-41fa-8af9-d5f0816d1bab\") " pod="kube-system/kube-proxy-pg4dk" May 8 00:44:52.448082 kubelet[1714]: I0508 00:44:52.448042 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-dw5dw\" (UniqueName: \"kubernetes.io/projected/1f5464e4-0dbb-41fa-8af9-d5f0816d1bab-kube-api-access-dw5dw\") pod \"kube-proxy-pg4dk\" (UID: \"1f5464e4-0dbb-41fa-8af9-d5f0816d1bab\") " pod="kube-system/kube-proxy-pg4dk" May 8 00:44:52.448082 kubelet[1714]: I0508 00:44:52.448058 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/9aa6a583-91cc-49dd-af14-9b4e64e91760-run\") pod \"kube-flannel-ds-bl5zj\" (UID: \"9aa6a583-91cc-49dd-af14-9b4e64e91760\") " pod="kube-flannel/kube-flannel-ds-bl5zj" May 8 00:44:52.448214 kubelet[1714]: I0508 00:44:52.448089 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/9aa6a583-91cc-49dd-af14-9b4e64e91760-flannel-cfg\") pod \"kube-flannel-ds-bl5zj\" (UID: \"9aa6a583-91cc-49dd-af14-9b4e64e91760\") " pod="kube-flannel/kube-flannel-ds-bl5zj" May 8 00:44:52.448214 kubelet[1714]: I0508 00:44:52.448104 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/1f5464e4-0dbb-41fa-8af9-d5f0816d1bab-kube-proxy\") pod \"kube-proxy-pg4dk\" (UID: \"1f5464e4-0dbb-41fa-8af9-d5f0816d1bab\") " pod="kube-system/kube-proxy-pg4dk" May 8 00:44:52.448214 kubelet[1714]: I0508 00:44:52.448183 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/9aa6a583-91cc-49dd-af14-9b4e64e91760-cni-plugin\") pod \"kube-flannel-ds-bl5zj\" (UID: \"9aa6a583-91cc-49dd-af14-9b4e64e91760\") " pod="kube-flannel/kube-flannel-ds-bl5zj" May 8 00:44:52.740195 kubelet[1714]: E0508 00:44:52.740133 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:44:52.740909 containerd[1448]: time="2025-05-08T00:44:52.740864985Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-pg4dk,Uid:1f5464e4-0dbb-41fa-8af9-d5f0816d1bab,Namespace:kube-system,Attempt:0,}" May 8 00:44:52.751527 kubelet[1714]: E0508 00:44:52.751482 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:44:52.751867 containerd[1448]: time="2025-05-08T00:44:52.751823340Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-bl5zj,Uid:9aa6a583-91cc-49dd-af14-9b4e64e91760,Namespace:kube-flannel,Attempt:0,}" May 8 00:44:53.421780 kubelet[1714]: E0508 00:44:53.421724 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:53.766128 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3046834029.mount: Deactivated successfully. May 8 00:44:53.790270 containerd[1448]: time="2025-05-08T00:44:53.790184448Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 8 00:44:53.842867 containerd[1448]: time="2025-05-08T00:44:53.842765726Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312056" May 8 00:44:53.905806 containerd[1448]: time="2025-05-08T00:44:53.905734828Z" level=info msg="ImageUpdate event name:\"registry.k8s.io/pause:3.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 8 00:44:53.911033 containerd[1448]: time="2025-05-08T00:44:53.910951302Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 8 00:44:53.912731 containerd[1448]: time="2025-05-08T00:44:53.912657012Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" May 8 00:44:53.917116 containerd[1448]: time="2025-05-08T00:44:53.917072384Z" level=info msg="ImageCreate event name:\"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" May 8 00:44:53.917902 containerd[1448]: time="2025-05-08T00:44:53.917869449Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 1.16592928s" May 8 00:44:53.925364 containerd[1448]: time="2025-05-08T00:44:53.925314193Z" level=info msg="Pulled image \"registry.k8s.io/pause:3.8\" with image id \"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\", repo tag \"registry.k8s.io/pause:3.8\", repo digest \"registry.k8s.io/pause@sha256:9001185023633d17a2f98ff69b6ff2615b8ea02a825adffa40422f51dfdcde9d\", size \"311286\" in 1.184358818s" May 8 00:44:54.394407 containerd[1448]: time="2025-05-08T00:44:54.394228773Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:44:54.394407 containerd[1448]: time="2025-05-08T00:44:54.394294867Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:44:54.394407 containerd[1448]: time="2025-05-08T00:44:54.394306919Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:44:54.394648 containerd[1448]: time="2025-05-08T00:44:54.394433547Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:44:54.423722 kubelet[1714]: E0508 00:44:54.422171 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:54.430160 containerd[1448]: time="2025-05-08T00:44:54.429904981Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:44:54.430160 containerd[1448]: time="2025-05-08T00:44:54.429975113Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:44:54.430160 containerd[1448]: time="2025-05-08T00:44:54.429987045Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:44:54.430160 containerd[1448]: time="2025-05-08T00:44:54.430102021Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:44:54.535648 systemd[1]: Started cri-containerd-0b7c9bac6a67dc41154da9111f029e7e710e81a820b932be18de1ff7e2d482d0.scope - libcontainer container 0b7c9bac6a67dc41154da9111f029e7e710e81a820b932be18de1ff7e2d482d0. May 8 00:44:54.538062 systemd[1]: Started cri-containerd-8d97b10ce7704689c5879a3e94ea10815a71693975d6d4b582b3ae12d8fbc620.scope - libcontainer container 8d97b10ce7704689c5879a3e94ea10815a71693975d6d4b582b3ae12d8fbc620. May 8 00:44:54.564943 containerd[1448]: time="2025-05-08T00:44:54.564792812Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-pg4dk,Uid:1f5464e4-0dbb-41fa-8af9-d5f0816d1bab,Namespace:kube-system,Attempt:0,} returns sandbox id \"0b7c9bac6a67dc41154da9111f029e7e710e81a820b932be18de1ff7e2d482d0\"" May 8 00:44:54.566804 kubelet[1714]: E0508 00:44:54.566763 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:44:54.568006 containerd[1448]: time="2025-05-08T00:44:54.567969751Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\"" May 8 00:44:54.579200 containerd[1448]: time="2025-05-08T00:44:54.579144973Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-bl5zj,Uid:9aa6a583-91cc-49dd-af14-9b4e64e91760,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"8d97b10ce7704689c5879a3e94ea10815a71693975d6d4b582b3ae12d8fbc620\"" May 8 00:44:54.579740 kubelet[1714]: E0508 00:44:54.579717 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:44:55.422468 kubelet[1714]: E0508 00:44:55.422415 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:56.330415 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount651914808.mount: Deactivated successfully. May 8 00:44:56.423224 kubelet[1714]: E0508 00:44:56.423141 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:57.010948 containerd[1448]: time="2025-05-08T00:44:57.010878810Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:44:57.012803 containerd[1448]: time="2025-05-08T00:44:57.012736815Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.4: active requests=0, bytes read=30917856" May 8 00:44:57.014991 containerd[1448]: time="2025-05-08T00:44:57.014955607Z" level=info msg="ImageCreate event name:\"sha256:608f0c8bf7f9651ca79f170235ea5eefb978a0c1da132e7477a88ad37d171ad3\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:44:57.020717 containerd[1448]: time="2025-05-08T00:44:57.020657753Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:44:57.021248 containerd[1448]: time="2025-05-08T00:44:57.021215539Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.4\" with image id \"sha256:608f0c8bf7f9651ca79f170235ea5eefb978a0c1da132e7477a88ad37d171ad3\", repo tag \"registry.k8s.io/kube-proxy:v1.32.4\", repo digest \"registry.k8s.io/kube-proxy@sha256:152638222ecf265eb8e5352e3c50e8fc520994e8ffcff1ee1490c975f7fc2b36\", size \"30916875\" in 2.453212536s" May 8 00:44:57.021283 containerd[1448]: time="2025-05-08T00:44:57.021246798Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.4\" returns image reference \"sha256:608f0c8bf7f9651ca79f170235ea5eefb978a0c1da132e7477a88ad37d171ad3\"" May 8 00:44:57.022664 containerd[1448]: time="2025-05-08T00:44:57.022629942Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" May 8 00:44:57.023876 containerd[1448]: time="2025-05-08T00:44:57.023838549Z" level=info msg="CreateContainer within sandbox \"0b7c9bac6a67dc41154da9111f029e7e710e81a820b932be18de1ff7e2d482d0\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" May 8 00:44:57.045530 containerd[1448]: time="2025-05-08T00:44:57.045465555Z" level=info msg="CreateContainer within sandbox \"0b7c9bac6a67dc41154da9111f029e7e710e81a820b932be18de1ff7e2d482d0\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"8e85de8acb6d0de387ab4f208801fb4ebd4b1f27e864aa8768a80f5ba1c1ff5a\"" May 8 00:44:57.046447 containerd[1448]: time="2025-05-08T00:44:57.046402082Z" level=info msg="StartContainer for \"8e85de8acb6d0de387ab4f208801fb4ebd4b1f27e864aa8768a80f5ba1c1ff5a\"" May 8 00:44:57.093679 systemd[1]: Started cri-containerd-8e85de8acb6d0de387ab4f208801fb4ebd4b1f27e864aa8768a80f5ba1c1ff5a.scope - libcontainer container 8e85de8acb6d0de387ab4f208801fb4ebd4b1f27e864aa8768a80f5ba1c1ff5a. May 8 00:44:57.161378 containerd[1448]: time="2025-05-08T00:44:57.161300829Z" level=info msg="StartContainer for \"8e85de8acb6d0de387ab4f208801fb4ebd4b1f27e864aa8768a80f5ba1c1ff5a\" returns successfully" May 8 00:44:57.423843 kubelet[1714]: E0508 00:44:57.423706 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:57.583933 kubelet[1714]: E0508 00:44:57.583899 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:44:57.593645 kubelet[1714]: I0508 00:44:57.593559 1714 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-pg4dk" podStartSLOduration=5.138723442 podStartE2EDuration="7.593530899s" podCreationTimestamp="2025-05-08 00:44:50 +0000 UTC" firstStartedPulling="2025-05-08 00:44:54.567528564 +0000 UTC m=+4.626112286" lastFinishedPulling="2025-05-08 00:44:57.022336021 +0000 UTC m=+7.080919743" observedRunningTime="2025-05-08 00:44:57.593431843 +0000 UTC m=+7.652015565" watchObservedRunningTime="2025-05-08 00:44:57.593530899 +0000 UTC m=+7.652114621" May 8 00:44:58.424744 kubelet[1714]: E0508 00:44:58.424687 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:58.585404 kubelet[1714]: E0508 00:44:58.585368 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:44:58.955576 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2326730586.mount: Deactivated successfully. May 8 00:44:59.158974 containerd[1448]: time="2025-05-08T00:44:59.158905414Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:44:59.159917 containerd[1448]: time="2025-05-08T00:44:59.159858843Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852936" May 8 00:44:59.161007 containerd[1448]: time="2025-05-08T00:44:59.160978402Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:44:59.164554 containerd[1448]: time="2025-05-08T00:44:59.164488216Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:44:59.165403 containerd[1448]: time="2025-05-08T00:44:59.165368056Z" 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 2.142680015s" May 8 00:44:59.165444 containerd[1448]: time="2025-05-08T00:44:59.165402661Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" May 8 00:44:59.167240 containerd[1448]: time="2025-05-08T00:44:59.167216233Z" level=info msg="CreateContainer within sandbox \"8d97b10ce7704689c5879a3e94ea10815a71693975d6d4b582b3ae12d8fbc620\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" May 8 00:44:59.181745 containerd[1448]: time="2025-05-08T00:44:59.181681225Z" level=info msg="CreateContainer within sandbox \"8d97b10ce7704689c5879a3e94ea10815a71693975d6d4b582b3ae12d8fbc620\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"ef5f2d6243aa437fe4bb9954cbbabb82e229782cc4904e7904788ffbb1db7669\"" May 8 00:44:59.182424 containerd[1448]: time="2025-05-08T00:44:59.182318861Z" level=info msg="StartContainer for \"ef5f2d6243aa437fe4bb9954cbbabb82e229782cc4904e7904788ffbb1db7669\"" May 8 00:44:59.241557 systemd[1]: Started cri-containerd-ef5f2d6243aa437fe4bb9954cbbabb82e229782cc4904e7904788ffbb1db7669.scope - libcontainer container ef5f2d6243aa437fe4bb9954cbbabb82e229782cc4904e7904788ffbb1db7669. May 8 00:44:59.317681 containerd[1448]: time="2025-05-08T00:44:59.317620498Z" level=info msg="StartContainer for \"ef5f2d6243aa437fe4bb9954cbbabb82e229782cc4904e7904788ffbb1db7669\" returns successfully" May 8 00:44:59.317796 systemd[1]: cri-containerd-ef5f2d6243aa437fe4bb9954cbbabb82e229782cc4904e7904788ffbb1db7669.scope: Deactivated successfully. May 8 00:44:59.425224 kubelet[1714]: E0508 00:44:59.425155 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:44:59.587926 kubelet[1714]: E0508 00:44:59.587791 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:44:59.816221 containerd[1448]: time="2025-05-08T00:44:59.816156099Z" level=info msg="shim disconnected" id=ef5f2d6243aa437fe4bb9954cbbabb82e229782cc4904e7904788ffbb1db7669 namespace=k8s.io May 8 00:44:59.816221 containerd[1448]: time="2025-05-08T00:44:59.816214348Z" level=warning msg="cleaning up after shim disconnected" id=ef5f2d6243aa437fe4bb9954cbbabb82e229782cc4904e7904788ffbb1db7669 namespace=k8s.io May 8 00:44:59.816221 containerd[1448]: time="2025-05-08T00:44:59.816223094Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 8 00:44:59.890688 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-ef5f2d6243aa437fe4bb9954cbbabb82e229782cc4904e7904788ffbb1db7669-rootfs.mount: Deactivated successfully. May 8 00:45:00.425378 kubelet[1714]: E0508 00:45:00.425302 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:00.590729 kubelet[1714]: E0508 00:45:00.590691 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:45:00.591386 containerd[1448]: time="2025-05-08T00:45:00.591324583Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" May 8 00:45:01.426326 kubelet[1714]: E0508 00:45:01.426275 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:02.427232 kubelet[1714]: E0508 00:45:02.427175 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:03.427777 kubelet[1714]: E0508 00:45:03.427521 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:03.482392 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount905387741.mount: Deactivated successfully. May 8 00:45:04.431064 kubelet[1714]: E0508 00:45:04.431007 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:05.109710 containerd[1448]: time="2025-05-08T00:45:05.109645922Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:45:05.110460 containerd[1448]: time="2025-05-08T00:45:05.110424974Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" May 8 00:45:05.111763 containerd[1448]: time="2025-05-08T00:45:05.111732005Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:45:05.114618 containerd[1448]: time="2025-05-08T00:45:05.114585458Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:45:05.116497 containerd[1448]: time="2025-05-08T00:45:05.116458962Z" 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 4.525071621s" May 8 00:45:05.116497 containerd[1448]: time="2025-05-08T00:45:05.116491373Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" May 8 00:45:05.118287 containerd[1448]: time="2025-05-08T00:45:05.118240904Z" level=info msg="CreateContainer within sandbox \"8d97b10ce7704689c5879a3e94ea10815a71693975d6d4b582b3ae12d8fbc620\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" May 8 00:45:05.133649 containerd[1448]: time="2025-05-08T00:45:05.133605534Z" level=info msg="CreateContainer within sandbox \"8d97b10ce7704689c5879a3e94ea10815a71693975d6d4b582b3ae12d8fbc620\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"5b09511a0c972ba43aedd6af9dd2c52e13c136aede85009fc4dd61a72ca24694\"" May 8 00:45:05.134259 containerd[1448]: time="2025-05-08T00:45:05.134228031Z" level=info msg="StartContainer for \"5b09511a0c972ba43aedd6af9dd2c52e13c136aede85009fc4dd61a72ca24694\"" May 8 00:45:05.182543 systemd[1]: Started cri-containerd-5b09511a0c972ba43aedd6af9dd2c52e13c136aede85009fc4dd61a72ca24694.scope - libcontainer container 5b09511a0c972ba43aedd6af9dd2c52e13c136aede85009fc4dd61a72ca24694. May 8 00:45:05.216738 systemd[1]: cri-containerd-5b09511a0c972ba43aedd6af9dd2c52e13c136aede85009fc4dd61a72ca24694.scope: Deactivated successfully. May 8 00:45:05.218582 containerd[1448]: time="2025-05-08T00:45:05.218537762Z" level=info msg="StartContainer for \"5b09511a0c972ba43aedd6af9dd2c52e13c136aede85009fc4dd61a72ca24694\" returns successfully" May 8 00:45:05.236159 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-5b09511a0c972ba43aedd6af9dd2c52e13c136aede85009fc4dd61a72ca24694-rootfs.mount: Deactivated successfully. May 8 00:45:05.301539 kubelet[1714]: I0508 00:45:05.301475 1714 kubelet_node_status.go:502] "Fast updating node status as it just became ready" May 8 00:45:05.431399 kubelet[1714]: E0508 00:45:05.431266 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:05.535602 containerd[1448]: time="2025-05-08T00:45:05.535533686Z" level=info msg="shim disconnected" id=5b09511a0c972ba43aedd6af9dd2c52e13c136aede85009fc4dd61a72ca24694 namespace=k8s.io May 8 00:45:05.535602 containerd[1448]: time="2025-05-08T00:45:05.535594971Z" level=warning msg="cleaning up after shim disconnected" id=5b09511a0c972ba43aedd6af9dd2c52e13c136aede85009fc4dd61a72ca24694 namespace=k8s.io May 8 00:45:05.535602 containerd[1448]: time="2025-05-08T00:45:05.535605541Z" level=info msg="cleaning up dead shim" namespace=k8s.io May 8 00:45:05.599893 kubelet[1714]: E0508 00:45:05.599863 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:45:05.601340 containerd[1448]: time="2025-05-08T00:45:05.601302810Z" level=info msg="CreateContainer within sandbox \"8d97b10ce7704689c5879a3e94ea10815a71693975d6d4b582b3ae12d8fbc620\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" May 8 00:45:05.616285 containerd[1448]: time="2025-05-08T00:45:05.616224629Z" level=info msg="CreateContainer within sandbox \"8d97b10ce7704689c5879a3e94ea10815a71693975d6d4b582b3ae12d8fbc620\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"247248d85b55d0027ef052349d29bda4e02b33dd59384fb6b27624f9d0294ad4\"" May 8 00:45:05.617009 containerd[1448]: time="2025-05-08T00:45:05.616758370Z" level=info msg="StartContainer for \"247248d85b55d0027ef052349d29bda4e02b33dd59384fb6b27624f9d0294ad4\"" May 8 00:45:05.645526 systemd[1]: Started cri-containerd-247248d85b55d0027ef052349d29bda4e02b33dd59384fb6b27624f9d0294ad4.scope - libcontainer container 247248d85b55d0027ef052349d29bda4e02b33dd59384fb6b27624f9d0294ad4. May 8 00:45:05.672939 containerd[1448]: time="2025-05-08T00:45:05.672885920Z" level=info msg="StartContainer for \"247248d85b55d0027ef052349d29bda4e02b33dd59384fb6b27624f9d0294ad4\" returns successfully" May 8 00:45:06.431868 kubelet[1714]: E0508 00:45:06.431812 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:06.603044 kubelet[1714]: E0508 00:45:06.603016 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:45:06.612528 kubelet[1714]: I0508 00:45:06.612468 1714 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-bl5zj" podStartSLOduration=6.075517369 podStartE2EDuration="16.612450043s" podCreationTimestamp="2025-05-08 00:44:50 +0000 UTC" firstStartedPulling="2025-05-08 00:44:54.580121985 +0000 UTC m=+4.638705707" lastFinishedPulling="2025-05-08 00:45:05.117054669 +0000 UTC m=+15.175638381" observedRunningTime="2025-05-08 00:45:06.612339376 +0000 UTC m=+16.670923108" watchObservedRunningTime="2025-05-08 00:45:06.612450043 +0000 UTC m=+16.671033765" May 8 00:45:06.762161 systemd-networkd[1377]: flannel.1: Link UP May 8 00:45:06.762501 systemd-networkd[1377]: flannel.1: Gained carrier May 8 00:45:07.433016 kubelet[1714]: E0508 00:45:07.432961 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:07.541984 systemd[1]: Created slice kubepods-besteffort-poddf8dfed0_b04b_4040_b3d4_934881407738.slice - libcontainer container kubepods-besteffort-poddf8dfed0_b04b_4040_b3d4_934881407738.slice. May 8 00:45:07.602091 kubelet[1714]: I0508 00:45:07.602012 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-s4pqq\" (UniqueName: \"kubernetes.io/projected/df8dfed0-b04b-4040-b3d4-934881407738-kube-api-access-s4pqq\") pod \"nginx-deployment-7fcdb87857-htgfq\" (UID: \"df8dfed0-b04b-4040-b3d4-934881407738\") " pod="default/nginx-deployment-7fcdb87857-htgfq" May 8 00:45:07.604516 kubelet[1714]: E0508 00:45:07.604479 1714 dns.go:153] "Nameserver limits exceeded" err="Nameserver limits were exceeded, some nameservers have been omitted, the applied nameserver line is: 1.1.1.1 1.0.0.1 8.8.8.8" May 8 00:45:07.845194 containerd[1448]: time="2025-05-08T00:45:07.845135769Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-htgfq,Uid:df8dfed0-b04b-4040-b3d4-934881407738,Namespace:default,Attempt:0,}" May 8 00:45:07.864983 systemd-networkd[1377]: cni0: Link UP May 8 00:45:07.865318 systemd-networkd[1377]: cni0: Gained carrier May 8 00:45:07.865758 systemd-networkd[1377]: cni0: Lost carrier May 8 00:45:07.876338 systemd-networkd[1377]: vethb2b38434: Link UP May 8 00:45:07.878458 kernel: cni0: port 1(vethb2b38434) entered blocking state May 8 00:45:07.878512 kernel: cni0: port 1(vethb2b38434) entered disabled state May 8 00:45:07.878532 kernel: vethb2b38434: entered allmulticast mode May 8 00:45:07.880048 kernel: vethb2b38434: entered promiscuous mode May 8 00:45:07.881157 kernel: cni0: port 1(vethb2b38434) entered blocking state May 8 00:45:07.881192 kernel: cni0: port 1(vethb2b38434) entered forwarding state May 8 00:45:07.883032 kernel: cni0: port 1(vethb2b38434) entered disabled state May 8 00:45:07.888380 kernel: cni0: port 1(vethb2b38434) entered blocking state May 8 00:45:07.888428 kernel: cni0: port 1(vethb2b38434) entered forwarding state May 8 00:45:07.888526 systemd-networkd[1377]: vethb2b38434: Gained carrier May 8 00:45:07.888841 systemd-networkd[1377]: cni0: Gained carrier May 8 00:45:07.891246 containerd[1448]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00011c8e8), "name":"cbr0", "type":"bridge"} May 8 00:45:07.891246 containerd[1448]: delegateAdd: netconf sent to delegate plugin: May 8 00:45:07.915655 containerd[1448]: {"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-08T00:45:07.914783609Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:45:07.915655 containerd[1448]: time="2025-05-08T00:45:07.915619817Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:45:07.915655 containerd[1448]: time="2025-05-08T00:45:07.915635948Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:45:07.915947 containerd[1448]: time="2025-05-08T00:45:07.915738761Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:45:07.939480 systemd[1]: Started cri-containerd-88ec58880881d4f0726fc4e9926f2368e5c4c4b36d4b7ff8dca2003f03cbef73.scope - libcontainer container 88ec58880881d4f0726fc4e9926f2368e5c4c4b36d4b7ff8dca2003f03cbef73. May 8 00:45:07.957292 systemd-resolved[1328]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 8 00:45:07.994178 containerd[1448]: time="2025-05-08T00:45:07.994127996Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-htgfq,Uid:df8dfed0-b04b-4040-b3d4-934881407738,Namespace:default,Attempt:0,} returns sandbox id \"88ec58880881d4f0726fc4e9926f2368e5c4c4b36d4b7ff8dca2003f03cbef73\"" May 8 00:45:07.995214 containerd[1448]: time="2025-05-08T00:45:07.995192212Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 8 00:45:08.433722 kubelet[1714]: E0508 00:45:08.433646 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:08.550504 systemd-networkd[1377]: flannel.1: Gained IPv6LL May 8 00:45:09.126527 systemd-networkd[1377]: cni0: Gained IPv6LL May 8 00:45:09.382515 systemd-networkd[1377]: vethb2b38434: Gained IPv6LL May 8 00:45:09.434784 kubelet[1714]: E0508 00:45:09.434734 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:10.419654 kubelet[1714]: E0508 00:45:10.419582 1714 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:10.435322 kubelet[1714]: E0508 00:45:10.435270 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:11.014816 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3159083314.mount: Deactivated successfully. May 8 00:45:11.436603 kubelet[1714]: E0508 00:45:11.436385 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:12.240849 containerd[1448]: time="2025-05-08T00:45:12.240796052Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:45:12.241655 containerd[1448]: time="2025-05-08T00:45:12.241616260Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73306220" May 8 00:45:12.242942 containerd[1448]: time="2025-05-08T00:45:12.242910568Z" level=info msg="ImageCreate event name:\"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:45:12.245670 containerd[1448]: time="2025-05-08T00:45:12.245632333Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:beabce8f1782671ba500ddff99dd260fbf9c5ec85fb9c3162e35a3c40bafd023\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:45:12.246589 containerd[1448]: time="2025-05-08T00:45:12.246561526Z" 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 4.251341482s" May 8 00:45:12.246640 containerd[1448]: time="2025-05-08T00:45:12.246590030Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 8 00:45:12.248473 containerd[1448]: time="2025-05-08T00:45:12.248442434Z" level=info msg="CreateContainer within sandbox \"88ec58880881d4f0726fc4e9926f2368e5c4c4b36d4b7ff8dca2003f03cbef73\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" May 8 00:45:12.276077 containerd[1448]: time="2025-05-08T00:45:12.276034820Z" level=info msg="CreateContainer within sandbox \"88ec58880881d4f0726fc4e9926f2368e5c4c4b36d4b7ff8dca2003f03cbef73\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"3028d624ed7a60d3897780a1787f22a121e177390f6ea7ddb9a250d4de29e2eb\"" May 8 00:45:12.276549 containerd[1448]: time="2025-05-08T00:45:12.276511784Z" level=info msg="StartContainer for \"3028d624ed7a60d3897780a1787f22a121e177390f6ea7ddb9a250d4de29e2eb\"" May 8 00:45:12.317502 systemd[1]: Started cri-containerd-3028d624ed7a60d3897780a1787f22a121e177390f6ea7ddb9a250d4de29e2eb.scope - libcontainer container 3028d624ed7a60d3897780a1787f22a121e177390f6ea7ddb9a250d4de29e2eb. May 8 00:45:12.356316 containerd[1448]: time="2025-05-08T00:45:12.356276620Z" level=info msg="StartContainer for \"3028d624ed7a60d3897780a1787f22a121e177390f6ea7ddb9a250d4de29e2eb\" returns successfully" May 8 00:45:12.436856 kubelet[1714]: E0508 00:45:12.436782 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:12.626069 kubelet[1714]: I0508 00:45:12.625886 1714 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-htgfq" podStartSLOduration=1.3733835810000001 podStartE2EDuration="5.625865612s" podCreationTimestamp="2025-05-08 00:45:07 +0000 UTC" firstStartedPulling="2025-05-08 00:45:07.994946351 +0000 UTC m=+18.053530073" lastFinishedPulling="2025-05-08 00:45:12.247428392 +0000 UTC m=+22.306012104" observedRunningTime="2025-05-08 00:45:12.625694311 +0000 UTC m=+22.684278033" watchObservedRunningTime="2025-05-08 00:45:12.625865612 +0000 UTC m=+22.684449344" May 8 00:45:13.437831 kubelet[1714]: E0508 00:45:13.437784 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:14.438298 kubelet[1714]: E0508 00:45:14.438250 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:14.741579 systemd[1]: Created slice kubepods-besteffort-pod97946170_88de_42d6_b8a8_5919e3d0c48f.slice - libcontainer container kubepods-besteffort-pod97946170_88de_42d6_b8a8_5919e3d0c48f.slice. May 8 00:45:14.781083 kubelet[1714]: I0508 00:45:14.781018 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/97946170-88de-42d6-b8a8-5919e3d0c48f-data\") pod \"nfs-server-provisioner-0\" (UID: \"97946170-88de-42d6-b8a8-5919e3d0c48f\") " pod="default/nfs-server-provisioner-0" May 8 00:45:14.881666 kubelet[1714]: I0508 00:45:14.881577 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-lg566\" (UniqueName: \"kubernetes.io/projected/97946170-88de-42d6-b8a8-5919e3d0c48f-kube-api-access-lg566\") pod \"nfs-server-provisioner-0\" (UID: \"97946170-88de-42d6-b8a8-5919e3d0c48f\") " pod="default/nfs-server-provisioner-0" May 8 00:45:15.045129 containerd[1448]: time="2025-05-08T00:45:15.044988495Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:97946170-88de-42d6-b8a8-5919e3d0c48f,Namespace:default,Attempt:0,}" May 8 00:45:15.070263 systemd-networkd[1377]: vethdd99c16c: Link UP May 8 00:45:15.074396 kernel: cni0: port 2(vethdd99c16c) entered blocking state May 8 00:45:15.074462 kernel: cni0: port 2(vethdd99c16c) entered disabled state May 8 00:45:15.074497 kernel: vethdd99c16c: entered allmulticast mode May 8 00:45:15.074522 kernel: vethdd99c16c: entered promiscuous mode May 8 00:45:15.087398 kernel: cni0: port 2(vethdd99c16c) entered blocking state May 8 00:45:15.087505 kernel: cni0: port 2(vethdd99c16c) entered forwarding state May 8 00:45:15.086614 systemd-networkd[1377]: vethdd99c16c: Gained carrier May 8 00:45:15.090549 containerd[1448]: 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)(0xc00001c938), "name":"cbr0", "type":"bridge"} May 8 00:45:15.090549 containerd[1448]: delegateAdd: netconf sent to delegate plugin: May 8 00:45:15.339049 containerd[1448]: {"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-08T00:45:15.338847180Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:45:15.339049 containerd[1448]: time="2025-05-08T00:45:15.338909176Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:45:15.339049 containerd[1448]: time="2025-05-08T00:45:15.338919646Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:45:15.339049 containerd[1448]: time="2025-05-08T00:45:15.338993585Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:45:15.363522 systemd[1]: Started cri-containerd-5c9e02f9843f9178c74ee7347dd8f16332f0d58f0dd5a79a31118fec689922ce.scope - libcontainer container 5c9e02f9843f9178c74ee7347dd8f16332f0d58f0dd5a79a31118fec689922ce. May 8 00:45:15.376158 systemd-resolved[1328]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 8 00:45:15.403395 containerd[1448]: time="2025-05-08T00:45:15.403326315Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:97946170-88de-42d6-b8a8-5919e3d0c48f,Namespace:default,Attempt:0,} returns sandbox id \"5c9e02f9843f9178c74ee7347dd8f16332f0d58f0dd5a79a31118fec689922ce\"" May 8 00:45:15.405034 containerd[1448]: time="2025-05-08T00:45:15.404939160Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" May 8 00:45:15.438755 kubelet[1714]: E0508 00:45:15.438678 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:16.439673 kubelet[1714]: E0508 00:45:16.439608 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:16.614608 systemd-networkd[1377]: vethdd99c16c: Gained IPv6LL May 8 00:45:17.440720 kubelet[1714]: E0508 00:45:17.440608 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:18.441453 kubelet[1714]: E0508 00:45:18.441389 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:18.639499 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3936337963.mount: Deactivated successfully. May 8 00:45:19.442197 kubelet[1714]: E0508 00:45:19.442126 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:20.442937 kubelet[1714]: E0508 00:45:20.442873 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:21.443790 kubelet[1714]: E0508 00:45:21.443736 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:22.444376 kubelet[1714]: E0508 00:45:22.444320 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:23.189510 containerd[1448]: time="2025-05-08T00:45:23.189450684Z" 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 8 00:45:23.190791 containerd[1448]: time="2025-05-08T00:45:23.190748934Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039406" May 8 00:45:23.193545 containerd[1448]: time="2025-05-08T00:45:23.193514665Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:45:23.197683 containerd[1448]: time="2025-05-08T00:45:23.197639021Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:45:23.198832 containerd[1448]: time="2025-05-08T00:45:23.198764442Z" 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 7.793779857s" May 8 00:45:23.198891 containerd[1448]: time="2025-05-08T00:45:23.198838393Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" May 8 00:45:23.201335 containerd[1448]: time="2025-05-08T00:45:23.201306087Z" level=info msg="CreateContainer within sandbox \"5c9e02f9843f9178c74ee7347dd8f16332f0d58f0dd5a79a31118fec689922ce\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" May 8 00:45:23.270539 containerd[1448]: time="2025-05-08T00:45:23.270491885Z" level=info msg="CreateContainer within sandbox \"5c9e02f9843f9178c74ee7347dd8f16332f0d58f0dd5a79a31118fec689922ce\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"272adda0019ab5d3776477cc1942f1e5cf58df7bc2ded32a7487efb1cd18ddbc\"" May 8 00:45:23.271010 containerd[1448]: time="2025-05-08T00:45:23.270977288Z" level=info msg="StartContainer for \"272adda0019ab5d3776477cc1942f1e5cf58df7bc2ded32a7487efb1cd18ddbc\"" May 8 00:45:23.299491 systemd[1]: Started cri-containerd-272adda0019ab5d3776477cc1942f1e5cf58df7bc2ded32a7487efb1cd18ddbc.scope - libcontainer container 272adda0019ab5d3776477cc1942f1e5cf58df7bc2ded32a7487efb1cd18ddbc. May 8 00:45:23.338282 containerd[1448]: time="2025-05-08T00:45:23.338233986Z" level=info msg="StartContainer for \"272adda0019ab5d3776477cc1942f1e5cf58df7bc2ded32a7487efb1cd18ddbc\" returns successfully" May 8 00:45:23.444695 kubelet[1714]: E0508 00:45:23.444570 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:24.445332 kubelet[1714]: E0508 00:45:24.445242 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:25.445823 kubelet[1714]: E0508 00:45:25.445744 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:26.446722 kubelet[1714]: E0508 00:45:26.446667 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:27.447739 kubelet[1714]: E0508 00:45:27.447679 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:28.448277 kubelet[1714]: E0508 00:45:28.448231 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:29.448836 kubelet[1714]: E0508 00:45:29.448771 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:29.941068 update_engine[1434]: I20250508 00:45:29.940948 1434 update_attempter.cc:509] Updating boot flags... May 8 00:45:29.966393 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2755) May 8 00:45:30.013445 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2758) May 8 00:45:30.041409 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 39 scanned by (udev-worker) (2758) May 8 00:45:30.420400 kubelet[1714]: E0508 00:45:30.420308 1714 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:30.449893 kubelet[1714]: E0508 00:45:30.449852 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:31.450686 kubelet[1714]: E0508 00:45:31.450611 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:32.450835 kubelet[1714]: E0508 00:45:32.450754 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:33.347154 kubelet[1714]: I0508 00:45:33.347077 1714 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=11.551776937 podStartE2EDuration="19.347058421s" podCreationTimestamp="2025-05-08 00:45:14 +0000 UTC" firstStartedPulling="2025-05-08 00:45:15.40465647 +0000 UTC m=+25.463240192" lastFinishedPulling="2025-05-08 00:45:23.199937954 +0000 UTC m=+33.258521676" observedRunningTime="2025-05-08 00:45:23.676406369 +0000 UTC m=+33.734990111" watchObservedRunningTime="2025-05-08 00:45:33.347058421 +0000 UTC m=+43.405642143" May 8 00:45:33.353276 systemd[1]: Created slice kubepods-besteffort-pod7eab0f48_337d_4de9_aa11_4e327eef3e0d.slice - libcontainer container kubepods-besteffort-pod7eab0f48_337d_4de9_aa11_4e327eef3e0d.slice. May 8 00:45:33.451212 kubelet[1714]: E0508 00:45:33.451163 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:33.506546 kubelet[1714]: I0508 00:45:33.506515 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9mx72\" (UniqueName: \"kubernetes.io/projected/7eab0f48-337d-4de9-aa11-4e327eef3e0d-kube-api-access-9mx72\") pod \"test-pod-1\" (UID: \"7eab0f48-337d-4de9-aa11-4e327eef3e0d\") " pod="default/test-pod-1" May 8 00:45:33.506626 kubelet[1714]: I0508 00:45:33.506551 1714 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-9dccf4ab-6599-4e20-a564-2799df5210f7\" (UniqueName: \"kubernetes.io/nfs/7eab0f48-337d-4de9-aa11-4e327eef3e0d-pvc-9dccf4ab-6599-4e20-a564-2799df5210f7\") pod \"test-pod-1\" (UID: \"7eab0f48-337d-4de9-aa11-4e327eef3e0d\") " pod="default/test-pod-1" May 8 00:45:33.629384 kernel: FS-Cache: Loaded May 8 00:45:33.697425 kernel: RPC: Registered named UNIX socket transport module. May 8 00:45:33.697530 kernel: RPC: Registered udp transport module. May 8 00:45:33.697557 kernel: RPC: Registered tcp transport module. May 8 00:45:33.698577 kernel: RPC: Registered tcp-with-tls transport module. May 8 00:45:33.698597 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 8 00:45:33.965651 kernel: NFS: Registering the id_resolver key type May 8 00:45:33.965784 kernel: Key type id_resolver registered May 8 00:45:33.965818 kernel: Key type id_legacy registered May 8 00:45:33.992774 nfsidmap[2800]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 8 00:45:33.997232 nfsidmap[2803]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'localdomain' May 8 00:45:34.256558 containerd[1448]: time="2025-05-08T00:45:34.256423278Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:7eab0f48-337d-4de9-aa11-4e327eef3e0d,Namespace:default,Attempt:0,}" May 8 00:45:34.628518 kubelet[1714]: E0508 00:45:34.628219 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:34.642963 systemd-networkd[1377]: veth62ea6862: Link UP May 8 00:45:34.645015 kernel: cni0: port 3(veth62ea6862) entered blocking state May 8 00:45:34.645162 kernel: cni0: port 3(veth62ea6862) entered disabled state May 8 00:45:34.645188 kernel: veth62ea6862: entered allmulticast mode May 8 00:45:34.646376 kernel: veth62ea6862: entered promiscuous mode May 8 00:45:34.654623 kernel: cni0: port 3(veth62ea6862) entered blocking state May 8 00:45:34.654684 kernel: cni0: port 3(veth62ea6862) entered forwarding state May 8 00:45:34.654903 systemd-networkd[1377]: veth62ea6862: Gained carrier May 8 00:45:34.656225 containerd[1448]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000ae8e8), "name":"cbr0", "type":"bridge"} May 8 00:45:34.656225 containerd[1448]: delegateAdd: netconf sent to delegate plugin: May 8 00:45:34.683180 containerd[1448]: {"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-08T00:45:34.683010374Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 8 00:45:34.683180 containerd[1448]: time="2025-05-08T00:45:34.683155448Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 8 00:45:34.683434 containerd[1448]: time="2025-05-08T00:45:34.683175476Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:45:34.683434 containerd[1448]: time="2025-05-08T00:45:34.683256910Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 8 00:45:34.913492 systemd[1]: run-containerd-runc-k8s.io-b76bb29e0a267dc065b9b1cccb71e11e1c61e691853e4f1ae3a736457ada9818-runc.tlyV2b.mount: Deactivated successfully. May 8 00:45:34.922473 systemd[1]: Started cri-containerd-b76bb29e0a267dc065b9b1cccb71e11e1c61e691853e4f1ae3a736457ada9818.scope - libcontainer container b76bb29e0a267dc065b9b1cccb71e11e1c61e691853e4f1ae3a736457ada9818. May 8 00:45:34.942781 systemd-resolved[1328]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address May 8 00:45:34.986298 containerd[1448]: time="2025-05-08T00:45:34.986231988Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:7eab0f48-337d-4de9-aa11-4e327eef3e0d,Namespace:default,Attempt:0,} returns sandbox id \"b76bb29e0a267dc065b9b1cccb71e11e1c61e691853e4f1ae3a736457ada9818\"" May 8 00:45:34.987562 containerd[1448]: time="2025-05-08T00:45:34.987532224Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" May 8 00:45:35.396063 containerd[1448]: time="2025-05-08T00:45:35.395993262Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" May 8 00:45:35.396805 containerd[1448]: time="2025-05-08T00:45:35.396763157Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" May 8 00:45:35.399647 containerd[1448]: time="2025-05-08T00:45:35.399606625Z" 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 412.035629ms" May 8 00:45:35.399647 containerd[1448]: time="2025-05-08T00:45:35.399641030Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:7e2dd24abce21cd256091445aca4b7eb00774264c2b0a8714701dd7091509efa\"" May 8 00:45:35.401545 containerd[1448]: time="2025-05-08T00:45:35.401522011Z" level=info msg="CreateContainer within sandbox \"b76bb29e0a267dc065b9b1cccb71e11e1c61e691853e4f1ae3a736457ada9818\" for container &ContainerMetadata{Name:test,Attempt:0,}" May 8 00:45:35.419416 containerd[1448]: time="2025-05-08T00:45:35.419375779Z" level=info msg="CreateContainer within sandbox \"b76bb29e0a267dc065b9b1cccb71e11e1c61e691853e4f1ae3a736457ada9818\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"f633ea2add421451e9f595785fe8a4361b6b78feed339f5f723dd0ca434c2060\"" May 8 00:45:35.419918 containerd[1448]: time="2025-05-08T00:45:35.419856747Z" level=info msg="StartContainer for \"f633ea2add421451e9f595785fe8a4361b6b78feed339f5f723dd0ca434c2060\"" May 8 00:45:35.446480 systemd[1]: Started cri-containerd-f633ea2add421451e9f595785fe8a4361b6b78feed339f5f723dd0ca434c2060.scope - libcontainer container f633ea2add421451e9f595785fe8a4361b6b78feed339f5f723dd0ca434c2060. May 8 00:45:35.471645 containerd[1448]: time="2025-05-08T00:45:35.471606541Z" level=info msg="StartContainer for \"f633ea2add421451e9f595785fe8a4361b6b78feed339f5f723dd0ca434c2060\" returns successfully" May 8 00:45:35.629254 kubelet[1714]: E0508 00:45:35.629216 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:35.699414 kubelet[1714]: I0508 00:45:35.699302 1714 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=21.28606229 podStartE2EDuration="21.699288656s" podCreationTimestamp="2025-05-08 00:45:14 +0000 UTC" firstStartedPulling="2025-05-08 00:45:34.987151935 +0000 UTC m=+45.045735657" lastFinishedPulling="2025-05-08 00:45:35.400378301 +0000 UTC m=+45.458962023" observedRunningTime="2025-05-08 00:45:35.699278266 +0000 UTC m=+45.757861988" watchObservedRunningTime="2025-05-08 00:45:35.699288656 +0000 UTC m=+45.757872378" May 8 00:45:36.629533 kubelet[1714]: E0508 00:45:36.629462 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" May 8 00:45:36.646507 systemd-networkd[1377]: veth62ea6862: Gained IPv6LL May 8 00:45:37.630545 kubelet[1714]: E0508 00:45:37.630471 1714 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"