Apr 18 00:38:22.017987 kernel: Linux version 6.6.127-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 Fri Apr 17 22:11:20 -00 2026 Apr 18 00:38:22.018024 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=e69cfa144bf8cf6f0b7e7881c91c17228ba9dbcb6c99d9692bced9ddba34ee3a Apr 18 00:38:22.018037 kernel: BIOS-provided physical RAM map: Apr 18 00:38:22.018052 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Apr 18 00:38:22.018070 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Apr 18 00:38:22.018079 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Apr 18 00:38:22.018090 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdbfff] usable Apr 18 00:38:22.018112 kernel: BIOS-e820: [mem 0x000000007ffdc000-0x000000007fffffff] reserved Apr 18 00:38:22.018121 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Apr 18 00:38:22.018132 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Apr 18 00:38:22.018142 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Apr 18 00:38:22.018151 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Apr 18 00:38:22.018177 kernel: NX (Execute Disable) protection: active Apr 18 00:38:22.018186 kernel: APIC: Static calls initialized Apr 18 00:38:22.018197 kernel: SMBIOS 2.8 present. Apr 18 00:38:22.018207 kernel: DMI: Red Hat KVM/RHEL-AV, BIOS 1.13.0-2.module_el8.5.0+2608+72063365 04/01/2014 Apr 18 00:38:22.018220 kernel: Hypervisor detected: KVM Apr 18 00:38:22.018234 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Apr 18 00:38:22.018256 kernel: kvm-clock: using sched offset of 4422840805 cycles Apr 18 00:38:22.018266 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Apr 18 00:38:22.018283 kernel: tsc: Detected 2799.998 MHz processor Apr 18 00:38:22.018293 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Apr 18 00:38:22.018303 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Apr 18 00:38:22.018312 kernel: last_pfn = 0x7ffdc max_arch_pfn = 0x400000000 Apr 18 00:38:22.018322 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Apr 18 00:38:22.018331 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Apr 18 00:38:22.018345 kernel: Using GB pages for direct mapping Apr 18 00:38:22.018355 kernel: ACPI: Early table checksum verification disabled Apr 18 00:38:22.018364 kernel: ACPI: RSDP 0x00000000000F5AA0 000014 (v00 BOCHS ) Apr 18 00:38:22.018374 kernel: ACPI: RSDT 0x000000007FFE47A5 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 18 00:38:22.018383 kernel: ACPI: FACP 0x000000007FFE438D 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Apr 18 00:38:22.018427 kernel: ACPI: DSDT 0x000000007FFDFD80 00460D (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 18 00:38:22.018438 kernel: ACPI: FACS 0x000000007FFDFD40 000040 Apr 18 00:38:22.018449 kernel: ACPI: APIC 0x000000007FFE4481 0000F0 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 18 00:38:22.018460 kernel: ACPI: SRAT 0x000000007FFE4571 0001D0 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 18 00:38:22.018476 kernel: ACPI: MCFG 0x000000007FFE4741 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 18 00:38:22.018487 kernel: ACPI: WAET 0x000000007FFE477D 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Apr 18 00:38:22.018498 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe438d-0x7ffe4480] Apr 18 00:38:22.018509 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffdfd80-0x7ffe438c] Apr 18 00:38:22.018520 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffdfd40-0x7ffdfd7f] Apr 18 00:38:22.018538 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe4481-0x7ffe4570] Apr 18 00:38:22.018549 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe4571-0x7ffe4740] Apr 18 00:38:22.018565 kernel: ACPI: Reserving MCFG table memory at [mem 0x7ffe4741-0x7ffe477c] Apr 18 00:38:22.018576 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe477d-0x7ffe47a4] Apr 18 00:38:22.018588 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Apr 18 00:38:22.018599 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Apr 18 00:38:22.018610 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Apr 18 00:38:22.018621 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Apr 18 00:38:22.018633 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Apr 18 00:38:22.018644 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Apr 18 00:38:22.018659 kernel: SRAT: PXM 0 -> APIC 0x06 -> Node 0 Apr 18 00:38:22.018671 kernel: SRAT: PXM 0 -> APIC 0x07 -> Node 0 Apr 18 00:38:22.018692 kernel: SRAT: PXM 0 -> APIC 0x08 -> Node 0 Apr 18 00:38:22.018742 kernel: SRAT: PXM 0 -> APIC 0x09 -> Node 0 Apr 18 00:38:22.018753 kernel: SRAT: PXM 0 -> APIC 0x0a -> Node 0 Apr 18 00:38:22.018777 kernel: SRAT: PXM 0 -> APIC 0x0b -> Node 0 Apr 18 00:38:22.018795 kernel: SRAT: PXM 0 -> APIC 0x0c -> Node 0 Apr 18 00:38:22.018806 kernel: SRAT: PXM 0 -> APIC 0x0d -> Node 0 Apr 18 00:38:22.018817 kernel: SRAT: PXM 0 -> APIC 0x0e -> Node 0 Apr 18 00:38:22.018834 kernel: SRAT: PXM 0 -> APIC 0x0f -> Node 0 Apr 18 00:38:22.018846 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Apr 18 00:38:22.018857 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] Apr 18 00:38:22.018869 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x20800fffff] hotplug Apr 18 00:38:22.018880 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0x7ffdbfff] -> [mem 0x00000000-0x7ffdbfff] Apr 18 00:38:22.018892 kernel: NODE_DATA(0) allocated [mem 0x7ffd6000-0x7ffdbfff] Apr 18 00:38:22.018903 kernel: Zone ranges: Apr 18 00:38:22.018918 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Apr 18 00:38:22.018930 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdbfff] Apr 18 00:38:22.018941 kernel: Normal empty Apr 18 00:38:22.018957 kernel: Movable zone start for each node Apr 18 00:38:22.018968 kernel: Early memory node ranges Apr 18 00:38:22.018980 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Apr 18 00:38:22.018991 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdbfff] Apr 18 00:38:22.019002 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff] Apr 18 00:38:22.019014 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Apr 18 00:38:22.019025 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Apr 18 00:38:22.019036 kernel: On node 0, zone DMA32: 36 pages in unavailable ranges Apr 18 00:38:22.019047 kernel: ACPI: PM-Timer IO Port: 0x608 Apr 18 00:38:22.019063 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Apr 18 00:38:22.019075 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Apr 18 00:38:22.019086 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Apr 18 00:38:22.019099 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Apr 18 00:38:22.019110 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Apr 18 00:38:22.019133 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Apr 18 00:38:22.019144 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Apr 18 00:38:22.019155 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Apr 18 00:38:22.019165 kernel: TSC deadline timer available Apr 18 00:38:22.019180 kernel: smpboot: Allowing 16 CPUs, 14 hotplug CPUs Apr 18 00:38:22.019191 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Apr 18 00:38:22.019224 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Apr 18 00:38:22.019235 kernel: Booting paravirtualized kernel on KVM Apr 18 00:38:22.019246 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Apr 18 00:38:22.019257 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:16 nr_cpu_ids:16 nr_node_ids:1 Apr 18 00:38:22.019281 kernel: percpu: Embedded 57 pages/cpu s196328 r8192 d28952 u262144 Apr 18 00:38:22.019292 kernel: pcpu-alloc: s196328 r8192 d28952 u262144 alloc=1*2097152 Apr 18 00:38:22.019304 kernel: pcpu-alloc: [0] 00 01 02 03 04 05 06 07 [0] 08 09 10 11 12 13 14 15 Apr 18 00:38:22.019320 kernel: kvm-guest: PV spinlocks enabled Apr 18 00:38:22.019331 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Apr 18 00:38:22.019344 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=e69cfa144bf8cf6f0b7e7881c91c17228ba9dbcb6c99d9692bced9ddba34ee3a Apr 18 00:38:22.019356 kernel: random: crng init done Apr 18 00:38:22.019367 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 18 00:38:22.019397 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Apr 18 00:38:22.019418 kernel: Fallback order for Node 0: 0 Apr 18 00:38:22.019442 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515804 Apr 18 00:38:22.019459 kernel: Policy zone: DMA32 Apr 18 00:38:22.019471 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 18 00:38:22.019482 kernel: software IO TLB: area num 16. Apr 18 00:38:22.019494 kernel: Memory: 1901592K/2096616K available (12288K kernel code, 2288K rwdata, 22752K rodata, 42892K init, 2304K bss, 194764K reserved, 0K cma-reserved) Apr 18 00:38:22.019505 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=16, Nodes=1 Apr 18 00:38:22.019517 kernel: Kernel/User page tables isolation: enabled Apr 18 00:38:22.019528 kernel: ftrace: allocating 37996 entries in 149 pages Apr 18 00:38:22.019539 kernel: ftrace: allocated 149 pages with 4 groups Apr 18 00:38:22.019550 kernel: Dynamic Preempt: voluntary Apr 18 00:38:22.019566 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 18 00:38:22.019578 kernel: rcu: RCU event tracing is enabled. Apr 18 00:38:22.019590 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=16. Apr 18 00:38:22.019601 kernel: Trampoline variant of Tasks RCU enabled. Apr 18 00:38:22.019613 kernel: Rude variant of Tasks RCU enabled. Apr 18 00:38:22.019635 kernel: Tracing variant of Tasks RCU enabled. Apr 18 00:38:22.019651 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 18 00:38:22.019663 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=16 Apr 18 00:38:22.019675 kernel: NR_IRQS: 33024, nr_irqs: 552, preallocated irqs: 16 Apr 18 00:38:22.019687 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Apr 18 00:38:22.019699 kernel: Console: colour VGA+ 80x25 Apr 18 00:38:22.019723 kernel: printk: console [tty0] enabled Apr 18 00:38:22.019754 kernel: printk: console [ttyS0] enabled Apr 18 00:38:22.019766 kernel: ACPI: Core revision 20230628 Apr 18 00:38:22.019778 kernel: APIC: Switch to symmetric I/O mode setup Apr 18 00:38:22.019789 kernel: x2apic enabled Apr 18 00:38:22.019813 kernel: APIC: Switched APIC routing to: physical x2apic Apr 18 00:38:22.019830 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x285c3ee517e, max_idle_ns: 440795257231 ns Apr 18 00:38:22.019842 kernel: Calibrating delay loop (skipped) preset value.. 5599.99 BogoMIPS (lpj=2799998) Apr 18 00:38:22.019854 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Apr 18 00:38:22.019866 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Apr 18 00:38:22.019878 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Apr 18 00:38:22.019890 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Apr 18 00:38:22.019902 kernel: Spectre V2 : Mitigation: Retpolines Apr 18 00:38:22.019914 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Apr 18 00:38:22.019926 kernel: Spectre V2 : Enabling Restricted Speculation for firmware calls Apr 18 00:38:22.019938 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Apr 18 00:38:22.019954 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Apr 18 00:38:22.019966 kernel: MDS: Mitigation: Clear CPU buffers Apr 18 00:38:22.019978 kernel: MMIO Stale Data: Unknown: No mitigations Apr 18 00:38:22.019990 kernel: SRBDS: Unknown: Dependent on hypervisor status Apr 18 00:38:22.020001 kernel: active return thunk: its_return_thunk Apr 18 00:38:22.020013 kernel: ITS: Mitigation: Aligned branch/return thunks Apr 18 00:38:22.020025 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Apr 18 00:38:22.020037 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Apr 18 00:38:22.020049 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Apr 18 00:38:22.020061 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Apr 18 00:38:22.020073 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Apr 18 00:38:22.020089 kernel: Freeing SMP alternatives memory: 32K Apr 18 00:38:22.020101 kernel: pid_max: default: 32768 minimum: 301 Apr 18 00:38:22.020113 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Apr 18 00:38:22.020125 kernel: landlock: Up and running. Apr 18 00:38:22.020149 kernel: SELinux: Initializing. Apr 18 00:38:22.020160 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Apr 18 00:38:22.020171 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Apr 18 00:38:22.020182 kernel: smpboot: CPU0: Intel Xeon E3-12xx v2 (Ivy Bridge, IBRS) (family: 0x6, model: 0x3a, stepping: 0x9) Apr 18 00:38:22.020193 kernel: RCU Tasks: Setting shift to 4 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=16. Apr 18 00:38:22.020204 kernel: RCU Tasks Rude: Setting shift to 4 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=16. Apr 18 00:38:22.020215 kernel: RCU Tasks Trace: Setting shift to 4 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=16. Apr 18 00:38:22.020230 kernel: Performance Events: unsupported p6 CPU model 58 no PMU driver, software events only. Apr 18 00:38:22.020254 kernel: signal: max sigframe size: 1776 Apr 18 00:38:22.020266 kernel: rcu: Hierarchical SRCU implementation. Apr 18 00:38:22.020277 kernel: rcu: Max phase no-delay instances is 400. Apr 18 00:38:22.020289 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Apr 18 00:38:22.020300 kernel: smp: Bringing up secondary CPUs ... Apr 18 00:38:22.020323 kernel: smpboot: x86: Booting SMP configuration: Apr 18 00:38:22.020335 kernel: .... node #0, CPUs: #1 Apr 18 00:38:22.020347 kernel: smpboot: CPU 1 Converting physical 0 to logical die 1 Apr 18 00:38:22.020363 kernel: smp: Brought up 1 node, 2 CPUs Apr 18 00:38:22.020387 kernel: smpboot: Max logical packages: 16 Apr 18 00:38:22.020399 kernel: smpboot: Total of 2 processors activated (11199.99 BogoMIPS) Apr 18 00:38:22.020420 kernel: devtmpfs: initialized Apr 18 00:38:22.020433 kernel: x86/mm: Memory block size: 128MB Apr 18 00:38:22.020445 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 18 00:38:22.020457 kernel: futex hash table entries: 4096 (order: 6, 262144 bytes, linear) Apr 18 00:38:22.020469 kernel: pinctrl core: initialized pinctrl subsystem Apr 18 00:38:22.020481 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 18 00:38:22.020498 kernel: audit: initializing netlink subsys (disabled) Apr 18 00:38:22.020511 kernel: audit: type=2000 audit(1776472700.555:1): state=initialized audit_enabled=0 res=1 Apr 18 00:38:22.020522 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 18 00:38:22.020534 kernel: thermal_sys: Registered thermal governor 'user_space' Apr 18 00:38:22.020546 kernel: cpuidle: using governor menu Apr 18 00:38:22.020558 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 18 00:38:22.020570 kernel: dca service started, version 1.12.1 Apr 18 00:38:22.020582 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Apr 18 00:38:22.020594 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Apr 18 00:38:22.020611 kernel: PCI: Using configuration type 1 for base access Apr 18 00:38:22.020623 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Apr 18 00:38:22.020635 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Apr 18 00:38:22.020647 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Apr 18 00:38:22.020659 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Apr 18 00:38:22.020671 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Apr 18 00:38:22.020683 kernel: ACPI: Added _OSI(Module Device) Apr 18 00:38:22.020695 kernel: ACPI: Added _OSI(Processor Device) Apr 18 00:38:22.020729 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 18 00:38:22.020759 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 18 00:38:22.020772 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Apr 18 00:38:22.020784 kernel: ACPI: Interpreter enabled Apr 18 00:38:22.020796 kernel: ACPI: PM: (supports S0 S5) Apr 18 00:38:22.020808 kernel: ACPI: Using IOAPIC for interrupt routing Apr 18 00:38:22.020820 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Apr 18 00:38:22.020832 kernel: PCI: Using E820 reservations for host bridge windows Apr 18 00:38:22.020843 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Apr 18 00:38:22.020855 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Apr 18 00:38:22.021176 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Apr 18 00:38:22.021378 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Apr 18 00:38:22.021572 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Apr 18 00:38:22.021591 kernel: PCI host bridge to bus 0000:00 Apr 18 00:38:22.021798 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Apr 18 00:38:22.021951 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Apr 18 00:38:22.022111 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Apr 18 00:38:22.022275 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xafffffff window] Apr 18 00:38:22.022483 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Apr 18 00:38:22.022635 kernel: pci_bus 0000:00: root bus resource [mem 0x20c0000000-0x28bfffffff window] Apr 18 00:38:22.022808 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 18 00:38:22.023012 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Apr 18 00:38:22.023221 kernel: pci 0000:00:01.0: [1013:00b8] type 00 class 0x030000 Apr 18 00:38:22.023456 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfa000000-0xfbffffff pref] Apr 18 00:38:22.023623 kernel: pci 0000:00:01.0: reg 0x14: [mem 0xfea50000-0xfea50fff] Apr 18 00:38:22.023853 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfea40000-0xfea4ffff pref] Apr 18 00:38:22.024020 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Apr 18 00:38:22.024228 kernel: pci 0000:00:02.0: [1b36:000c] type 01 class 0x060400 Apr 18 00:38:22.024439 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfea51000-0xfea51fff] Apr 18 00:38:22.024638 kernel: pci 0000:00:02.1: [1b36:000c] type 01 class 0x060400 Apr 18 00:38:22.024871 kernel: pci 0000:00:02.1: reg 0x10: [mem 0xfea52000-0xfea52fff] Apr 18 00:38:22.025101 kernel: pci 0000:00:02.2: [1b36:000c] type 01 class 0x060400 Apr 18 00:38:22.025274 kernel: pci 0000:00:02.2: reg 0x10: [mem 0xfea53000-0xfea53fff] Apr 18 00:38:22.025513 kernel: pci 0000:00:02.3: [1b36:000c] type 01 class 0x060400 Apr 18 00:38:22.025683 kernel: pci 0000:00:02.3: reg 0x10: [mem 0xfea54000-0xfea54fff] Apr 18 00:38:22.025917 kernel: pci 0000:00:02.4: [1b36:000c] type 01 class 0x060400 Apr 18 00:38:22.026092 kernel: pci 0000:00:02.4: reg 0x10: [mem 0xfea55000-0xfea55fff] Apr 18 00:38:22.026297 kernel: pci 0000:00:02.5: [1b36:000c] type 01 class 0x060400 Apr 18 00:38:22.026485 kernel: pci 0000:00:02.5: reg 0x10: [mem 0xfea56000-0xfea56fff] Apr 18 00:38:22.026666 kernel: pci 0000:00:02.6: [1b36:000c] type 01 class 0x060400 Apr 18 00:38:22.026862 kernel: pci 0000:00:02.6: reg 0x10: [mem 0xfea57000-0xfea57fff] Apr 18 00:38:22.027062 kernel: pci 0000:00:02.7: [1b36:000c] type 01 class 0x060400 Apr 18 00:38:22.027242 kernel: pci 0000:00:02.7: reg 0x10: [mem 0xfea58000-0xfea58fff] Apr 18 00:38:22.027467 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Apr 18 00:38:22.027637 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc0c0-0xc0df] Apr 18 00:38:22.027822 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfea59000-0xfea59fff] Apr 18 00:38:22.028020 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfd000000-0xfd003fff 64bit pref] Apr 18 00:38:22.028186 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfea00000-0xfea3ffff pref] Apr 18 00:38:22.028390 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 Apr 18 00:38:22.028595 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] Apr 18 00:38:22.028818 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfea5a000-0xfea5afff] Apr 18 00:38:22.028988 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfd004000-0xfd007fff 64bit pref] Apr 18 00:38:22.029177 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Apr 18 00:38:22.029346 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Apr 18 00:38:22.029561 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Apr 18 00:38:22.029778 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc0e0-0xc0ff] Apr 18 00:38:22.029975 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfea5b000-0xfea5bfff] Apr 18 00:38:22.030174 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Apr 18 00:38:22.030341 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] Apr 18 00:38:22.030545 kernel: pci 0000:01:00.0: [1b36:000e] type 01 class 0x060400 Apr 18 00:38:22.030813 kernel: pci 0000:01:00.0: reg 0x10: [mem 0xfda00000-0xfda000ff 64bit] Apr 18 00:38:22.030992 kernel: pci 0000:00:02.0: PCI bridge to [bus 01-02] Apr 18 00:38:22.031175 kernel: pci 0000:00:02.0: bridge window [mem 0xfd800000-0xfdbfffff] Apr 18 00:38:22.031338 kernel: pci 0000:00:02.0: bridge window [mem 0xfce00000-0xfcffffff 64bit pref] Apr 18 00:38:22.031529 kernel: pci_bus 0000:02: extended config space not accessible Apr 18 00:38:22.031733 kernel: pci 0000:02:01.0: [8086:25ab] type 00 class 0x088000 Apr 18 00:38:22.031923 kernel: pci 0000:02:01.0: reg 0x10: [mem 0xfd800000-0xfd80000f] Apr 18 00:38:22.032130 kernel: pci 0000:01:00.0: PCI bridge to [bus 02] Apr 18 00:38:22.032320 kernel: pci 0000:01:00.0: bridge window [mem 0xfd800000-0xfd9fffff] Apr 18 00:38:22.032526 kernel: pci 0000:03:00.0: [1b36:000d] type 00 class 0x0c0330 Apr 18 00:38:22.032728 kernel: pci 0000:03:00.0: reg 0x10: [mem 0xfe800000-0xfe803fff 64bit] Apr 18 00:38:22.032914 kernel: pci 0000:00:02.1: PCI bridge to [bus 03] Apr 18 00:38:22.033076 kernel: pci 0000:00:02.1: bridge window [mem 0xfe800000-0xfe9fffff] Apr 18 00:38:22.033236 kernel: pci 0000:00:02.1: bridge window [mem 0xfcc00000-0xfcdfffff 64bit pref] Apr 18 00:38:22.033425 kernel: pci 0000:04:00.0: [1af4:1044] type 00 class 0x00ff00 Apr 18 00:38:22.033606 kernel: pci 0000:04:00.0: reg 0x20: [mem 0xfca00000-0xfca03fff 64bit pref] Apr 18 00:38:22.033820 kernel: pci 0000:00:02.2: PCI bridge to [bus 04] Apr 18 00:38:22.033995 kernel: pci 0000:00:02.2: bridge window [mem 0xfe600000-0xfe7fffff] Apr 18 00:38:22.034157 kernel: pci 0000:00:02.2: bridge window [mem 0xfca00000-0xfcbfffff 64bit pref] Apr 18 00:38:22.034347 kernel: pci 0000:00:02.3: PCI bridge to [bus 05] Apr 18 00:38:22.034521 kernel: pci 0000:00:02.3: bridge window [mem 0xfe400000-0xfe5fffff] Apr 18 00:38:22.034685 kernel: pci 0000:00:02.3: bridge window [mem 0xfc800000-0xfc9fffff 64bit pref] Apr 18 00:38:22.035616 kernel: pci 0000:00:02.4: PCI bridge to [bus 06] Apr 18 00:38:22.036117 kernel: pci 0000:00:02.4: bridge window [mem 0xfe200000-0xfe3fffff] Apr 18 00:38:22.036296 kernel: pci 0000:00:02.4: bridge window [mem 0xfc600000-0xfc7fffff 64bit pref] Apr 18 00:38:22.036491 kernel: pci 0000:00:02.5: PCI bridge to [bus 07] Apr 18 00:38:22.036655 kernel: pci 0000:00:02.5: bridge window [mem 0xfe000000-0xfe1fffff] Apr 18 00:38:22.036885 kernel: pci 0000:00:02.5: bridge window [mem 0xfc400000-0xfc5fffff 64bit pref] Apr 18 00:38:22.038728 kernel: pci 0000:00:02.6: PCI bridge to [bus 08] Apr 18 00:38:22.038953 kernel: pci 0000:00:02.6: bridge window [mem 0xfde00000-0xfdffffff] Apr 18 00:38:22.039145 kernel: pci 0000:00:02.6: bridge window [mem 0xfc200000-0xfc3fffff 64bit pref] Apr 18 00:38:22.039331 kernel: pci 0000:00:02.7: PCI bridge to [bus 09] Apr 18 00:38:22.039518 kernel: pci 0000:00:02.7: bridge window [mem 0xfdc00000-0xfddfffff] Apr 18 00:38:22.039689 kernel: pci 0000:00:02.7: bridge window [mem 0xfc000000-0xfc1fffff 64bit pref] Apr 18 00:38:22.039727 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Apr 18 00:38:22.039741 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Apr 18 00:38:22.039754 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Apr 18 00:38:22.039767 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Apr 18 00:38:22.039779 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Apr 18 00:38:22.039799 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Apr 18 00:38:22.039811 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Apr 18 00:38:22.039823 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Apr 18 00:38:22.039836 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Apr 18 00:38:22.039848 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Apr 18 00:38:22.039860 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Apr 18 00:38:22.039872 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Apr 18 00:38:22.039884 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Apr 18 00:38:22.039896 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Apr 18 00:38:22.039914 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Apr 18 00:38:22.039926 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Apr 18 00:38:22.039938 kernel: iommu: Default domain type: Translated Apr 18 00:38:22.039950 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Apr 18 00:38:22.039963 kernel: PCI: Using ACPI for IRQ routing Apr 18 00:38:22.039975 kernel: PCI: pci_cache_line_size set to 64 bytes Apr 18 00:38:22.039987 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Apr 18 00:38:22.039999 kernel: e820: reserve RAM buffer [mem 0x7ffdc000-0x7fffffff] Apr 18 00:38:22.040184 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Apr 18 00:38:22.040355 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Apr 18 00:38:22.040533 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Apr 18 00:38:22.040552 kernel: vgaarb: loaded Apr 18 00:38:22.040565 kernel: clocksource: Switched to clocksource kvm-clock Apr 18 00:38:22.040577 kernel: VFS: Disk quotas dquot_6.6.0 Apr 18 00:38:22.040589 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 18 00:38:22.040601 kernel: pnp: PnP ACPI init Apr 18 00:38:22.041909 kernel: system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved Apr 18 00:38:22.041951 kernel: pnp: PnP ACPI: found 5 devices Apr 18 00:38:22.041963 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Apr 18 00:38:22.041975 kernel: NET: Registered PF_INET protocol family Apr 18 00:38:22.041986 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 18 00:38:22.041998 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Apr 18 00:38:22.042009 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 18 00:38:22.042021 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Apr 18 00:38:22.042032 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Apr 18 00:38:22.042049 kernel: TCP: Hash tables configured (established 16384 bind 16384) Apr 18 00:38:22.042060 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Apr 18 00:38:22.042072 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Apr 18 00:38:22.042082 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 18 00:38:22.042094 kernel: NET: Registered PF_XDP protocol family Apr 18 00:38:22.042264 kernel: pci 0000:00:02.0: bridge window [io 0x1000-0x0fff] to [bus 01-02] add_size 1000 Apr 18 00:38:22.042466 kernel: pci 0000:00:02.1: bridge window [io 0x1000-0x0fff] to [bus 03] add_size 1000 Apr 18 00:38:22.042631 kernel: pci 0000:00:02.2: bridge window [io 0x1000-0x0fff] to [bus 04] add_size 1000 Apr 18 00:38:22.042823 kernel: pci 0000:00:02.3: bridge window [io 0x1000-0x0fff] to [bus 05] add_size 1000 Apr 18 00:38:22.042991 kernel: pci 0000:00:02.4: bridge window [io 0x1000-0x0fff] to [bus 06] add_size 1000 Apr 18 00:38:22.043153 kernel: pci 0000:00:02.5: bridge window [io 0x1000-0x0fff] to [bus 07] add_size 1000 Apr 18 00:38:22.043345 kernel: pci 0000:00:02.6: bridge window [io 0x1000-0x0fff] to [bus 08] add_size 1000 Apr 18 00:38:22.043538 kernel: pci 0000:00:02.7: bridge window [io 0x1000-0x0fff] to [bus 09] add_size 1000 Apr 18 00:38:22.043699 kernel: pci 0000:00:02.0: BAR 13: assigned [io 0x1000-0x1fff] Apr 18 00:38:22.046895 kernel: pci 0000:00:02.1: BAR 13: assigned [io 0x2000-0x2fff] Apr 18 00:38:22.047074 kernel: pci 0000:00:02.2: BAR 13: assigned [io 0x3000-0x3fff] Apr 18 00:38:22.047250 kernel: pci 0000:00:02.3: BAR 13: assigned [io 0x4000-0x4fff] Apr 18 00:38:22.047455 kernel: pci 0000:00:02.4: BAR 13: assigned [io 0x5000-0x5fff] Apr 18 00:38:22.047626 kernel: pci 0000:00:02.5: BAR 13: assigned [io 0x6000-0x6fff] Apr 18 00:38:22.047848 kernel: pci 0000:00:02.6: BAR 13: assigned [io 0x7000-0x7fff] Apr 18 00:38:22.048013 kernel: pci 0000:00:02.7: BAR 13: assigned [io 0x8000-0x8fff] Apr 18 00:38:22.048184 kernel: pci 0000:01:00.0: PCI bridge to [bus 02] Apr 18 00:38:22.048383 kernel: pci 0000:01:00.0: bridge window [mem 0xfd800000-0xfd9fffff] Apr 18 00:38:22.048562 kernel: pci 0000:00:02.0: PCI bridge to [bus 01-02] Apr 18 00:38:22.050761 kernel: pci 0000:00:02.0: bridge window [io 0x1000-0x1fff] Apr 18 00:38:22.050961 kernel: pci 0000:00:02.0: bridge window [mem 0xfd800000-0xfdbfffff] Apr 18 00:38:22.051140 kernel: pci 0000:00:02.0: bridge window [mem 0xfce00000-0xfcffffff 64bit pref] Apr 18 00:38:22.051322 kernel: pci 0000:00:02.1: PCI bridge to [bus 03] Apr 18 00:38:22.051513 kernel: pci 0000:00:02.1: bridge window [io 0x2000-0x2fff] Apr 18 00:38:22.051678 kernel: pci 0000:00:02.1: bridge window [mem 0xfe800000-0xfe9fffff] Apr 18 00:38:22.051995 kernel: pci 0000:00:02.1: bridge window [mem 0xfcc00000-0xfcdfffff 64bit pref] Apr 18 00:38:22.052176 kernel: pci 0000:00:02.2: PCI bridge to [bus 04] Apr 18 00:38:22.052341 kernel: pci 0000:00:02.2: bridge window [io 0x3000-0x3fff] Apr 18 00:38:22.052520 kernel: pci 0000:00:02.2: bridge window [mem 0xfe600000-0xfe7fffff] Apr 18 00:38:22.053730 kernel: pci 0000:00:02.2: bridge window [mem 0xfca00000-0xfcbfffff 64bit pref] Apr 18 00:38:22.053919 kernel: pci 0000:00:02.3: PCI bridge to [bus 05] Apr 18 00:38:22.054091 kernel: pci 0000:00:02.3: bridge window [io 0x4000-0x4fff] Apr 18 00:38:22.054270 kernel: pci 0000:00:02.3: bridge window [mem 0xfe400000-0xfe5fffff] Apr 18 00:38:22.054453 kernel: pci 0000:00:02.3: bridge window [mem 0xfc800000-0xfc9fffff 64bit pref] Apr 18 00:38:22.054624 kernel: pci 0000:00:02.4: PCI bridge to [bus 06] Apr 18 00:38:22.055825 kernel: pci 0000:00:02.4: bridge window [io 0x5000-0x5fff] Apr 18 00:38:22.055998 kernel: pci 0000:00:02.4: bridge window [mem 0xfe200000-0xfe3fffff] Apr 18 00:38:22.056165 kernel: pci 0000:00:02.4: bridge window [mem 0xfc600000-0xfc7fffff 64bit pref] Apr 18 00:38:22.056331 kernel: pci 0000:00:02.5: PCI bridge to [bus 07] Apr 18 00:38:22.056509 kernel: pci 0000:00:02.5: bridge window [io 0x6000-0x6fff] Apr 18 00:38:22.056694 kernel: pci 0000:00:02.5: bridge window [mem 0xfe000000-0xfe1fffff] Apr 18 00:38:22.058152 kernel: pci 0000:00:02.5: bridge window [mem 0xfc400000-0xfc5fffff 64bit pref] Apr 18 00:38:22.058324 kernel: pci 0000:00:02.6: PCI bridge to [bus 08] Apr 18 00:38:22.058506 kernel: pci 0000:00:02.6: bridge window [io 0x7000-0x7fff] Apr 18 00:38:22.058671 kernel: pci 0000:00:02.6: bridge window [mem 0xfde00000-0xfdffffff] Apr 18 00:38:22.059912 kernel: pci 0000:00:02.6: bridge window [mem 0xfc200000-0xfc3fffff 64bit pref] Apr 18 00:38:22.060083 kernel: pci 0000:00:02.7: PCI bridge to [bus 09] Apr 18 00:38:22.060248 kernel: pci 0000:00:02.7: bridge window [io 0x8000-0x8fff] Apr 18 00:38:22.060435 kernel: pci 0000:00:02.7: bridge window [mem 0xfdc00000-0xfddfffff] Apr 18 00:38:22.060600 kernel: pci 0000:00:02.7: bridge window [mem 0xfc000000-0xfc1fffff 64bit pref] Apr 18 00:38:22.062036 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Apr 18 00:38:22.062190 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Apr 18 00:38:22.062338 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Apr 18 00:38:22.062529 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xafffffff window] Apr 18 00:38:22.062688 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Apr 18 00:38:22.062857 kernel: pci_bus 0000:00: resource 9 [mem 0x20c0000000-0x28bfffffff window] Apr 18 00:38:22.063040 kernel: pci_bus 0000:01: resource 0 [io 0x1000-0x1fff] Apr 18 00:38:22.063201 kernel: pci_bus 0000:01: resource 1 [mem 0xfd800000-0xfdbfffff] Apr 18 00:38:22.063359 kernel: pci_bus 0000:01: resource 2 [mem 0xfce00000-0xfcffffff 64bit pref] Apr 18 00:38:22.063545 kernel: pci_bus 0000:02: resource 1 [mem 0xfd800000-0xfd9fffff] Apr 18 00:38:22.065743 kernel: pci_bus 0000:03: resource 0 [io 0x2000-0x2fff] Apr 18 00:38:22.065957 kernel: pci_bus 0000:03: resource 1 [mem 0xfe800000-0xfe9fffff] Apr 18 00:38:22.066119 kernel: pci_bus 0000:03: resource 2 [mem 0xfcc00000-0xfcdfffff 64bit pref] Apr 18 00:38:22.066293 kernel: pci_bus 0000:04: resource 0 [io 0x3000-0x3fff] Apr 18 00:38:22.066474 kernel: pci_bus 0000:04: resource 1 [mem 0xfe600000-0xfe7fffff] Apr 18 00:38:22.066634 kernel: pci_bus 0000:04: resource 2 [mem 0xfca00000-0xfcbfffff 64bit pref] Apr 18 00:38:22.066884 kernel: pci_bus 0000:05: resource 0 [io 0x4000-0x4fff] Apr 18 00:38:22.067048 kernel: pci_bus 0000:05: resource 1 [mem 0xfe400000-0xfe5fffff] Apr 18 00:38:22.067202 kernel: pci_bus 0000:05: resource 2 [mem 0xfc800000-0xfc9fffff 64bit pref] Apr 18 00:38:22.068849 kernel: pci_bus 0000:06: resource 0 [io 0x5000-0x5fff] Apr 18 00:38:22.069082 kernel: pci_bus 0000:06: resource 1 [mem 0xfe200000-0xfe3fffff] Apr 18 00:38:22.069255 kernel: pci_bus 0000:06: resource 2 [mem 0xfc600000-0xfc7fffff 64bit pref] Apr 18 00:38:22.069467 kernel: pci_bus 0000:07: resource 0 [io 0x6000-0x6fff] Apr 18 00:38:22.069637 kernel: pci_bus 0000:07: resource 1 [mem 0xfe000000-0xfe1fffff] Apr 18 00:38:22.069872 kernel: pci_bus 0000:07: resource 2 [mem 0xfc400000-0xfc5fffff 64bit pref] Apr 18 00:38:22.070050 kernel: pci_bus 0000:08: resource 0 [io 0x7000-0x7fff] Apr 18 00:38:22.070282 kernel: pci_bus 0000:08: resource 1 [mem 0xfde00000-0xfdffffff] Apr 18 00:38:22.070497 kernel: pci_bus 0000:08: resource 2 [mem 0xfc200000-0xfc3fffff 64bit pref] Apr 18 00:38:22.072723 kernel: pci_bus 0000:09: resource 0 [io 0x8000-0x8fff] Apr 18 00:38:22.072910 kernel: pci_bus 0000:09: resource 1 [mem 0xfdc00000-0xfddfffff] Apr 18 00:38:22.073075 kernel: pci_bus 0000:09: resource 2 [mem 0xfc000000-0xfc1fffff 64bit pref] Apr 18 00:38:22.073104 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Apr 18 00:38:22.073119 kernel: PCI: CLS 0 bytes, default 64 Apr 18 00:38:22.073133 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Apr 18 00:38:22.073146 kernel: software IO TLB: mapped [mem 0x0000000079800000-0x000000007d800000] (64MB) Apr 18 00:38:22.073159 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Apr 18 00:38:22.073173 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x285c3ee517e, max_idle_ns: 440795257231 ns Apr 18 00:38:22.073186 kernel: Initialise system trusted keyrings Apr 18 00:38:22.073199 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Apr 18 00:38:22.073212 kernel: Key type asymmetric registered Apr 18 00:38:22.073230 kernel: Asymmetric key parser 'x509' registered Apr 18 00:38:22.073243 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Apr 18 00:38:22.073256 kernel: io scheduler mq-deadline registered Apr 18 00:38:22.073269 kernel: io scheduler kyber registered Apr 18 00:38:22.073282 kernel: io scheduler bfq registered Apr 18 00:38:22.073474 kernel: pcieport 0000:00:02.0: PME: Signaling with IRQ 24 Apr 18 00:38:22.073647 kernel: pcieport 0000:00:02.0: AER: enabled with IRQ 24 Apr 18 00:38:22.073840 kernel: pcieport 0000:00:02.0: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Apr 18 00:38:22.074030 kernel: pcieport 0000:00:02.1: PME: Signaling with IRQ 25 Apr 18 00:38:22.074209 kernel: pcieport 0000:00:02.1: AER: enabled with IRQ 25 Apr 18 00:38:22.074375 kernel: pcieport 0000:00:02.1: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Apr 18 00:38:22.074560 kernel: pcieport 0000:00:02.2: PME: Signaling with IRQ 26 Apr 18 00:38:22.076757 kernel: pcieport 0000:00:02.2: AER: enabled with IRQ 26 Apr 18 00:38:22.076962 kernel: pcieport 0000:00:02.2: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Apr 18 00:38:22.077154 kernel: pcieport 0000:00:02.3: PME: Signaling with IRQ 27 Apr 18 00:38:22.077344 kernel: pcieport 0000:00:02.3: AER: enabled with IRQ 27 Apr 18 00:38:22.077540 kernel: pcieport 0000:00:02.3: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Apr 18 00:38:22.077734 kernel: pcieport 0000:00:02.4: PME: Signaling with IRQ 28 Apr 18 00:38:22.077916 kernel: pcieport 0000:00:02.4: AER: enabled with IRQ 28 Apr 18 00:38:22.078104 kernel: pcieport 0000:00:02.4: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Apr 18 00:38:22.078305 kernel: pcieport 0000:00:02.5: PME: Signaling with IRQ 29 Apr 18 00:38:22.078500 kernel: pcieport 0000:00:02.5: AER: enabled with IRQ 29 Apr 18 00:38:22.078681 kernel: pcieport 0000:00:02.5: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Apr 18 00:38:22.081138 kernel: pcieport 0000:00:02.6: PME: Signaling with IRQ 30 Apr 18 00:38:22.081523 kernel: pcieport 0000:00:02.6: AER: enabled with IRQ 30 Apr 18 00:38:22.081940 kernel: pcieport 0000:00:02.6: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Apr 18 00:38:22.082202 kernel: pcieport 0000:00:02.7: PME: Signaling with IRQ 31 Apr 18 00:38:22.082539 kernel: pcieport 0000:00:02.7: AER: enabled with IRQ 31 Apr 18 00:38:22.084755 kernel: pcieport 0000:00:02.7: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Apr 18 00:38:22.084780 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Apr 18 00:38:22.084796 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Apr 18 00:38:22.084809 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Apr 18 00:38:22.084822 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 18 00:38:22.084843 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Apr 18 00:38:22.084856 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Apr 18 00:38:22.084869 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Apr 18 00:38:22.084882 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Apr 18 00:38:22.085095 kernel: rtc_cmos 00:03: RTC can wake from S4 Apr 18 00:38:22.085125 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Apr 18 00:38:22.085293 kernel: rtc_cmos 00:03: registered as rtc0 Apr 18 00:38:22.085468 kernel: rtc_cmos 00:03: setting system clock to 2026-04-18T00:38:21 UTC (1776472701) Apr 18 00:38:22.085634 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Apr 18 00:38:22.085654 kernel: intel_pstate: CPU model not supported Apr 18 00:38:22.085667 kernel: NET: Registered PF_INET6 protocol family Apr 18 00:38:22.085680 kernel: Segment Routing with IPv6 Apr 18 00:38:22.085693 kernel: In-situ OAM (IOAM) with IPv6 Apr 18 00:38:22.085757 kernel: NET: Registered PF_PACKET protocol family Apr 18 00:38:22.085771 kernel: Key type dns_resolver registered Apr 18 00:38:22.085783 kernel: IPI shorthand broadcast: enabled Apr 18 00:38:22.085796 kernel: sched_clock: Marking stable (1253003724, 219429580)->(1594402028, -121968724) Apr 18 00:38:22.085817 kernel: registered taskstats version 1 Apr 18 00:38:22.085830 kernel: Loading compiled-in X.509 certificates Apr 18 00:38:22.085843 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.127-flatcar: 39e9969c7f49062f0fc1d1fb72e8f874436eb94f' Apr 18 00:38:22.085856 kernel: Key type .fscrypt registered Apr 18 00:38:22.085869 kernel: Key type fscrypt-provisioning registered Apr 18 00:38:22.085881 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 18 00:38:22.085894 kernel: ima: Allocated hash algorithm: sha1 Apr 18 00:38:22.085906 kernel: ima: No architecture policies found Apr 18 00:38:22.085919 kernel: clk: Disabling unused clocks Apr 18 00:38:22.085937 kernel: Freeing unused kernel image (initmem) memory: 42892K Apr 18 00:38:22.085950 kernel: Write protecting the kernel read-only data: 36864k Apr 18 00:38:22.085984 kernel: Freeing unused kernel image (rodata/data gap) memory: 1824K Apr 18 00:38:22.085997 kernel: Run /init as init process Apr 18 00:38:22.086010 kernel: with arguments: Apr 18 00:38:22.086023 kernel: /init Apr 18 00:38:22.086043 kernel: with environment: Apr 18 00:38:22.086055 kernel: HOME=/ Apr 18 00:38:22.086068 kernel: TERM=linux Apr 18 00:38:22.086090 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) Apr 18 00:38:22.086106 systemd[1]: Detected virtualization kvm. Apr 18 00:38:22.086120 systemd[1]: Detected architecture x86-64. Apr 18 00:38:22.086133 systemd[1]: Running in initrd. Apr 18 00:38:22.086146 systemd[1]: No hostname configured, using default hostname. Apr 18 00:38:22.086159 systemd[1]: Hostname set to . Apr 18 00:38:22.086173 systemd[1]: Initializing machine ID from VM UUID. Apr 18 00:38:22.086191 systemd[1]: Queued start job for default target initrd.target. Apr 18 00:38:22.086205 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 18 00:38:22.086219 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 18 00:38:22.086233 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Apr 18 00:38:22.086247 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 18 00:38:22.086260 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Apr 18 00:38:22.086274 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Apr 18 00:38:22.086294 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Apr 18 00:38:22.086308 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Apr 18 00:38:22.086322 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 18 00:38:22.086336 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 18 00:38:22.086349 systemd[1]: Reached target paths.target - Path Units. Apr 18 00:38:22.086362 systemd[1]: Reached target slices.target - Slice Units. Apr 18 00:38:22.086376 systemd[1]: Reached target swap.target - Swaps. Apr 18 00:38:22.086390 systemd[1]: Reached target timers.target - Timer Units. Apr 18 00:38:22.086428 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Apr 18 00:38:22.086442 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 18 00:38:22.086456 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Apr 18 00:38:22.086469 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Apr 18 00:38:22.086482 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 18 00:38:22.086496 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 18 00:38:22.086509 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 18 00:38:22.086528 systemd[1]: Reached target sockets.target - Socket Units. Apr 18 00:38:22.086541 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Apr 18 00:38:22.086560 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 18 00:38:22.086573 systemd[1]: Finished network-cleanup.service - Network Cleanup. Apr 18 00:38:22.086587 systemd[1]: Starting systemd-fsck-usr.service... Apr 18 00:38:22.086600 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 18 00:38:22.086614 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 18 00:38:22.086627 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 18 00:38:22.086683 systemd-journald[201]: Collecting audit messages is disabled. Apr 18 00:38:22.088750 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Apr 18 00:38:22.088767 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 18 00:38:22.088781 systemd[1]: Finished systemd-fsck-usr.service. Apr 18 00:38:22.088803 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Apr 18 00:38:22.088819 systemd-journald[201]: Journal started Apr 18 00:38:22.088844 systemd-journald[201]: Runtime Journal (/run/log/journal/e521b7f3f2334aa0ac80db3ecd1d099b) is 4.7M, max 38.0M, 33.2M free. Apr 18 00:38:22.064996 systemd-modules-load[202]: Inserted module 'overlay' Apr 18 00:38:22.133777 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 18 00:38:22.133812 kernel: Bridge firewalling registered Apr 18 00:38:22.133830 systemd[1]: Started systemd-journald.service - Journal Service. Apr 18 00:38:22.111496 systemd-modules-load[202]: Inserted module 'br_netfilter' Apr 18 00:38:22.140572 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 18 00:38:22.142794 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 18 00:38:22.158130 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 18 00:38:22.160371 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 18 00:38:22.177578 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 18 00:38:22.180040 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Apr 18 00:38:22.194975 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 18 00:38:22.197423 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 18 00:38:22.198504 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 18 00:38:22.202057 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 18 00:38:22.214937 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Apr 18 00:38:22.226007 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 18 00:38:22.228417 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 18 00:38:22.231758 dracut-cmdline[235]: dracut-dracut-053 Apr 18 00:38:22.234835 dracut-cmdline[235]: 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=e69cfa144bf8cf6f0b7e7881c91c17228ba9dbcb6c99d9692bced9ddba34ee3a Apr 18 00:38:22.275818 systemd-resolved[236]: Positive Trust Anchors: Apr 18 00:38:22.276933 systemd-resolved[236]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 18 00:38:22.276978 systemd-resolved[236]: 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 Apr 18 00:38:22.284890 systemd-resolved[236]: Defaulting to hostname 'linux'. Apr 18 00:38:22.287875 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 18 00:38:22.289536 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 18 00:38:22.334796 kernel: SCSI subsystem initialized Apr 18 00:38:22.346742 kernel: Loading iSCSI transport class v2.0-870. Apr 18 00:38:22.359763 kernel: iscsi: registered transport (tcp) Apr 18 00:38:22.385049 kernel: iscsi: registered transport (qla4xxx) Apr 18 00:38:22.385126 kernel: QLogic iSCSI HBA Driver Apr 18 00:38:22.440971 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Apr 18 00:38:22.452911 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Apr 18 00:38:22.482122 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 18 00:38:22.482220 kernel: device-mapper: uevent: version 1.0.3 Apr 18 00:38:22.483008 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Apr 18 00:38:22.531759 kernel: raid6: sse2x4 gen() 13795 MB/s Apr 18 00:38:22.549746 kernel: raid6: sse2x2 gen() 9256 MB/s Apr 18 00:38:22.568338 kernel: raid6: sse2x1 gen() 9836 MB/s Apr 18 00:38:22.568383 kernel: raid6: using algorithm sse2x4 gen() 13795 MB/s Apr 18 00:38:22.587336 kernel: raid6: .... xor() 7931 MB/s, rmw enabled Apr 18 00:38:22.587409 kernel: raid6: using ssse3x2 recovery algorithm Apr 18 00:38:22.612751 kernel: xor: automatically using best checksumming function avx Apr 18 00:38:22.801752 kernel: Btrfs loaded, zoned=no, fsverity=no Apr 18 00:38:22.817925 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Apr 18 00:38:22.824985 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 18 00:38:22.853624 systemd-udevd[421]: Using default interface naming scheme 'v255'. Apr 18 00:38:22.860885 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 18 00:38:22.869910 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Apr 18 00:38:22.891052 dracut-pre-trigger[429]: rd.md=0: removing MD RAID activation Apr 18 00:38:22.929937 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Apr 18 00:38:22.935883 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 18 00:38:23.054024 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 18 00:38:23.060932 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Apr 18 00:38:23.087097 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Apr 18 00:38:23.089820 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Apr 18 00:38:23.092090 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 18 00:38:23.093843 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 18 00:38:23.099898 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Apr 18 00:38:23.131931 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Apr 18 00:38:23.178736 kernel: virtio_blk virtio1: 2/0/0 default/read/poll queues Apr 18 00:38:23.199446 kernel: virtio_blk virtio1: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) Apr 18 00:38:23.199750 kernel: cryptd: max_cpu_qlen set to 1000 Apr 18 00:38:23.217045 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Apr 18 00:38:23.217104 kernel: GPT:17805311 != 125829119 Apr 18 00:38:23.217122 kernel: GPT:Alternate GPT header not at the end of the disk. Apr 18 00:38:23.217149 kernel: GPT:17805311 != 125829119 Apr 18 00:38:23.217171 kernel: GPT: Use GNU Parted to correct GPT errors. Apr 18 00:38:23.220911 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 18 00:38:23.234193 kernel: AVX version of gcm_enc/dec engaged. Apr 18 00:38:23.234252 kernel: ACPI: bus type USB registered Apr 18 00:38:23.235675 kernel: usbcore: registered new interface driver usbfs Apr 18 00:38:23.238499 kernel: AES CTR mode by8 optimization enabled Apr 18 00:38:23.238534 kernel: usbcore: registered new interface driver hub Apr 18 00:38:23.239882 kernel: usbcore: registered new device driver usb Apr 18 00:38:23.239864 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 18 00:38:23.240039 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 18 00:38:23.241361 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 18 00:38:23.242300 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 18 00:38:23.242662 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 18 00:38:23.244795 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Apr 18 00:38:23.254852 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 18 00:38:23.313721 kernel: xhci_hcd 0000:03:00.0: xHCI Host Controller Apr 18 00:38:23.314042 kernel: xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 1 Apr 18 00:38:23.315727 kernel: libata version 3.00 loaded. Apr 18 00:38:23.318724 kernel: xhci_hcd 0000:03:00.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 Apr 18 00:38:23.330742 kernel: xhci_hcd 0000:03:00.0: xHCI Host Controller Apr 18 00:38:23.342206 kernel: xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 2 Apr 18 00:38:23.342504 kernel: xhci_hcd 0000:03:00.0: Host supports USB 3.0 SuperSpeed Apr 18 00:38:23.342780 kernel: hub 1-0:1.0: USB hub found Apr 18 00:38:23.343031 kernel: hub 1-0:1.0: 4 ports detected Apr 18 00:38:23.343250 kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. Apr 18 00:38:23.343549 kernel: hub 2-0:1.0: USB hub found Apr 18 00:38:23.345877 kernel: hub 2-0:1.0: 4 ports detected Apr 18 00:38:23.347730 kernel: BTRFS: device fsid 81b0bf8a-1550-4880-b72f-76fa51dbb6c0 devid 1 transid 32 /dev/vda3 scanned by (udev-worker) (476) Apr 18 00:38:23.352190 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Apr 18 00:38:23.433422 kernel: BTRFS: device label OEM devid 1 transid 9 /dev/vda6 scanned by (udev-worker) (475) Apr 18 00:38:23.433457 kernel: ahci 0000:00:1f.2: version 3.0 Apr 18 00:38:23.433815 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Apr 18 00:38:23.433837 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Apr 18 00:38:23.434948 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Apr 18 00:38:23.435164 kernel: scsi host0: ahci Apr 18 00:38:23.435405 kernel: scsi host1: ahci Apr 18 00:38:23.435613 kernel: scsi host2: ahci Apr 18 00:38:23.435862 kernel: scsi host3: ahci Apr 18 00:38:23.436054 kernel: scsi host4: ahci Apr 18 00:38:23.436264 kernel: scsi host5: ahci Apr 18 00:38:23.436482 kernel: ata1: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b100 irq 41 Apr 18 00:38:23.436503 kernel: ata2: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b180 irq 41 Apr 18 00:38:23.436520 kernel: ata3: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b200 irq 41 Apr 18 00:38:23.436538 kernel: ata4: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b280 irq 41 Apr 18 00:38:23.436554 kernel: ata5: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b300 irq 41 Apr 18 00:38:23.436571 kernel: ata6: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b380 irq 41 Apr 18 00:38:23.435435 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 18 00:38:23.452889 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Apr 18 00:38:23.453771 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Apr 18 00:38:23.462031 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Apr 18 00:38:23.473804 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 18 00:38:23.484969 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Apr 18 00:38:23.490884 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Apr 18 00:38:23.494313 disk-uuid[563]: Primary Header is updated. Apr 18 00:38:23.494313 disk-uuid[563]: Secondary Entries is updated. Apr 18 00:38:23.494313 disk-uuid[563]: Secondary Header is updated. Apr 18 00:38:23.499997 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 18 00:38:23.506747 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 18 00:38:23.521599 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 18 00:38:23.572725 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Apr 18 00:38:23.688861 kernel: ata2: SATA link down (SStatus 0 SControl 300) Apr 18 00:38:23.691739 kernel: ata1: SATA link down (SStatus 0 SControl 300) Apr 18 00:38:23.695740 kernel: ata5: SATA link down (SStatus 0 SControl 300) Apr 18 00:38:23.698734 kernel: ata4: SATA link down (SStatus 0 SControl 300) Apr 18 00:38:23.698771 kernel: ata3: SATA link down (SStatus 0 SControl 300) Apr 18 00:38:23.701737 kernel: ata6: SATA link down (SStatus 0 SControl 300) Apr 18 00:38:23.724738 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 18 00:38:23.730859 kernel: usbcore: registered new interface driver usbhid Apr 18 00:38:23.730934 kernel: usbhid: USB HID core driver Apr 18 00:38:23.738083 kernel: input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:02.1/0000:03:00.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input2 Apr 18 00:38:23.738178 kernel: hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:03:00.0-1/input0 Apr 18 00:38:24.511228 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Apr 18 00:38:24.512940 disk-uuid[564]: The operation has completed successfully. Apr 18 00:38:24.565950 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 18 00:38:24.566106 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Apr 18 00:38:24.590950 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Apr 18 00:38:24.597551 sh[583]: Success Apr 18 00:38:24.615744 kernel: device-mapper: verity: sha256 using implementation "sha256-avx" Apr 18 00:38:24.663499 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Apr 18 00:38:24.677813 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Apr 18 00:38:24.679427 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Apr 18 00:38:24.711985 kernel: BTRFS info (device dm-0): first mount of filesystem 81b0bf8a-1550-4880-b72f-76fa51dbb6c0 Apr 18 00:38:24.712083 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Apr 18 00:38:24.712106 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Apr 18 00:38:24.713961 kernel: BTRFS info (device dm-0): disabling log replay at mount time Apr 18 00:38:24.716456 kernel: BTRFS info (device dm-0): using free space tree Apr 18 00:38:24.726720 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Apr 18 00:38:24.728287 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Apr 18 00:38:24.734017 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Apr 18 00:38:24.739078 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Apr 18 00:38:24.754513 kernel: BTRFS info (device vda6): first mount of filesystem a5a0fe13-59ac-4c21-ab23-7fd1bfa02f60 Apr 18 00:38:24.754587 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 18 00:38:24.754608 kernel: BTRFS info (device vda6): using free space tree Apr 18 00:38:24.761741 kernel: BTRFS info (device vda6): auto enabling async discard Apr 18 00:38:24.778928 kernel: BTRFS info (device vda6): last unmount of filesystem a5a0fe13-59ac-4c21-ab23-7fd1bfa02f60 Apr 18 00:38:24.778586 systemd[1]: mnt-oem.mount: Deactivated successfully. Apr 18 00:38:24.790419 systemd[1]: Finished ignition-setup.service - Ignition (setup). Apr 18 00:38:24.798998 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Apr 18 00:38:24.879233 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 18 00:38:24.896263 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 18 00:38:24.929059 systemd-networkd[764]: lo: Link UP Apr 18 00:38:24.929071 systemd-networkd[764]: lo: Gained carrier Apr 18 00:38:24.933178 systemd-networkd[764]: Enumeration completed Apr 18 00:38:24.934334 systemd-networkd[764]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 18 00:38:24.934349 systemd-networkd[764]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 18 00:38:24.936274 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 18 00:38:24.937423 systemd-networkd[764]: eth0: Link UP Apr 18 00:38:24.937429 systemd-networkd[764]: eth0: Gained carrier Apr 18 00:38:24.937439 systemd-networkd[764]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 18 00:38:24.939527 systemd[1]: Reached target network.target - Network. Apr 18 00:38:24.950296 ignition[681]: Ignition 2.19.0 Apr 18 00:38:24.950332 ignition[681]: Stage: fetch-offline Apr 18 00:38:24.950443 ignition[681]: no configs at "/usr/lib/ignition/base.d" Apr 18 00:38:24.952476 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Apr 18 00:38:24.950470 ignition[681]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 18 00:38:24.950684 ignition[681]: parsed url from cmdline: "" Apr 18 00:38:24.950690 ignition[681]: no config URL provided Apr 18 00:38:24.950699 ignition[681]: reading system config file "/usr/lib/ignition/user.ign" Apr 18 00:38:24.950754 ignition[681]: no config at "/usr/lib/ignition/user.ign" Apr 18 00:38:24.950763 ignition[681]: failed to fetch config: resource requires networking Apr 18 00:38:24.951031 ignition[681]: Ignition finished successfully Apr 18 00:38:24.958970 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Apr 18 00:38:24.978815 systemd-networkd[764]: eth0: DHCPv4 address 10.230.47.182/30, gateway 10.230.47.181 acquired from 10.230.47.181 Apr 18 00:38:24.984661 ignition[772]: Ignition 2.19.0 Apr 18 00:38:24.984680 ignition[772]: Stage: fetch Apr 18 00:38:24.985070 ignition[772]: no configs at "/usr/lib/ignition/base.d" Apr 18 00:38:24.985090 ignition[772]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 18 00:38:24.985217 ignition[772]: parsed url from cmdline: "" Apr 18 00:38:24.985223 ignition[772]: no config URL provided Apr 18 00:38:24.985232 ignition[772]: reading system config file "/usr/lib/ignition/user.ign" Apr 18 00:38:24.985247 ignition[772]: no config at "/usr/lib/ignition/user.ign" Apr 18 00:38:24.985505 ignition[772]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 Apr 18 00:38:24.985551 ignition[772]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... Apr 18 00:38:24.985619 ignition[772]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... Apr 18 00:38:25.001417 ignition[772]: GET result: OK Apr 18 00:38:25.002267 ignition[772]: parsing config with SHA512: 78ed80f88f047c4edbffbd39c551950cc58273d27a8335bd5d1f50af084a888127df71193fb50a63ef554d69835c2113e0fc962fe78ffbb5a9cab6bf6a897819 Apr 18 00:38:25.007387 unknown[772]: fetched base config from "system" Apr 18 00:38:25.007402 unknown[772]: fetched base config from "system" Apr 18 00:38:25.007411 unknown[772]: fetched user config from "openstack" Apr 18 00:38:25.008843 ignition[772]: fetch: fetch complete Apr 18 00:38:25.010857 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Apr 18 00:38:25.008851 ignition[772]: fetch: fetch passed Apr 18 00:38:25.008955 ignition[772]: Ignition finished successfully Apr 18 00:38:25.016975 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Apr 18 00:38:25.049096 ignition[779]: Ignition 2.19.0 Apr 18 00:38:25.050309 ignition[779]: Stage: kargs Apr 18 00:38:25.050565 ignition[779]: no configs at "/usr/lib/ignition/base.d" Apr 18 00:38:25.050584 ignition[779]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 18 00:38:25.052825 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Apr 18 00:38:25.051456 ignition[779]: kargs: kargs passed Apr 18 00:38:25.051531 ignition[779]: Ignition finished successfully Apr 18 00:38:25.070055 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Apr 18 00:38:25.088460 ignition[785]: Ignition 2.19.0 Apr 18 00:38:25.088483 ignition[785]: Stage: disks Apr 18 00:38:25.088761 ignition[785]: no configs at "/usr/lib/ignition/base.d" Apr 18 00:38:25.091759 systemd[1]: Finished ignition-disks.service - Ignition (disks). Apr 18 00:38:25.088781 ignition[785]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 18 00:38:25.093016 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Apr 18 00:38:25.089883 ignition[785]: disks: disks passed Apr 18 00:38:25.094106 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Apr 18 00:38:25.089966 ignition[785]: Ignition finished successfully Apr 18 00:38:25.095784 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 18 00:38:25.097318 systemd[1]: Reached target sysinit.target - System Initialization. Apr 18 00:38:25.098524 systemd[1]: Reached target basic.target - Basic System. Apr 18 00:38:25.110986 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Apr 18 00:38:25.129945 systemd-fsck[793]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Apr 18 00:38:25.134069 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Apr 18 00:38:25.140853 systemd[1]: Mounting sysroot.mount - /sysroot... Apr 18 00:38:25.259722 kernel: EXT4-fs (vda9): mounted filesystem d3c199f8-8065-4f33-a75b-da2f09d4fc39 r/w with ordered data mode. Quota mode: none. Apr 18 00:38:25.261194 systemd[1]: Mounted sysroot.mount - /sysroot. Apr 18 00:38:25.262580 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Apr 18 00:38:25.276921 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 18 00:38:25.280925 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Apr 18 00:38:25.283538 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Apr 18 00:38:25.286904 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent... Apr 18 00:38:25.289908 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 18 00:38:25.296462 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by mount (801) Apr 18 00:38:25.296496 kernel: BTRFS info (device vda6): first mount of filesystem a5a0fe13-59ac-4c21-ab23-7fd1bfa02f60 Apr 18 00:38:25.289961 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Apr 18 00:38:25.295830 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Apr 18 00:38:25.300400 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 18 00:38:25.300428 kernel: BTRFS info (device vda6): using free space tree Apr 18 00:38:25.307439 kernel: BTRFS info (device vda6): auto enabling async discard Apr 18 00:38:25.309604 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Apr 18 00:38:25.314253 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 18 00:38:25.389351 initrd-setup-root[829]: cut: /sysroot/etc/passwd: No such file or directory Apr 18 00:38:25.402447 initrd-setup-root[836]: cut: /sysroot/etc/group: No such file or directory Apr 18 00:38:25.410226 initrd-setup-root[843]: cut: /sysroot/etc/shadow: No such file or directory Apr 18 00:38:25.416860 initrd-setup-root[850]: cut: /sysroot/etc/gshadow: No such file or directory Apr 18 00:38:25.526339 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Apr 18 00:38:25.533855 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Apr 18 00:38:25.537874 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Apr 18 00:38:25.548748 kernel: BTRFS info (device vda6): last unmount of filesystem a5a0fe13-59ac-4c21-ab23-7fd1bfa02f60 Apr 18 00:38:25.577225 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Apr 18 00:38:25.582725 ignition[917]: INFO : Ignition 2.19.0 Apr 18 00:38:25.582725 ignition[917]: INFO : Stage: mount Apr 18 00:38:25.582725 ignition[917]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 18 00:38:25.582725 ignition[917]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 18 00:38:25.586127 ignition[917]: INFO : mount: mount passed Apr 18 00:38:25.586127 ignition[917]: INFO : Ignition finished successfully Apr 18 00:38:25.585960 systemd[1]: Finished ignition-mount.service - Ignition (mount). Apr 18 00:38:25.709030 systemd[1]: sysroot-oem.mount: Deactivated successfully. Apr 18 00:38:26.832006 systemd-networkd[764]: eth0: Gained IPv6LL Apr 18 00:38:27.841815 systemd-networkd[764]: eth0: Ignoring DHCPv6 address 2a02:1348:179:8bed:24:19ff:fee6:2fb6/128 (valid for 59min 59s, preferred for 59min 59s) which conflicts with 2a02:1348:179:8bed:24:19ff:fee6:2fb6/64 assigned by NDisc. Apr 18 00:38:27.843773 systemd-networkd[764]: eth0: Hint: use IPv6Token= setting to change the address generated by NDisc or set UseAutonomousPrefix=no. Apr 18 00:38:32.467748 coreos-metadata[803]: Apr 18 00:38:32.467 WARN failed to locate config-drive, using the metadata service API instead Apr 18 00:38:32.491534 coreos-metadata[803]: Apr 18 00:38:32.491 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Apr 18 00:38:32.508655 coreos-metadata[803]: Apr 18 00:38:32.508 INFO Fetch successful Apr 18 00:38:32.509989 coreos-metadata[803]: Apr 18 00:38:32.509 INFO wrote hostname srv-qmq7g.gb1.brightbox.com to /sysroot/etc/hostname Apr 18 00:38:32.512427 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. Apr 18 00:38:32.512594 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent. Apr 18 00:38:32.527897 systemd[1]: Starting ignition-files.service - Ignition (files)... Apr 18 00:38:32.555928 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Apr 18 00:38:32.568754 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by mount (934) Apr 18 00:38:32.575644 kernel: BTRFS info (device vda6): first mount of filesystem a5a0fe13-59ac-4c21-ab23-7fd1bfa02f60 Apr 18 00:38:32.575681 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Apr 18 00:38:32.575722 kernel: BTRFS info (device vda6): using free space tree Apr 18 00:38:32.580730 kernel: BTRFS info (device vda6): auto enabling async discard Apr 18 00:38:32.583914 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Apr 18 00:38:32.615440 ignition[952]: INFO : Ignition 2.19.0 Apr 18 00:38:32.615440 ignition[952]: INFO : Stage: files Apr 18 00:38:32.617313 ignition[952]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 18 00:38:32.617313 ignition[952]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 18 00:38:32.617313 ignition[952]: DEBUG : files: compiled without relabeling support, skipping Apr 18 00:38:32.620083 ignition[952]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Apr 18 00:38:32.620083 ignition[952]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Apr 18 00:38:32.637327 ignition[952]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Apr 18 00:38:32.638899 ignition[952]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Apr 18 00:38:32.641797 ignition[952]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Apr 18 00:38:32.640012 unknown[952]: wrote ssh authorized keys file for user: core Apr 18 00:38:32.643825 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Apr 18 00:38:32.643825 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Apr 18 00:38:32.643825 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Apr 18 00:38:32.643825 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Apr 18 00:38:32.643825 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.4-x86-64.raw" Apr 18 00:38:32.643825 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.34.4-x86-64.raw" Apr 18 00:38:32.643825 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.4-x86-64.raw" Apr 18 00:38:32.653840 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://extensions.flatcar.org/extensions/kubernetes-v1.34.4-x86-64.raw: attempt #1 Apr 18 00:38:32.925204 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Apr 18 00:38:34.638728 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.34.4-x86-64.raw" Apr 18 00:38:34.641842 ignition[952]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 18 00:38:34.641842 ignition[952]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 18 00:38:34.641842 ignition[952]: INFO : files: files passed Apr 18 00:38:34.641842 ignition[952]: INFO : Ignition finished successfully Apr 18 00:38:34.643052 systemd[1]: Finished ignition-files.service - Ignition (files). Apr 18 00:38:34.654672 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Apr 18 00:38:34.656917 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Apr 18 00:38:34.661165 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 18 00:38:34.662145 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Apr 18 00:38:34.679100 initrd-setup-root-after-ignition[980]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 18 00:38:34.681964 initrd-setup-root-after-ignition[980]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Apr 18 00:38:34.682992 initrd-setup-root-after-ignition[984]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 18 00:38:34.685521 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 18 00:38:34.686653 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Apr 18 00:38:34.697037 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Apr 18 00:38:34.732934 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 18 00:38:34.733986 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Apr 18 00:38:34.735263 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Apr 18 00:38:34.736803 systemd[1]: Reached target initrd.target - Initrd Default Target. Apr 18 00:38:34.737765 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Apr 18 00:38:34.747960 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Apr 18 00:38:34.765631 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 18 00:38:34.772896 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Apr 18 00:38:34.801372 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Apr 18 00:38:34.803195 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 18 00:38:34.804073 systemd[1]: Stopped target timers.target - Timer Units. Apr 18 00:38:34.805462 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 18 00:38:34.805661 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Apr 18 00:38:34.807405 systemd[1]: Stopped target initrd.target - Initrd Default Target. Apr 18 00:38:34.808350 systemd[1]: Stopped target basic.target - Basic System. Apr 18 00:38:34.809754 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Apr 18 00:38:34.811068 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Apr 18 00:38:34.812329 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Apr 18 00:38:34.813932 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Apr 18 00:38:34.815416 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Apr 18 00:38:34.816969 systemd[1]: Stopped target sysinit.target - System Initialization. Apr 18 00:38:34.818362 systemd[1]: Stopped target local-fs.target - Local File Systems. Apr 18 00:38:34.819840 systemd[1]: Stopped target swap.target - Swaps. Apr 18 00:38:34.821110 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 18 00:38:34.821286 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Apr 18 00:38:34.822908 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Apr 18 00:38:34.823787 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 18 00:38:34.825193 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Apr 18 00:38:34.825385 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 18 00:38:34.826868 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 18 00:38:34.827041 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Apr 18 00:38:34.829001 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 18 00:38:34.829200 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Apr 18 00:38:34.830927 systemd[1]: ignition-files.service: Deactivated successfully. Apr 18 00:38:34.831092 systemd[1]: Stopped ignition-files.service - Ignition (files). Apr 18 00:38:34.851053 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Apr 18 00:38:34.852177 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 18 00:38:34.852351 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Apr 18 00:38:34.856549 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Apr 18 00:38:34.859132 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 18 00:38:34.859314 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Apr 18 00:38:34.863908 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 18 00:38:34.864112 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Apr 18 00:38:34.873891 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 18 00:38:34.874974 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Apr 18 00:38:34.880971 ignition[1004]: INFO : Ignition 2.19.0 Apr 18 00:38:34.880971 ignition[1004]: INFO : Stage: umount Apr 18 00:38:34.883074 ignition[1004]: INFO : no configs at "/usr/lib/ignition/base.d" Apr 18 00:38:34.883074 ignition[1004]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Apr 18 00:38:34.883074 ignition[1004]: INFO : umount: umount passed Apr 18 00:38:34.883074 ignition[1004]: INFO : Ignition finished successfully Apr 18 00:38:34.884270 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 18 00:38:34.885083 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Apr 18 00:38:34.887309 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 18 00:38:34.887410 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Apr 18 00:38:34.889029 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 18 00:38:34.889112 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Apr 18 00:38:34.890985 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 18 00:38:34.891057 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Apr 18 00:38:34.892836 systemd[1]: Stopped target network.target - Network. Apr 18 00:38:34.893852 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 18 00:38:34.893937 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Apr 18 00:38:34.895813 systemd[1]: Stopped target paths.target - Path Units. Apr 18 00:38:34.896872 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 18 00:38:34.897056 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 18 00:38:34.898082 systemd[1]: Stopped target slices.target - Slice Units. Apr 18 00:38:34.898677 systemd[1]: Stopped target sockets.target - Socket Units. Apr 18 00:38:34.901132 systemd[1]: iscsid.socket: Deactivated successfully. Apr 18 00:38:34.901203 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Apr 18 00:38:34.902215 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 18 00:38:34.902274 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Apr 18 00:38:34.902961 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 18 00:38:34.903055 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Apr 18 00:38:34.904828 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Apr 18 00:38:34.904905 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Apr 18 00:38:34.913071 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Apr 18 00:38:34.914748 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Apr 18 00:38:34.918094 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 18 00:38:34.920959 systemd-networkd[764]: eth0: DHCPv6 lease lost Apr 18 00:38:34.924311 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 18 00:38:34.924556 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Apr 18 00:38:34.926583 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 18 00:38:34.926639 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Apr 18 00:38:34.935856 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Apr 18 00:38:34.936570 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 18 00:38:34.936722 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Apr 18 00:38:34.939030 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 18 00:38:34.941926 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 18 00:38:34.942070 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Apr 18 00:38:34.947024 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 18 00:38:34.947130 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Apr 18 00:38:34.949872 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 18 00:38:34.949960 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Apr 18 00:38:34.951263 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Apr 18 00:38:34.951326 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 18 00:38:34.957066 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 18 00:38:34.958791 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 18 00:38:34.961737 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 18 00:38:34.961860 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Apr 18 00:38:34.962688 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 18 00:38:34.962765 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Apr 18 00:38:34.964088 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 18 00:38:34.964163 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Apr 18 00:38:34.968092 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 18 00:38:34.968176 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Apr 18 00:38:34.969773 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 18 00:38:34.969859 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Apr 18 00:38:34.977924 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Apr 18 00:38:34.979046 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Apr 18 00:38:34.979145 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 18 00:38:34.979897 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 18 00:38:34.979970 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Apr 18 00:38:34.981261 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 18 00:38:34.981409 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Apr 18 00:38:34.989790 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 18 00:38:34.989951 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Apr 18 00:38:35.020610 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 18 00:38:35.020800 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Apr 18 00:38:35.022449 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Apr 18 00:38:35.023480 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 18 00:38:35.023561 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Apr 18 00:38:35.033959 systemd[1]: Starting initrd-switch-root.service - Switch Root... Apr 18 00:38:35.043690 systemd[1]: Switching root. Apr 18 00:38:35.087845 systemd-journald[201]: Received SIGTERM from PID 1 (systemd). Apr 18 00:38:35.087964 systemd-journald[201]: Journal stopped Apr 18 00:38:36.533650 kernel: SELinux: policy capability network_peer_controls=1 Apr 18 00:38:36.534839 kernel: SELinux: policy capability open_perms=1 Apr 18 00:38:36.534874 kernel: SELinux: policy capability extended_socket_class=1 Apr 18 00:38:36.534908 kernel: SELinux: policy capability always_check_network=0 Apr 18 00:38:36.534932 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 18 00:38:36.534969 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 18 00:38:36.534989 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 18 00:38:36.535012 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 18 00:38:36.535042 systemd[1]: Successfully loaded SELinux policy in 51.563ms. Apr 18 00:38:36.535103 kernel: audit: type=1403 audit(1776472715.346:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 18 00:38:36.536206 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 23.443ms. Apr 18 00:38:36.536238 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) Apr 18 00:38:36.536278 systemd[1]: Detected virtualization kvm. Apr 18 00:38:36.536305 systemd[1]: Detected architecture x86-64. Apr 18 00:38:36.536337 systemd[1]: Detected first boot. Apr 18 00:38:36.536358 systemd[1]: Hostname set to . Apr 18 00:38:36.536401 systemd[1]: Initializing machine ID from VM UUID. Apr 18 00:38:36.536420 zram_generator::config[1047]: No configuration found. Apr 18 00:38:36.536470 systemd[1]: Populated /etc with preset unit settings. Apr 18 00:38:36.536491 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 18 00:38:36.536530 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Apr 18 00:38:36.536551 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 18 00:38:36.536577 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Apr 18 00:38:36.536609 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Apr 18 00:38:36.536633 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Apr 18 00:38:36.536653 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Apr 18 00:38:36.536686 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Apr 18 00:38:36.537783 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Apr 18 00:38:36.537817 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Apr 18 00:38:36.537849 systemd[1]: Created slice user.slice - User and Session Slice. Apr 18 00:38:36.537869 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Apr 18 00:38:36.537888 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Apr 18 00:38:36.537915 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Apr 18 00:38:36.537934 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Apr 18 00:38:36.537953 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Apr 18 00:38:36.537986 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Apr 18 00:38:36.538006 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Apr 18 00:38:36.538032 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Apr 18 00:38:36.538058 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Apr 18 00:38:36.538094 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Apr 18 00:38:36.538115 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Apr 18 00:38:36.538145 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Apr 18 00:38:36.538173 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Apr 18 00:38:36.538203 systemd[1]: Reached target remote-fs.target - Remote File Systems. Apr 18 00:38:36.538224 systemd[1]: Reached target slices.target - Slice Units. Apr 18 00:38:36.538243 systemd[1]: Reached target swap.target - Swaps. Apr 18 00:38:36.538262 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Apr 18 00:38:36.538281 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Apr 18 00:38:36.538306 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Apr 18 00:38:36.538335 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Apr 18 00:38:36.538355 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Apr 18 00:38:36.538397 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Apr 18 00:38:36.538417 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Apr 18 00:38:36.538441 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Apr 18 00:38:36.538478 systemd[1]: Mounting media.mount - External Media Directory... Apr 18 00:38:36.538497 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 18 00:38:36.538516 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Apr 18 00:38:36.538551 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Apr 18 00:38:36.538579 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Apr 18 00:38:36.538614 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). Apr 18 00:38:36.538634 systemd[1]: Reached target machines.target - Containers. Apr 18 00:38:36.538662 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Apr 18 00:38:36.538694 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 18 00:38:36.538802 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Apr 18 00:38:36.538829 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Apr 18 00:38:36.538849 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 18 00:38:36.538868 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 18 00:38:36.538887 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 18 00:38:36.538924 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Apr 18 00:38:36.538946 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 18 00:38:36.539762 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 18 00:38:36.539794 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 18 00:38:36.539814 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Apr 18 00:38:36.539854 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 18 00:38:36.539875 systemd[1]: Stopped systemd-fsck-usr.service. Apr 18 00:38:36.539894 systemd[1]: Starting systemd-journald.service - Journal Service... Apr 18 00:38:36.539913 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Apr 18 00:38:36.539932 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Apr 18 00:38:36.539951 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Apr 18 00:38:36.539970 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Apr 18 00:38:36.539999 systemd[1]: verity-setup.service: Deactivated successfully. Apr 18 00:38:36.540020 systemd[1]: Stopped verity-setup.service. Apr 18 00:38:36.540059 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 18 00:38:36.540101 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Apr 18 00:38:36.540155 systemd-journald[1144]: Collecting audit messages is disabled. Apr 18 00:38:36.540206 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Apr 18 00:38:36.540228 systemd[1]: Mounted media.mount - External Media Directory. Apr 18 00:38:36.540253 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Apr 18 00:38:36.540287 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Apr 18 00:38:36.540308 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Apr 18 00:38:36.540327 systemd-journald[1144]: Journal started Apr 18 00:38:36.540357 systemd-journald[1144]: Runtime Journal (/run/log/journal/e521b7f3f2334aa0ac80db3ecd1d099b) is 4.7M, max 38.0M, 33.2M free. Apr 18 00:38:36.163813 systemd[1]: Queued start job for default target multi-user.target. Apr 18 00:38:36.543854 systemd[1]: Started systemd-journald.service - Journal Service. Apr 18 00:38:36.186020 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Apr 18 00:38:36.186741 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 18 00:38:36.545359 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Apr 18 00:38:36.547924 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 18 00:38:36.548181 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Apr 18 00:38:36.550954 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 18 00:38:36.551183 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 18 00:38:36.552886 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 18 00:38:36.553099 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 18 00:38:36.554291 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Apr 18 00:38:36.555819 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Apr 18 00:38:36.558255 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Apr 18 00:38:36.572643 systemd[1]: Reached target network-pre.target - Preparation for Network. Apr 18 00:38:36.581218 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Apr 18 00:38:36.583818 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 18 00:38:36.583859 systemd[1]: Reached target local-fs.target - Local File Systems. Apr 18 00:38:36.585756 kernel: fuse: init (API version 7.39) Apr 18 00:38:36.587684 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Apr 18 00:38:36.594813 kernel: loop: module loaded Apr 18 00:38:36.594945 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Apr 18 00:38:36.599895 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Apr 18 00:38:36.600796 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 18 00:38:36.603251 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Apr 18 00:38:36.609375 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Apr 18 00:38:36.611805 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 18 00:38:36.622949 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Apr 18 00:38:36.627552 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Apr 18 00:38:36.638855 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Apr 18 00:38:36.644341 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Apr 18 00:38:36.645487 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 18 00:38:36.645671 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Apr 18 00:38:36.659282 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 18 00:38:36.659543 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 18 00:38:36.660635 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Apr 18 00:38:36.661767 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Apr 18 00:38:36.691826 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Apr 18 00:38:36.692822 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 18 00:38:36.706163 systemd-journald[1144]: Time spent on flushing to /var/log/journal/e521b7f3f2334aa0ac80db3ecd1d099b is 54.122ms for 1115 entries. Apr 18 00:38:36.706163 systemd-journald[1144]: System Journal (/var/log/journal/e521b7f3f2334aa0ac80db3ecd1d099b) is 8.0M, max 584.8M, 576.8M free. Apr 18 00:38:36.781575 systemd-journald[1144]: Received client request to flush runtime journal. Apr 18 00:38:36.781898 kernel: loop0: detected capacity change from 0 to 140768 Apr 18 00:38:36.702947 systemd[1]: Starting systemd-sysusers.service - Create System Users... Apr 18 00:38:36.708663 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Apr 18 00:38:36.713258 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Apr 18 00:38:36.716019 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Apr 18 00:38:36.731921 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Apr 18 00:38:36.790451 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Apr 18 00:38:36.799840 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 18 00:38:36.809755 kernel: ACPI: bus type drm_connector registered Apr 18 00:38:36.811563 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Apr 18 00:38:36.822292 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 18 00:38:36.822540 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 18 00:38:36.842805 kernel: loop1: detected capacity change from 0 to 8 Apr 18 00:38:36.843920 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 18 00:38:36.845839 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Apr 18 00:38:36.894760 kernel: loop2: detected capacity change from 0 to 219192 Apr 18 00:38:36.911308 systemd[1]: Finished systemd-sysusers.service - Create System Users. Apr 18 00:38:36.933078 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Apr 18 00:38:36.977757 kernel: loop3: detected capacity change from 0 to 142488 Apr 18 00:38:36.991716 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Apr 18 00:38:37.009980 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Apr 18 00:38:37.033300 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Apr 18 00:38:37.033324 systemd-tmpfiles[1200]: ACLs are not supported, ignoring. Apr 18 00:38:37.043760 kernel: loop4: detected capacity change from 0 to 140768 Apr 18 00:38:37.055185 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Apr 18 00:38:37.070080 udevadm[1204]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Apr 18 00:38:37.075134 kernel: loop5: detected capacity change from 0 to 8 Apr 18 00:38:37.078823 kernel: loop6: detected capacity change from 0 to 219192 Apr 18 00:38:37.113768 kernel: loop7: detected capacity change from 0 to 142488 Apr 18 00:38:37.152081 (sd-merge)[1206]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-openstack'. Apr 18 00:38:37.153019 (sd-merge)[1206]: Merged extensions into '/usr'. Apr 18 00:38:37.161321 systemd[1]: Reloading requested from client PID 1176 ('systemd-sysext') (unit systemd-sysext.service)... Apr 18 00:38:37.161351 systemd[1]: Reloading... Apr 18 00:38:37.308777 zram_generator::config[1233]: No configuration found. Apr 18 00:38:37.429535 ldconfig[1171]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 18 00:38:37.537402 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. Apr 18 00:38:37.603668 systemd[1]: Reloading finished in 441 ms. Apr 18 00:38:37.635778 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Apr 18 00:38:37.637829 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Apr 18 00:38:37.652085 systemd[1]: Starting ensure-sysext.service... Apr 18 00:38:37.663056 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Apr 18 00:38:37.685346 systemd[1]: Reloading requested from client PID 1289 ('systemctl') (unit ensure-sysext.service)... Apr 18 00:38:37.685385 systemd[1]: Reloading... Apr 18 00:38:37.705661 systemd-tmpfiles[1290]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 18 00:38:37.706352 systemd-tmpfiles[1290]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Apr 18 00:38:37.707878 systemd-tmpfiles[1290]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 18 00:38:37.708295 systemd-tmpfiles[1290]: ACLs are not supported, ignoring. Apr 18 00:38:37.708404 systemd-tmpfiles[1290]: ACLs are not supported, ignoring. Apr 18 00:38:37.726363 systemd-tmpfiles[1290]: Detected autofs mount point /boot during canonicalization of boot. Apr 18 00:38:37.726384 systemd-tmpfiles[1290]: Skipping /boot Apr 18 00:38:37.757611 systemd-tmpfiles[1290]: Detected autofs mount point /boot during canonicalization of boot. Apr 18 00:38:37.757633 systemd-tmpfiles[1290]: Skipping /boot Apr 18 00:38:37.823768 zram_generator::config[1317]: No configuration found. Apr 18 00:38:38.015413 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. Apr 18 00:38:38.082584 systemd[1]: Reloading finished in 396 ms. Apr 18 00:38:38.107174 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Apr 18 00:38:38.113658 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Apr 18 00:38:38.126967 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Apr 18 00:38:38.140161 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Apr 18 00:38:38.143496 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Apr 18 00:38:38.155417 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Apr 18 00:38:38.162244 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Apr 18 00:38:38.170962 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Apr 18 00:38:38.179345 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 18 00:38:38.179667 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 18 00:38:38.187165 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Apr 18 00:38:38.196139 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Apr 18 00:38:38.199786 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Apr 18 00:38:38.201965 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 18 00:38:38.202155 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 18 00:38:38.206917 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 18 00:38:38.207205 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 18 00:38:38.207476 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 18 00:38:38.219341 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Apr 18 00:38:38.226796 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 18 00:38:38.240485 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Apr 18 00:38:38.251433 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 18 00:38:38.252800 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Apr 18 00:38:38.263158 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Apr 18 00:38:38.264134 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Apr 18 00:38:38.275162 systemd[1]: Starting systemd-update-done.service - Update is Completed... Apr 18 00:38:38.277763 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Apr 18 00:38:38.279142 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Apr 18 00:38:38.283129 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 18 00:38:38.283352 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Apr 18 00:38:38.285430 systemd[1]: Finished ensure-sysext.service. Apr 18 00:38:38.287344 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 18 00:38:38.287554 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Apr 18 00:38:38.289323 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 18 00:38:38.289538 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Apr 18 00:38:38.309127 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 18 00:38:38.309771 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Apr 18 00:38:38.313274 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 18 00:38:38.314065 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Apr 18 00:38:38.322152 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Apr 18 00:38:38.324772 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). Apr 18 00:38:38.328774 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Apr 18 00:38:38.337919 systemd[1]: Finished systemd-update-done.service - Update is Completed. Apr 18 00:38:38.338605 systemd-udevd[1381]: Using default interface naming scheme 'v255'. Apr 18 00:38:38.372829 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Apr 18 00:38:38.382688 systemd[1]: Starting systemd-networkd.service - Network Configuration... Apr 18 00:38:38.397449 augenrules[1418]: No rules Apr 18 00:38:38.402943 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Apr 18 00:38:38.430899 systemd[1]: Started systemd-userdbd.service - User Database Manager. Apr 18 00:38:38.582046 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Apr 18 00:38:38.636599 systemd-resolved[1379]: Positive Trust Anchors: Apr 18 00:38:38.636637 systemd-resolved[1379]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 18 00:38:38.636682 systemd-resolved[1379]: 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 Apr 18 00:38:38.654437 systemd-networkd[1416]: lo: Link UP Apr 18 00:38:38.654451 systemd-networkd[1416]: lo: Gained carrier Apr 18 00:38:38.662628 systemd-resolved[1379]: Using system hostname 'srv-qmq7g.gb1.brightbox.com'. Apr 18 00:38:38.663752 systemd-networkd[1416]: Enumeration completed Apr 18 00:38:38.664399 systemd-networkd[1416]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 18 00:38:38.664412 systemd-networkd[1416]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 18 00:38:38.664825 systemd[1]: Started systemd-networkd.service - Network Configuration. Apr 18 00:38:38.676466 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Apr 18 00:38:38.679332 systemd-networkd[1416]: eth0: Link UP Apr 18 00:38:38.679345 systemd-networkd[1416]: eth0: Gained carrier Apr 18 00:38:38.679387 systemd-networkd[1416]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 18 00:38:38.679894 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Apr 18 00:38:38.681533 systemd[1]: Reached target time-set.target - System Time Set. Apr 18 00:38:38.682575 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Apr 18 00:38:38.684237 systemd[1]: Reached target network.target - Network. Apr 18 00:38:38.685160 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Apr 18 00:38:38.690768 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 32 scanned by (udev-worker) (1415) Apr 18 00:38:38.710832 systemd-networkd[1416]: eth0: DHCPv4 address 10.230.47.182/30, gateway 10.230.47.181 acquired from 10.230.47.181 Apr 18 00:38:38.713208 systemd-timesyncd[1405]: Network configuration changed, trying to establish connection. Apr 18 00:38:38.754595 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Apr 18 00:38:38.759044 systemd-networkd[1416]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Apr 18 00:38:38.763005 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Apr 18 00:38:38.768759 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Apr 18 00:38:38.776733 kernel: ACPI: button: Power Button [PWRF] Apr 18 00:38:38.793053 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Apr 18 00:38:38.808760 kernel: mousedev: PS/2 mouse device common for all mice Apr 18 00:38:38.840724 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Apr 18 00:38:38.847480 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Apr 18 00:38:38.847814 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Apr 18 00:38:38.861728 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 Apr 18 00:38:38.924050 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Apr 18 00:38:39.480292 systemd-timesyncd[1405]: Contacted time server 109.74.206.120:123 (0.flatcar.pool.ntp.org). Apr 18 00:38:39.480564 systemd-timesyncd[1405]: Initial clock synchronization to Sat 2026-04-18 00:38:39.480093 UTC. Apr 18 00:38:39.484848 systemd-resolved[1379]: Clock change detected. Flushing caches. Apr 18 00:38:39.639492 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Apr 18 00:38:39.642120 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Apr 18 00:38:39.653283 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Apr 18 00:38:39.671104 lvm[1463]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 18 00:38:39.703267 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Apr 18 00:38:39.704435 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Apr 18 00:38:39.705209 systemd[1]: Reached target sysinit.target - System Initialization. Apr 18 00:38:39.706099 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Apr 18 00:38:39.706896 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Apr 18 00:38:39.708203 systemd[1]: Started logrotate.timer - Daily rotation of log files. Apr 18 00:38:39.709020 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Apr 18 00:38:39.709860 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Apr 18 00:38:39.710652 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 18 00:38:39.710708 systemd[1]: Reached target paths.target - Path Units. Apr 18 00:38:39.711425 systemd[1]: Reached target timers.target - Timer Units. Apr 18 00:38:39.713009 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Apr 18 00:38:39.715572 systemd[1]: Starting docker.socket - Docker Socket for the API... Apr 18 00:38:39.721310 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Apr 18 00:38:39.724007 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Apr 18 00:38:39.725404 systemd[1]: Listening on docker.socket - Docker Socket for the API. Apr 18 00:38:39.726235 systemd[1]: Reached target sockets.target - Socket Units. Apr 18 00:38:39.726874 systemd[1]: Reached target basic.target - Basic System. Apr 18 00:38:39.727568 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Apr 18 00:38:39.727616 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Apr 18 00:38:39.729143 systemd[1]: Starting containerd.service - containerd container runtime... Apr 18 00:38:39.735396 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Apr 18 00:38:39.743050 lvm[1467]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 18 00:38:39.742768 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Apr 18 00:38:39.751623 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Apr 18 00:38:39.762250 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Apr 18 00:38:39.763657 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). Apr 18 00:38:39.767228 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Apr 18 00:38:39.776184 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Apr 18 00:38:39.781286 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Apr 18 00:38:39.791867 jq[1471]: false Apr 18 00:38:39.792451 systemd[1]: Starting systemd-logind.service - User Login Management... Apr 18 00:38:39.794651 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 18 00:38:39.796411 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 18 00:38:39.799225 systemd[1]: Starting update-engine.service - Update Engine... Apr 18 00:38:39.802180 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Apr 18 00:38:39.806265 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Apr 18 00:38:39.816659 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 18 00:38:39.818096 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Apr 18 00:38:39.819705 extend-filesystems[1472]: Found loop4 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found loop5 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found loop6 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found loop7 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found vda Apr 18 00:38:39.822496 extend-filesystems[1472]: Found vda1 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found vda2 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found vda3 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found usr Apr 18 00:38:39.822496 extend-filesystems[1472]: Found vda4 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found vda6 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found vda7 Apr 18 00:38:39.822496 extend-filesystems[1472]: Found vda9 Apr 18 00:38:39.822496 extend-filesystems[1472]: Checking size of /dev/vda9 Apr 18 00:38:39.831376 dbus-daemon[1470]: [system] SELinux support is enabled Apr 18 00:38:39.835997 systemd[1]: Started dbus.service - D-Bus System Message Bus. Apr 18 00:38:39.835224 dbus-daemon[1470]: [system] Activating systemd to hand-off: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=244 pid=1416 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Apr 18 00:38:39.848429 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 18 00:38:39.850096 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Apr 18 00:38:39.854821 dbus-daemon[1470]: [system] Successfully activated service 'org.freedesktop.systemd1' Apr 18 00:38:39.855716 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). Apr 18 00:38:39.855786 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Apr 18 00:38:39.857234 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). Apr 18 00:38:39.857275 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Apr 18 00:38:39.881253 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Apr 18 00:38:39.887366 extend-filesystems[1472]: Resized partition /dev/vda9 Apr 18 00:38:39.893117 extend-filesystems[1502]: resize2fs 1.47.1 (20-May-2024) Apr 18 00:38:39.921238 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 15121403 blocks Apr 18 00:38:39.922323 jq[1480]: true Apr 18 00:38:39.899729 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Apr 18 00:38:39.948529 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 32 scanned by (udev-worker) (1419) Apr 18 00:38:39.944670 (ntainerd)[1491]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Apr 18 00:38:39.953815 systemd[1]: motdgen.service: Deactivated successfully. Apr 18 00:38:39.954226 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Apr 18 00:38:39.971850 update_engine[1479]: I20260418 00:38:39.971076 1479 main.cc:92] Flatcar Update Engine starting Apr 18 00:38:39.986133 update_engine[1479]: I20260418 00:38:39.983579 1479 update_check_scheduler.cc:74] Next update check in 9m14s Apr 18 00:38:39.984121 systemd[1]: Started update-engine.service - Update Engine. Apr 18 00:38:39.994063 jq[1505]: true Apr 18 00:38:39.997540 systemd[1]: Started locksmithd.service - Cluster reboot manager. Apr 18 00:38:40.087633 dbus-daemon[1470]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 18 00:38:40.088593 dbus-daemon[1470]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1494 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Apr 18 00:38:40.088610 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Apr 18 00:38:40.103327 systemd[1]: Starting polkit.service - Authorization Manager... Apr 18 00:38:40.127116 polkitd[1524]: Started polkitd version 121 Apr 18 00:38:40.150993 polkitd[1524]: Loading rules from directory /etc/polkit-1/rules.d Apr 18 00:38:40.152374 polkitd[1524]: Loading rules from directory /usr/share/polkit-1/rules.d Apr 18 00:38:40.160524 polkitd[1524]: Finished loading, compiling and executing 2 rules Apr 18 00:38:40.180263 dbus-daemon[1470]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 18 00:38:40.180715 systemd[1]: Started polkit.service - Authorization Manager. Apr 18 00:38:40.181812 polkitd[1524]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Apr 18 00:38:40.198466 locksmithd[1508]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 18 00:38:40.201061 bash[1525]: Updated "/home/core/.ssh/authorized_keys" Apr 18 00:38:40.205476 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Apr 18 00:38:40.214282 systemd[1]: Starting sshkeys.service... Apr 18 00:38:40.217902 systemd-logind[1478]: Watching system buttons on /dev/input/event2 (Power Button) Apr 18 00:38:40.218805 systemd-logind[1478]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Apr 18 00:38:40.219734 systemd-logind[1478]: New seat seat0. Apr 18 00:38:40.222000 systemd[1]: Started systemd-logind.service - User Login Management. Apr 18 00:38:40.227691 systemd-hostnamed[1494]: Hostname set to (static) Apr 18 00:38:40.248417 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Apr 18 00:38:40.257220 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Apr 18 00:38:40.313636 kernel: EXT4-fs (vda9): resized filesystem to 15121403 Apr 18 00:38:40.368707 extend-filesystems[1502]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Apr 18 00:38:40.368707 extend-filesystems[1502]: old_desc_blocks = 1, new_desc_blocks = 8 Apr 18 00:38:40.368707 extend-filesystems[1502]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. Apr 18 00:38:40.376965 extend-filesystems[1472]: Resized filesystem in /dev/vda9 Apr 18 00:38:40.370435 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 18 00:38:40.371214 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Apr 18 00:38:40.448354 containerd[1491]: time="2026-04-18T00:38:40.448114962Z" level=info msg="starting containerd" revision=174e0d1785eeda18dc2beba45e1d5a188771636b version=v1.7.21 Apr 18 00:38:40.481055 containerd[1491]: time="2026-04-18T00:38:40.480701203Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 18 00:38:40.484059 containerd[1491]: time="2026-04-18T00:38:40.483998715Z" 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.127-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 18 00:38:40.484803 containerd[1491]: time="2026-04-18T00:38:40.484132111Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 18 00:38:40.484803 containerd[1491]: time="2026-04-18T00:38:40.484163671Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 18 00:38:40.484803 containerd[1491]: time="2026-04-18T00:38:40.484529766Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Apr 18 00:38:40.484803 containerd[1491]: time="2026-04-18T00:38:40.484560100Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Apr 18 00:38:40.484803 containerd[1491]: time="2026-04-18T00:38:40.484680685Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Apr 18 00:38:40.484803 containerd[1491]: time="2026-04-18T00:38:40.484702270Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 18 00:38:40.487259 containerd[1491]: time="2026-04-18T00:38:40.487223270Z" 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 Apr 18 00:38:40.487320 containerd[1491]: time="2026-04-18T00:38:40.487259446Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 18 00:38:40.487320 containerd[1491]: time="2026-04-18T00:38:40.487282116Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Apr 18 00:38:40.487320 containerd[1491]: time="2026-04-18T00:38:40.487308318Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 18 00:38:40.487482 containerd[1491]: time="2026-04-18T00:38:40.487457833Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 18 00:38:40.488422 containerd[1491]: time="2026-04-18T00:38:40.487893447Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 18 00:38:40.488422 containerd[1491]: time="2026-04-18T00:38:40.488036487Z" 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 Apr 18 00:38:40.488422 containerd[1491]: time="2026-04-18T00:38:40.488058406Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 18 00:38:40.488422 containerd[1491]: time="2026-04-18T00:38:40.488209971Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 18 00:38:40.488422 containerd[1491]: time="2026-04-18T00:38:40.488343075Z" level=info msg="metadata content store policy set" policy=shared Apr 18 00:38:40.491775 containerd[1491]: time="2026-04-18T00:38:40.491744496Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 18 00:38:40.491924 containerd[1491]: time="2026-04-18T00:38:40.491815916Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 18 00:38:40.491924 containerd[1491]: time="2026-04-18T00:38:40.491842432Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Apr 18 00:38:40.491924 containerd[1491]: time="2026-04-18T00:38:40.491883379Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Apr 18 00:38:40.492040 containerd[1491]: time="2026-04-18T00:38:40.491919216Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 18 00:38:40.492338 containerd[1491]: time="2026-04-18T00:38:40.492155167Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 18 00:38:40.492543 containerd[1491]: time="2026-04-18T00:38:40.492517948Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 18 00:38:40.492704 containerd[1491]: time="2026-04-18T00:38:40.492681275Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Apr 18 00:38:40.492773 containerd[1491]: time="2026-04-18T00:38:40.492712477Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Apr 18 00:38:40.492773 containerd[1491]: time="2026-04-18T00:38:40.492733311Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Apr 18 00:38:40.492773 containerd[1491]: time="2026-04-18T00:38:40.492754273Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 18 00:38:40.492878 containerd[1491]: time="2026-04-18T00:38:40.492779930Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 18 00:38:40.492878 containerd[1491]: time="2026-04-18T00:38:40.492804959Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 18 00:38:40.492878 containerd[1491]: time="2026-04-18T00:38:40.492825270Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 18 00:38:40.492878 containerd[1491]: time="2026-04-18T00:38:40.492844723Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 18 00:38:40.492878 containerd[1491]: time="2026-04-18T00:38:40.492862988Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 18 00:38:40.493052 containerd[1491]: time="2026-04-18T00:38:40.492883801Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 18 00:38:40.493052 containerd[1491]: time="2026-04-18T00:38:40.492902191Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 18 00:38:40.493052 containerd[1491]: time="2026-04-18T00:38:40.492939682Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493052 containerd[1491]: time="2026-04-18T00:38:40.492974619Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493052 containerd[1491]: time="2026-04-18T00:38:40.492991487Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493236 containerd[1491]: time="2026-04-18T00:38:40.493054132Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493236 containerd[1491]: time="2026-04-18T00:38:40.493080546Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493236 containerd[1491]: time="2026-04-18T00:38:40.493145595Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493236 containerd[1491]: time="2026-04-18T00:38:40.493167138Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493236 containerd[1491]: time="2026-04-18T00:38:40.493206509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493390 containerd[1491]: time="2026-04-18T00:38:40.493243216Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493390 containerd[1491]: time="2026-04-18T00:38:40.493263666Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493390 containerd[1491]: time="2026-04-18T00:38:40.493323331Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493390 containerd[1491]: time="2026-04-18T00:38:40.493360358Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493510 containerd[1491]: time="2026-04-18T00:38:40.493405487Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493510 containerd[1491]: time="2026-04-18T00:38:40.493427862Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Apr 18 00:38:40.493510 containerd[1491]: time="2026-04-18T00:38:40.493482150Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493605 containerd[1491]: time="2026-04-18T00:38:40.493548553Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493605 containerd[1491]: time="2026-04-18T00:38:40.493572611Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 18 00:38:40.493707 containerd[1491]: time="2026-04-18T00:38:40.493660519Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 18 00:38:40.493743 containerd[1491]: time="2026-04-18T00:38:40.493719188Z" 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 Apr 18 00:38:40.493743 containerd[1491]: time="2026-04-18T00:38:40.493739199Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 18 00:38:40.493820 containerd[1491]: time="2026-04-18T00:38:40.493758933Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Apr 18 00:38:40.493820 containerd[1491]: time="2026-04-18T00:38:40.493791506Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.493879 containerd[1491]: time="2026-04-18T00:38:40.493817498Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Apr 18 00:38:40.493879 containerd[1491]: time="2026-04-18T00:38:40.493837817Z" level=info msg="NRI interface is disabled by configuration." Apr 18 00:38:40.493879 containerd[1491]: time="2026-04-18T00:38:40.493870781Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 18 00:38:40.498042 containerd[1491]: time="2026-04-18T00:38:40.494385337Z" 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}" Apr 18 00:38:40.498042 containerd[1491]: time="2026-04-18T00:38:40.494501977Z" level=info msg="Connect containerd service" Apr 18 00:38:40.498042 containerd[1491]: time="2026-04-18T00:38:40.494564017Z" level=info msg="using legacy CRI server" Apr 18 00:38:40.498042 containerd[1491]: time="2026-04-18T00:38:40.494597083Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Apr 18 00:38:40.498042 containerd[1491]: time="2026-04-18T00:38:40.494816525Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 18 00:38:40.498042 containerd[1491]: time="2026-04-18T00:38:40.496936006Z" 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" Apr 18 00:38:40.498551 containerd[1491]: time="2026-04-18T00:38:40.498164999Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 18 00:38:40.498551 containerd[1491]: time="2026-04-18T00:38:40.498269457Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 18 00:38:40.498551 containerd[1491]: time="2026-04-18T00:38:40.498355771Z" level=info msg="Start subscribing containerd event" Apr 18 00:38:40.498551 containerd[1491]: time="2026-04-18T00:38:40.498406710Z" level=info msg="Start recovering state" Apr 18 00:38:40.498551 containerd[1491]: time="2026-04-18T00:38:40.498499261Z" level=info msg="Start event monitor" Apr 18 00:38:40.498551 containerd[1491]: time="2026-04-18T00:38:40.498529807Z" level=info msg="Start snapshots syncer" Apr 18 00:38:40.498740 containerd[1491]: time="2026-04-18T00:38:40.498550406Z" level=info msg="Start cni network conf syncer for default" Apr 18 00:38:40.498740 containerd[1491]: time="2026-04-18T00:38:40.498564553Z" level=info msg="Start streaming server" Apr 18 00:38:40.498857 systemd[1]: Started containerd.service - containerd container runtime. Apr 18 00:38:40.501860 containerd[1491]: time="2026-04-18T00:38:40.501802011Z" level=info msg="containerd successfully booted in 0.055537s" Apr 18 00:38:40.569881 sshd_keygen[1504]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 18 00:38:40.601488 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Apr 18 00:38:40.611503 systemd[1]: Starting issuegen.service - Generate /run/issue... Apr 18 00:38:40.617329 systemd[1]: Started sshd@0-10.230.47.182:22-4.175.71.9:57560.service - OpenSSH per-connection server daemon (4.175.71.9:57560). Apr 18 00:38:40.630489 systemd[1]: issuegen.service: Deactivated successfully. Apr 18 00:38:40.630848 systemd[1]: Finished issuegen.service - Generate /run/issue. Apr 18 00:38:40.641450 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Apr 18 00:38:40.658859 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Apr 18 00:38:40.667586 systemd[1]: Started getty@tty1.service - Getty on tty1. Apr 18 00:38:40.671318 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Apr 18 00:38:40.672551 systemd[1]: Reached target getty.target - Login Prompts. Apr 18 00:38:40.767615 sshd[1564]: Accepted publickey for core from 4.175.71.9 port 57560 ssh2: RSA SHA256:whbN8rz0V69lTASVYUI8hp7QVnV+OlGZN00Yaq8px5s Apr 18 00:38:40.771110 sshd[1564]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 18 00:38:40.789066 systemd-logind[1478]: New session 1 of user core. Apr 18 00:38:40.792309 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Apr 18 00:38:40.798547 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Apr 18 00:38:40.830145 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Apr 18 00:38:40.838583 systemd[1]: Starting user@500.service - User Manager for UID 500... Apr 18 00:38:40.854793 (systemd)[1576]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 18 00:38:40.931394 systemd-networkd[1416]: eth0: Gained IPv6LL Apr 18 00:38:40.939295 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Apr 18 00:38:40.947602 systemd[1]: Reached target network-online.target - Network is Online. Apr 18 00:38:40.957568 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 18 00:38:40.963634 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Apr 18 00:38:41.016507 systemd[1576]: Queued start job for default target default.target. Apr 18 00:38:41.023763 systemd[1576]: Created slice app.slice - User Application Slice. Apr 18 00:38:41.023802 systemd[1576]: Reached target paths.target - Paths. Apr 18 00:38:41.023836 systemd[1576]: Reached target timers.target - Timers. Apr 18 00:38:41.027186 systemd[1576]: Starting dbus.socket - D-Bus User Message Bus Socket... Apr 18 00:38:41.033266 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Apr 18 00:38:41.057170 systemd[1576]: Listening on dbus.socket - D-Bus User Message Bus Socket. Apr 18 00:38:41.057393 systemd[1576]: Reached target sockets.target - Sockets. Apr 18 00:38:41.057419 systemd[1576]: Reached target basic.target - Basic System. Apr 18 00:38:41.057480 systemd[1576]: Reached target default.target - Main User Target. Apr 18 00:38:41.057543 systemd[1576]: Startup finished in 192ms. Apr 18 00:38:41.058124 systemd[1]: Started user@500.service - User Manager for UID 500. Apr 18 00:38:41.068319 systemd[1]: Started session-1.scope - Session 1 of User core. Apr 18 00:38:41.205432 systemd[1]: Started sshd@1-10.230.47.182:22-4.175.71.9:57566.service - OpenSSH per-connection server daemon (4.175.71.9:57566). Apr 18 00:38:41.338727 sshd[1598]: Accepted publickey for core from 4.175.71.9 port 57566 ssh2: RSA SHA256:whbN8rz0V69lTASVYUI8hp7QVnV+OlGZN00Yaq8px5s Apr 18 00:38:41.340233 sshd[1598]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 18 00:38:41.348685 systemd-logind[1478]: New session 2 of user core. Apr 18 00:38:41.354766 systemd[1]: Started session-2.scope - Session 2 of User core. Apr 18 00:38:41.464755 sshd[1598]: pam_unix(sshd:session): session closed for user core Apr 18 00:38:41.471263 systemd[1]: sshd@1-10.230.47.182:22-4.175.71.9:57566.service: Deactivated successfully. Apr 18 00:38:41.472218 systemd-logind[1478]: Session 2 logged out. Waiting for processes to exit. Apr 18 00:38:41.476317 systemd[1]: session-2.scope: Deactivated successfully. Apr 18 00:38:41.478563 systemd-logind[1478]: Removed session 2. Apr 18 00:38:41.497499 systemd[1]: Started sshd@2-10.230.47.182:22-4.175.71.9:57582.service - OpenSSH per-connection server daemon (4.175.71.9:57582). Apr 18 00:38:41.631594 sshd[1606]: Accepted publickey for core from 4.175.71.9 port 57582 ssh2: RSA SHA256:whbN8rz0V69lTASVYUI8hp7QVnV+OlGZN00Yaq8px5s Apr 18 00:38:41.634944 sshd[1606]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 18 00:38:41.642875 systemd-logind[1478]: New session 3 of user core. Apr 18 00:38:41.657593 systemd[1]: Started session-3.scope - Session 3 of User core. Apr 18 00:38:41.764036 sshd[1606]: pam_unix(sshd:session): session closed for user core Apr 18 00:38:41.770393 systemd[1]: sshd@2-10.230.47.182:22-4.175.71.9:57582.service: Deactivated successfully. Apr 18 00:38:41.773442 systemd[1]: session-3.scope: Deactivated successfully. Apr 18 00:38:41.774657 systemd-logind[1478]: Session 3 logged out. Waiting for processes to exit. Apr 18 00:38:41.777033 systemd-logind[1478]: Removed session 3. Apr 18 00:38:41.952752 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 18 00:38:41.962473 (kubelet)[1617]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 18 00:38:42.372669 systemd-networkd[1416]: eth0: Ignoring DHCPv6 address 2a02:1348:179:8bed:24:19ff:fee6:2fb6/128 (valid for 59min 59s, preferred for 59min 59s) which conflicts with 2a02:1348:179:8bed:24:19ff:fee6:2fb6/64 assigned by NDisc. Apr 18 00:38:42.372682 systemd-networkd[1416]: eth0: Hint: use IPv6Token= setting to change the address generated by NDisc or set UseAutonomousPrefix=no. Apr 18 00:38:42.506933 kubelet[1617]: E0418 00:38:42.506834 1617 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" Apr 18 00:38:42.513493 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 18 00:38:42.513804 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 18 00:38:45.710707 login[1572]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 18 00:38:45.723916 systemd-logind[1478]: New session 4 of user core. Apr 18 00:38:45.734703 login[1571]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 18 00:38:45.736488 systemd[1]: Started session-4.scope - Session 4 of User core. Apr 18 00:38:45.748385 systemd-logind[1478]: New session 5 of user core. Apr 18 00:38:45.757627 systemd[1]: Started session-5.scope - Session 5 of User core. Apr 18 00:38:46.850075 coreos-metadata[1469]: Apr 18 00:38:46.849 WARN failed to locate config-drive, using the metadata service API instead Apr 18 00:38:46.875278 coreos-metadata[1469]: Apr 18 00:38:46.875 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1 Apr 18 00:38:46.884935 coreos-metadata[1469]: Apr 18 00:38:46.884 INFO Fetch failed with 404: resource not found Apr 18 00:38:46.884935 coreos-metadata[1469]: Apr 18 00:38:46.884 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Apr 18 00:38:46.885421 coreos-metadata[1469]: Apr 18 00:38:46.885 INFO Fetch successful Apr 18 00:38:46.885654 coreos-metadata[1469]: Apr 18 00:38:46.885 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 Apr 18 00:38:46.898137 coreos-metadata[1469]: Apr 18 00:38:46.898 INFO Fetch successful Apr 18 00:38:46.898528 coreos-metadata[1469]: Apr 18 00:38:46.898 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 Apr 18 00:38:46.913388 coreos-metadata[1469]: Apr 18 00:38:46.913 INFO Fetch successful Apr 18 00:38:46.913681 coreos-metadata[1469]: Apr 18 00:38:46.913 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 Apr 18 00:38:46.927613 coreos-metadata[1469]: Apr 18 00:38:46.927 INFO Fetch successful Apr 18 00:38:46.927970 coreos-metadata[1469]: Apr 18 00:38:46.927 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 Apr 18 00:38:46.945485 coreos-metadata[1469]: Apr 18 00:38:46.945 INFO Fetch successful Apr 18 00:38:46.976695 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Apr 18 00:38:46.978659 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Apr 18 00:38:47.348246 coreos-metadata[1542]: Apr 18 00:38:47.348 WARN failed to locate config-drive, using the metadata service API instead Apr 18 00:38:47.370338 coreos-metadata[1542]: Apr 18 00:38:47.370 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 Apr 18 00:38:47.392693 coreos-metadata[1542]: Apr 18 00:38:47.392 INFO Fetch successful Apr 18 00:38:47.392900 coreos-metadata[1542]: Apr 18 00:38:47.392 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 Apr 18 00:38:47.420201 coreos-metadata[1542]: Apr 18 00:38:47.420 INFO Fetch successful Apr 18 00:38:47.428819 unknown[1542]: wrote ssh authorized keys file for user: core Apr 18 00:38:47.461984 update-ssh-keys[1662]: Updated "/home/core/.ssh/authorized_keys" Apr 18 00:38:47.464116 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Apr 18 00:38:47.467415 systemd[1]: Finished sshkeys.service. Apr 18 00:38:47.468955 systemd[1]: Reached target multi-user.target - Multi-User System. Apr 18 00:38:47.469609 systemd[1]: Startup finished in 1.423s (kernel) + 13.600s (initrd) + 11.642s (userspace) = 26.666s. Apr 18 00:38:51.810518 systemd[1]: Started sshd@3-10.230.47.182:22-4.175.71.9:33332.service - OpenSSH per-connection server daemon (4.175.71.9:33332). Apr 18 00:38:51.936166 sshd[1666]: Accepted publickey for core from 4.175.71.9 port 33332 ssh2: RSA SHA256:whbN8rz0V69lTASVYUI8hp7QVnV+OlGZN00Yaq8px5s Apr 18 00:38:51.938733 sshd[1666]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 18 00:38:51.946841 systemd-logind[1478]: New session 6 of user core. Apr 18 00:38:51.954278 systemd[1]: Started session-6.scope - Session 6 of User core. Apr 18 00:38:52.060726 sshd[1666]: pam_unix(sshd:session): session closed for user core Apr 18 00:38:52.066313 systemd[1]: sshd@3-10.230.47.182:22-4.175.71.9:33332.service: Deactivated successfully. Apr 18 00:38:52.069430 systemd[1]: session-6.scope: Deactivated successfully. Apr 18 00:38:52.070498 systemd-logind[1478]: Session 6 logged out. Waiting for processes to exit. Apr 18 00:38:52.071843 systemd-logind[1478]: Removed session 6. Apr 18 00:38:52.087118 systemd[1]: Started sshd@4-10.230.47.182:22-4.175.71.9:33334.service - OpenSSH per-connection server daemon (4.175.71.9:33334). Apr 18 00:38:52.225805 sshd[1673]: Accepted publickey for core from 4.175.71.9 port 33334 ssh2: RSA SHA256:whbN8rz0V69lTASVYUI8hp7QVnV+OlGZN00Yaq8px5s Apr 18 00:38:52.228007 sshd[1673]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 18 00:38:52.236305 systemd-logind[1478]: New session 7 of user core. Apr 18 00:38:52.243263 systemd[1]: Started session-7.scope - Session 7 of User core. Apr 18 00:38:52.341805 sshd[1673]: pam_unix(sshd:session): session closed for user core Apr 18 00:38:52.347654 systemd[1]: sshd@4-10.230.47.182:22-4.175.71.9:33334.service: Deactivated successfully. Apr 18 00:38:52.350178 systemd[1]: session-7.scope: Deactivated successfully. Apr 18 00:38:52.351956 systemd-logind[1478]: Session 7 logged out. Waiting for processes to exit. Apr 18 00:38:52.353503 systemd-logind[1478]: Removed session 7. Apr 18 00:38:52.376406 systemd[1]: Started sshd@5-10.230.47.182:22-4.175.71.9:33336.service - OpenSSH per-connection server daemon (4.175.71.9:33336). Apr 18 00:38:52.504071 sshd[1680]: Accepted publickey for core from 4.175.71.9 port 33336 ssh2: RSA SHA256:whbN8rz0V69lTASVYUI8hp7QVnV+OlGZN00Yaq8px5s Apr 18 00:38:52.505655 sshd[1680]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 18 00:38:52.514822 systemd-logind[1478]: New session 8 of user core. Apr 18 00:38:52.525465 systemd[1]: Started session-8.scope - Session 8 of User core. Apr 18 00:38:52.526804 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Apr 18 00:38:52.535523 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 18 00:38:52.645365 sshd[1680]: pam_unix(sshd:session): session closed for user core Apr 18 00:38:52.650304 systemd-logind[1478]: Session 8 logged out. Waiting for processes to exit. Apr 18 00:38:52.651411 systemd[1]: sshd@5-10.230.47.182:22-4.175.71.9:33336.service: Deactivated successfully. Apr 18 00:38:52.654584 systemd[1]: session-8.scope: Deactivated successfully. Apr 18 00:38:52.657574 systemd-logind[1478]: Removed session 8. Apr 18 00:38:52.673151 systemd[1]: Started sshd@6-10.230.47.182:22-4.175.71.9:33338.service - OpenSSH per-connection server daemon (4.175.71.9:33338). Apr 18 00:38:52.736779 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 18 00:38:52.743259 (kubelet)[1697]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Apr 18 00:38:52.803961 sshd[1690]: Accepted publickey for core from 4.175.71.9 port 33338 ssh2: RSA SHA256:whbN8rz0V69lTASVYUI8hp7QVnV+OlGZN00Yaq8px5s Apr 18 00:38:52.805915 sshd[1690]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Apr 18 00:38:52.813261 systemd-logind[1478]: New session 9 of user core. Apr 18 00:38:52.819118 systemd[1]: Started session-9.scope - Session 9 of User core. Apr 18 00:38:52.850128 kubelet[1697]: E0418 00:38:52.850050 1697 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" Apr 18 00:38:52.854292 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Apr 18 00:38:52.854561 systemd[1]: kubelet.service: Failed with result 'exit-code'. Apr 18 00:38:52.919981 sudo[1707]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Apr 18 00:38:52.921199 sudo[1707]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Apr 18 00:38:53.631640 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 18 00:38:53.647398 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 18 00:38:53.686166 systemd[1]: Reloading requested from client PID 1740 ('systemctl') (unit session-9.scope)... Apr 18 00:38:53.686423 systemd[1]: Reloading... Apr 18 00:38:53.835046 zram_generator::config[1779]: No configuration found. Apr 18 00:38:54.004017 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. Apr 18 00:38:54.109808 systemd[1]: Reloading finished in 422 ms. Apr 18 00:38:54.187298 systemd[1]: Stopping kubelet.service - kubelet: The Kubernetes Node Agent... Apr 18 00:38:54.191925 systemd[1]: kubelet.service: Deactivated successfully. Apr 18 00:38:54.192469 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Apr 18 00:38:54.198659 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Apr 18 00:38:54.353257 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Apr 18 00:38:54.365544 (kubelet)[1848]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Apr 18 00:38:54.420533 kubelet[1848]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Apr 18 00:38:54.420533 kubelet[1848]: 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. Apr 18 00:38:54.421107 kubelet[1848]: I0418 00:38:54.420647 1848 server.go:213] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Apr 18 00:38:55.320650 kubelet[1848]: I0418 00:38:55.320551 1848 server.go:529] "Kubelet version" kubeletVersion="v1.34.4" Apr 18 00:38:55.322041 kubelet[1848]: I0418 00:38:55.320901 1848 server.go:531] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Apr 18 00:38:55.322041 kubelet[1848]: I0418 00:38:55.321002 1848 watchdog_linux.go:95] "Systemd watchdog is not enabled" Apr 18 00:38:55.322041 kubelet[1848]: I0418 00:38:55.321041 1848 watchdog_linux.go:137] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Apr 18 00:38:55.322041 kubelet[1848]: I0418 00:38:55.321307 1848 server.go:956] "Client rotation is on, will bootstrap in background" Apr 18 00:38:55.328389 kubelet[1848]: I0418 00:38:55.328285 1848 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Apr 18 00:38:55.338489 kubelet[1848]: E0418 00:38:55.338355 1848 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Apr 18 00:38:55.338489 kubelet[1848]: I0418 00:38:55.338461 1848 server.go:1400] "CRI implementation should be updated to support RuntimeConfig. Falling back to using cgroupDriver from kubelet config." Apr 18 00:38:55.346681 kubelet[1848]: I0418 00:38:55.346129 1848 server.go:781] "--cgroups-per-qos enabled, but --cgroup-root was not specified. Defaulting to /" Apr 18 00:38:55.348083 kubelet[1848]: I0418 00:38:55.347820 1848 container_manager_linux.go:270] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Apr 18 00:38:55.348163 kubelet[1848]: I0418 00:38:55.347858 1848 container_manager_linux.go:275] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.230.47.182","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,"MemoryManagerPolicy":"None","MemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Apr 18 00:38:55.348422 kubelet[1848]: I0418 00:38:55.348185 1848 topology_manager.go:138] "Creating topology manager with none policy" Apr 18 00:38:55.348422 kubelet[1848]: I0418 00:38:55.348202 1848 container_manager_linux.go:306] "Creating device plugin manager" Apr 18 00:38:55.348422 kubelet[1848]: I0418 00:38:55.348375 1848 container_manager_linux.go:315] "Creating Dynamic Resource Allocation (DRA) manager" Apr 18 00:38:55.350857 kubelet[1848]: I0418 00:38:55.350648 1848 state_mem.go:36] "Initialized new in-memory state store" Apr 18 00:38:55.350985 kubelet[1848]: I0418 00:38:55.350960 1848 kubelet.go:475] "Attempting to sync node with API server" Apr 18 00:38:55.351096 kubelet[1848]: I0418 00:38:55.350999 1848 kubelet.go:376] "Adding static pod path" path="/etc/kubernetes/manifests" Apr 18 00:38:55.351096 kubelet[1848]: I0418 00:38:55.351067 1848 kubelet.go:387] "Adding apiserver pod source" Apr 18 00:38:55.351186 kubelet[1848]: I0418 00:38:55.351109 1848 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Apr 18 00:38:55.351966 kubelet[1848]: E0418 00:38:55.351928 1848 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:38:55.352918 kubelet[1848]: E0418 00:38:55.352107 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:38:55.353421 kubelet[1848]: I0418 00:38:55.353399 1848 kuberuntime_manager.go:291] "Container runtime initialized" containerRuntime="containerd" version="v1.7.21" apiVersion="v1" Apr 18 00:38:55.354419 kubelet[1848]: I0418 00:38:55.354382 1848 kubelet.go:940] "Not starting ClusterTrustBundle informer because we are in static kubelet mode or the ClusterTrustBundleProjection featuregate is disabled" Apr 18 00:38:55.354575 kubelet[1848]: I0418 00:38:55.354556 1848 kubelet.go:964] "Not starting PodCertificateRequest manager because we are in static kubelet mode or the PodCertificateProjection feature gate is disabled" Apr 18 00:38:55.354784 kubelet[1848]: W0418 00:38:55.354766 1848 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Apr 18 00:38:55.359817 kubelet[1848]: I0418 00:38:55.359790 1848 server.go:1262] "Started kubelet" Apr 18 00:38:55.364362 kubelet[1848]: I0418 00:38:55.364341 1848 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Apr 18 00:38:55.368520 kubelet[1848]: E0418 00:38:55.368456 1848 reflector.go:205] "Failed to watch" err="failed to list *v1.Node: nodes \"10.230.47.182\" is forbidden: User \"system:anonymous\" cannot list resource \"nodes\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Node" Apr 18 00:38:55.369041 kubelet[1848]: E0418 00:38:55.368811 1848 reflector.go:205] "Failed to watch" err="failed to list *v1.Service: services is forbidden: User \"system:anonymous\" cannot list resource \"services\" in API group \"\" at the cluster scope" logger="UnhandledError" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.Service" Apr 18 00:38:55.372626 kubelet[1848]: I0418 00:38:55.372591 1848 server.go:180] "Starting to listen" address="0.0.0.0" port=10250 Apr 18 00:38:55.374829 kubelet[1848]: I0418 00:38:55.374785 1848 server.go:310] "Adding debug handlers to kubelet server" Apr 18 00:38:55.379768 kubelet[1848]: I0418 00:38:55.379726 1848 ratelimit.go:56] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Apr 18 00:38:55.379965 kubelet[1848]: I0418 00:38:55.379922 1848 server_v1.go:49] "podresources" method="list" useActivePods=true Apr 18 00:38:55.380413 kubelet[1848]: I0418 00:38:55.380395 1848 server.go:249] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Apr 18 00:38:55.380546 kubelet[1848]: I0418 00:38:55.380522 1848 desired_state_of_world_populator.go:146] "Desired state populator starts to run" Apr 18 00:38:55.380732 kubelet[1848]: I0418 00:38:55.380486 1848 volume_manager.go:313] "Starting Kubelet Volume Manager" Apr 18 00:38:55.385356 kubelet[1848]: E0418 00:38:55.380729 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:55.385683 kubelet[1848]: I0418 00:38:55.381432 1848 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Apr 18 00:38:55.386880 kubelet[1848]: I0418 00:38:55.386378 1848 reconciler.go:29] "Reconciler: start to sync state" Apr 18 00:38:55.386880 kubelet[1848]: E0418 00:38:55.381663 1848 kubelet.go:1615] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Apr 18 00:38:55.387720 kubelet[1848]: I0418 00:38:55.387696 1848 factory.go:223] Registration of the systemd container factory successfully Apr 18 00:38:55.387911 kubelet[1848]: I0418 00:38:55.387884 1848 factory.go:221] 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 Apr 18 00:38:55.390488 kubelet[1848]: I0418 00:38:55.390467 1848 factory.go:223] Registration of the containerd container factory successfully Apr 18 00:38:55.405835 kubelet[1848]: E0418 00:38:55.405688 1848 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.230.47.182\" not found" node="10.230.47.182" Apr 18 00:38:55.433674 kubelet[1848]: I0418 00:38:55.433640 1848 cpu_manager.go:221] "Starting CPU manager" policy="none" Apr 18 00:38:55.433674 kubelet[1848]: I0418 00:38:55.433663 1848 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Apr 18 00:38:55.435049 kubelet[1848]: I0418 00:38:55.434612 1848 state_mem.go:36] "Initialized new in-memory state store" Apr 18 00:38:55.438612 kubelet[1848]: I0418 00:38:55.438588 1848 policy_none.go:49] "None policy: Start" Apr 18 00:38:55.438695 kubelet[1848]: I0418 00:38:55.438664 1848 memory_manager.go:187] "Starting memorymanager" policy="None" Apr 18 00:38:55.438742 kubelet[1848]: I0418 00:38:55.438711 1848 state_mem.go:36] "Initializing new in-memory state store" logger="Memory Manager state checkpoint" Apr 18 00:38:55.440443 kubelet[1848]: I0418 00:38:55.440360 1848 policy_none.go:47] "Start" Apr 18 00:38:55.450004 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Apr 18 00:38:55.466222 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Apr 18 00:38:55.473212 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Apr 18 00:38:55.485361 kubelet[1848]: E0418 00:38:55.485310 1848 manager.go:513] "Failed to read data from checkpoint" err="checkpoint is not found" checkpoint="kubelet_internal_checkpoint" Apr 18 00:38:55.488034 kubelet[1848]: I0418 00:38:55.485641 1848 eviction_manager.go:189] "Eviction manager: starting control loop" Apr 18 00:38:55.488034 kubelet[1848]: E0418 00:38:55.485650 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:55.488034 kubelet[1848]: I0418 00:38:55.485687 1848 container_log_manager.go:146] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Apr 18 00:38:55.488034 kubelet[1848]: I0418 00:38:55.486116 1848 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Apr 18 00:38:55.491306 kubelet[1848]: E0418 00:38:55.491280 1848 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Apr 18 00:38:55.491378 kubelet[1848]: E0418 00:38:55.491341 1848 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.230.47.182\" not found" Apr 18 00:38:55.506209 kubelet[1848]: I0418 00:38:55.505978 1848 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv4" Apr 18 00:38:55.507646 kubelet[1848]: I0418 00:38:55.507625 1848 kubelet_network_linux.go:54] "Initialized iptables rules." protocol="IPv6" Apr 18 00:38:55.507750 kubelet[1848]: I0418 00:38:55.507733 1848 status_manager.go:244] "Starting to sync pod status with apiserver" Apr 18 00:38:55.507912 kubelet[1848]: I0418 00:38:55.507884 1848 kubelet.go:2428] "Starting kubelet main sync loop" Apr 18 00:38:55.509275 kubelet[1848]: E0418 00:38:55.508495 1848 kubelet.go:2452] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Apr 18 00:38:55.587650 kubelet[1848]: I0418 00:38:55.587516 1848 kubelet_node_status.go:75] "Attempting to register node" node="10.230.47.182" Apr 18 00:38:55.595964 kubelet[1848]: I0418 00:38:55.595931 1848 kubelet_node_status.go:78] "Successfully registered node" node="10.230.47.182" Apr 18 00:38:55.596102 kubelet[1848]: E0418 00:38:55.595972 1848 kubelet_node_status.go:486] "Error updating node status, will retry" err="error getting node \"10.230.47.182\": node \"10.230.47.182\" not found" Apr 18 00:38:55.615126 kubelet[1848]: E0418 00:38:55.615088 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:55.715826 kubelet[1848]: E0418 00:38:55.715738 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:55.816582 kubelet[1848]: E0418 00:38:55.816503 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:55.874416 sudo[1707]: pam_unix(sudo:session): session closed for user root Apr 18 00:38:55.891452 sshd[1690]: pam_unix(sshd:session): session closed for user core Apr 18 00:38:55.896637 systemd-logind[1478]: Session 9 logged out. Waiting for processes to exit. Apr 18 00:38:55.898153 systemd[1]: sshd@6-10.230.47.182:22-4.175.71.9:33338.service: Deactivated successfully. Apr 18 00:38:55.901120 systemd[1]: session-9.scope: Deactivated successfully. Apr 18 00:38:55.903487 systemd-logind[1478]: Removed session 9. Apr 18 00:38:55.917670 kubelet[1848]: E0418 00:38:55.917628 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:56.018725 kubelet[1848]: E0418 00:38:56.018639 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:56.119502 kubelet[1848]: E0418 00:38:56.119419 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:56.220391 kubelet[1848]: E0418 00:38:56.220171 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:56.321194 kubelet[1848]: E0418 00:38:56.321108 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:56.324535 kubelet[1848]: I0418 00:38:56.324455 1848 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Apr 18 00:38:56.324808 kubelet[1848]: I0418 00:38:56.324746 1848 reflector.go:571] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.RuntimeClass" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Apr 18 00:38:56.324808 kubelet[1848]: I0418 00:38:56.324746 1848 reflector.go:571] "Warning: watch ended with error" reflector="k8s.io/client-go/informers/factory.go:160" type="*v1.CSIDriver" err="very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received" Apr 18 00:38:56.352786 kubelet[1848]: E0418 00:38:56.352708 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:38:56.422090 kubelet[1848]: E0418 00:38:56.422044 1848 kubelet_node_status.go:404] "Error getting the current node from lister" err="node \"10.230.47.182\" not found" Apr 18 00:38:56.524175 kubelet[1848]: I0418 00:38:56.523393 1848 kuberuntime_manager.go:1828] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Apr 18 00:38:56.524679 containerd[1491]: time="2026-04-18T00:38:56.523926515Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Apr 18 00:38:56.526169 kubelet[1848]: I0418 00:38:56.525495 1848 kubelet_network.go:47] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Apr 18 00:38:57.353141 kubelet[1848]: E0418 00:38:57.353088 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:38:57.353387 kubelet[1848]: I0418 00:38:57.353165 1848 apiserver.go:52] "Watching apiserver" Apr 18 00:38:57.375159 systemd[1]: Created slice kubepods-besteffort-pod7c014a72_370c_4959_bf6d_05a90b9962d1.slice - libcontainer container kubepods-besteffort-pod7c014a72_370c_4959_bf6d_05a90b9962d1.slice. Apr 18 00:38:57.379956 systemd[1]: Created slice kubepods-burstable-pod6811fc54_d3ee_48d4_95e7_06ef3ade0572.slice - libcontainer container kubepods-burstable-pod6811fc54_d3ee_48d4_95e7_06ef3ade0572.slice. Apr 18 00:38:57.381160 kubelet[1848]: I0418 00:38:57.380794 1848 desired_state_of_world_populator.go:154] "Finished populating initial desired state of world" Apr 18 00:38:57.402590 kubelet[1848]: I0418 00:38:57.402550 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/7c014a72-370c-4959-bf6d-05a90b9962d1-kube-proxy\") pod \"kube-proxy-b8c4k\" (UID: \"7c014a72-370c-4959-bf6d-05a90b9962d1\") " pod="kube-system/kube-proxy-b8c4k" Apr 18 00:38:57.402832 kubelet[1848]: I0418 00:38:57.402808 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/7c014a72-370c-4959-bf6d-05a90b9962d1-xtables-lock\") pod \"kube-proxy-b8c4k\" (UID: \"7c014a72-370c-4959-bf6d-05a90b9962d1\") " pod="kube-system/kube-proxy-b8c4k" Apr 18 00:38:57.402972 kubelet[1848]: I0418 00:38:57.402949 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/7c014a72-370c-4959-bf6d-05a90b9962d1-lib-modules\") pod \"kube-proxy-b8c4k\" (UID: \"7c014a72-370c-4959-bf6d-05a90b9962d1\") " pod="kube-system/kube-proxy-b8c4k" Apr 18 00:38:57.403128 kubelet[1848]: I0418 00:38:57.403106 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/6811fc54-d3ee-48d4-95e7-06ef3ade0572-cni-plugin\") pod \"kube-flannel-ds-8ffhw\" (UID: \"6811fc54-d3ee-48d4-95e7-06ef3ade0572\") " pod="kube-flannel/kube-flannel-ds-8ffhw" Apr 18 00:38:57.403259 kubelet[1848]: I0418 00:38:57.403238 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/6811fc54-d3ee-48d4-95e7-06ef3ade0572-flannel-cfg\") pod \"kube-flannel-ds-8ffhw\" (UID: \"6811fc54-d3ee-48d4-95e7-06ef3ade0572\") " pod="kube-flannel/kube-flannel-ds-8ffhw" Apr 18 00:38:57.403420 kubelet[1848]: I0418 00:38:57.403376 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/6811fc54-d3ee-48d4-95e7-06ef3ade0572-xtables-lock\") pod \"kube-flannel-ds-8ffhw\" (UID: \"6811fc54-d3ee-48d4-95e7-06ef3ade0572\") " pod="kube-flannel/kube-flannel-ds-8ffhw" Apr 18 00:38:57.403611 kubelet[1848]: I0418 00:38:57.403583 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-c4w69\" (UniqueName: \"kubernetes.io/projected/6811fc54-d3ee-48d4-95e7-06ef3ade0572-kube-api-access-c4w69\") pod \"kube-flannel-ds-8ffhw\" (UID: \"6811fc54-d3ee-48d4-95e7-06ef3ade0572\") " pod="kube-flannel/kube-flannel-ds-8ffhw" Apr 18 00:38:57.403778 kubelet[1848]: I0418 00:38:57.403747 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-pghtp\" (UniqueName: \"kubernetes.io/projected/7c014a72-370c-4959-bf6d-05a90b9962d1-kube-api-access-pghtp\") pod \"kube-proxy-b8c4k\" (UID: \"7c014a72-370c-4959-bf6d-05a90b9962d1\") " pod="kube-system/kube-proxy-b8c4k" Apr 18 00:38:57.403905 kubelet[1848]: I0418 00:38:57.403874 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/6811fc54-d3ee-48d4-95e7-06ef3ade0572-run\") pod \"kube-flannel-ds-8ffhw\" (UID: \"6811fc54-d3ee-48d4-95e7-06ef3ade0572\") " pod="kube-flannel/kube-flannel-ds-8ffhw" Apr 18 00:38:57.404037 kubelet[1848]: I0418 00:38:57.404004 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/6811fc54-d3ee-48d4-95e7-06ef3ade0572-cni\") pod \"kube-flannel-ds-8ffhw\" (UID: \"6811fc54-d3ee-48d4-95e7-06ef3ade0572\") " pod="kube-flannel/kube-flannel-ds-8ffhw" Apr 18 00:38:57.694732 containerd[1491]: time="2026-04-18T00:38:57.694054589Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-b8c4k,Uid:7c014a72-370c-4959-bf6d-05a90b9962d1,Namespace:kube-system,Attempt:0,}" Apr 18 00:38:57.699475 containerd[1491]: time="2026-04-18T00:38:57.699436647Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-8ffhw,Uid:6811fc54-d3ee-48d4-95e7-06ef3ade0572,Namespace:kube-flannel,Attempt:0,}" Apr 18 00:38:58.146366 systemd[1]: Started sshd@7-10.230.47.182:22-66.132.172.183:35396.service - OpenSSH per-connection server daemon (66.132.172.183:35396). Apr 18 00:38:58.353580 kubelet[1848]: E0418 00:38:58.353515 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:38:58.459112 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount4246993965.mount: Deactivated successfully. Apr 18 00:38:58.467054 containerd[1491]: time="2026-04-18T00:38:58.466736015Z" 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\"}" Apr 18 00:38:58.468513 containerd[1491]: time="2026-04-18T00:38:58.468359071Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Apr 18 00:38:58.468645 containerd[1491]: time="2026-04-18T00:38:58.468513619Z" 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\"}" Apr 18 00:38:58.469705 containerd[1491]: time="2026-04-18T00:38:58.469671404Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312064" Apr 18 00:38:58.470404 containerd[1491]: time="2026-04-18T00:38:58.470294799Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Apr 18 00:38:58.478098 containerd[1491]: time="2026-04-18T00:38:58.476115527Z" 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\"}" Apr 18 00:38:58.479471 containerd[1491]: time="2026-04-18T00:38:58.479085802Z" 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 784.835794ms" Apr 18 00:38:58.480238 containerd[1491]: time="2026-04-18T00:38:58.480192939Z" 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 780.688827ms" Apr 18 00:38:58.654063 containerd[1491]: time="2026-04-18T00:38:58.653920808Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 18 00:38:58.654328 containerd[1491]: time="2026-04-18T00:38:58.654279066Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 18 00:38:58.654644 containerd[1491]: time="2026-04-18T00:38:58.654554495Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:38:58.655836 containerd[1491]: time="2026-04-18T00:38:58.655728489Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 18 00:38:58.657057 containerd[1491]: time="2026-04-18T00:38:58.655696586Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:38:58.658205 containerd[1491]: time="2026-04-18T00:38:58.657995878Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 18 00:38:58.658886 containerd[1491]: time="2026-04-18T00:38:58.658171871Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:38:58.658886 containerd[1491]: time="2026-04-18T00:38:58.658580665Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:38:58.778199 systemd[1]: Started cri-containerd-54e42006e8a27815fac0b107294c76faa9eeb568d92af42a41b29755188d5636.scope - libcontainer container 54e42006e8a27815fac0b107294c76faa9eeb568d92af42a41b29755188d5636. Apr 18 00:38:58.781058 systemd[1]: Started cri-containerd-ebe75edf8824d445154ee74150630c62f1a5add0e69e888226176e40df89b020.scope - libcontainer container ebe75edf8824d445154ee74150630c62f1a5add0e69e888226176e40df89b020. Apr 18 00:38:58.836262 containerd[1491]: time="2026-04-18T00:38:58.836169501Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-b8c4k,Uid:7c014a72-370c-4959-bf6d-05a90b9962d1,Namespace:kube-system,Attempt:0,} returns sandbox id \"ebe75edf8824d445154ee74150630c62f1a5add0e69e888226176e40df89b020\"" Apr 18 00:38:58.843197 containerd[1491]: time="2026-04-18T00:38:58.843143248Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.7\"" Apr 18 00:38:58.881133 containerd[1491]: time="2026-04-18T00:38:58.881033448Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-8ffhw,Uid:6811fc54-d3ee-48d4-95e7-06ef3ade0572,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"54e42006e8a27815fac0b107294c76faa9eeb568d92af42a41b29755188d5636\"" Apr 18 00:38:59.354865 kubelet[1848]: E0418 00:38:59.354733 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:00.355929 kubelet[1848]: E0418 00:39:00.355845 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:00.359802 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2376165587.mount: Deactivated successfully. Apr 18 00:39:00.825719 containerd[1491]: time="2026-04-18T00:39:00.825633581Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.34.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:00.827070 containerd[1491]: time="2026-04-18T00:39:00.826840774Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.34.7: active requests=0, bytes read=25972962" Apr 18 00:39:00.827783 containerd[1491]: time="2026-04-18T00:39:00.827747165Z" level=info msg="ImageCreate event name:\"sha256:345c2b8919907fbb425a843da24d86a16708ee53a49ad3fa2e6dc229c7b34643\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:00.830549 containerd[1491]: time="2026-04-18T00:39:00.830513314Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:062519bc0a14769e2f98c6bdff7816a17e6252de3f3c9cb102e6be33fe38d9e2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:00.831997 containerd[1491]: time="2026-04-18T00:39:00.831824092Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.34.7\" with image id \"sha256:345c2b8919907fbb425a843da24d86a16708ee53a49ad3fa2e6dc229c7b34643\", repo tag \"registry.k8s.io/kube-proxy:v1.34.7\", repo digest \"registry.k8s.io/kube-proxy@sha256:062519bc0a14769e2f98c6bdff7816a17e6252de3f3c9cb102e6be33fe38d9e2\", size \"25971973\" in 1.9886086s" Apr 18 00:39:00.831997 containerd[1491]: time="2026-04-18T00:39:00.831868577Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.34.7\" returns image reference \"sha256:345c2b8919907fbb425a843da24d86a16708ee53a49ad3fa2e6dc229c7b34643\"" Apr 18 00:39:00.837910 containerd[1491]: time="2026-04-18T00:39:00.836942559Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\"" Apr 18 00:39:00.841144 containerd[1491]: time="2026-04-18T00:39:00.841108294Z" level=info msg="CreateContainer within sandbox \"ebe75edf8824d445154ee74150630c62f1a5add0e69e888226176e40df89b020\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Apr 18 00:39:00.859465 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2956455783.mount: Deactivated successfully. Apr 18 00:39:00.862069 containerd[1491]: time="2026-04-18T00:39:00.861948702Z" level=info msg="CreateContainer within sandbox \"ebe75edf8824d445154ee74150630c62f1a5add0e69e888226176e40df89b020\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"cf733c67ebdecbf3db0f5b9eb0759a21ea4fc725ef59c683bfa41a3333c36a97\"" Apr 18 00:39:00.863046 containerd[1491]: time="2026-04-18T00:39:00.862983586Z" level=info msg="StartContainer for \"cf733c67ebdecbf3db0f5b9eb0759a21ea4fc725ef59c683bfa41a3333c36a97\"" Apr 18 00:39:00.915316 systemd[1]: Started cri-containerd-cf733c67ebdecbf3db0f5b9eb0759a21ea4fc725ef59c683bfa41a3333c36a97.scope - libcontainer container cf733c67ebdecbf3db0f5b9eb0759a21ea4fc725ef59c683bfa41a3333c36a97. Apr 18 00:39:00.955888 containerd[1491]: time="2026-04-18T00:39:00.955652294Z" level=info msg="StartContainer for \"cf733c67ebdecbf3db0f5b9eb0759a21ea4fc725ef59c683bfa41a3333c36a97\" returns successfully" Apr 18 00:39:01.356467 kubelet[1848]: E0418 00:39:01.356388 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:01.567597 kubelet[1848]: I0418 00:39:01.567440 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-b8c4k" podStartSLOduration=4.5734818520000005 podStartE2EDuration="6.567409359s" podCreationTimestamp="2026-04-18 00:38:55 +0000 UTC" firstStartedPulling="2026-04-18 00:38:58.841851878 +0000 UTC m=+4.470079450" lastFinishedPulling="2026-04-18 00:39:00.835779372 +0000 UTC m=+6.464006957" observedRunningTime="2026-04-18 00:39:01.566999228 +0000 UTC m=+7.195226825" watchObservedRunningTime="2026-04-18 00:39:01.567409359 +0000 UTC m=+7.195636938" Apr 18 00:39:02.195673 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3366138146.mount: Deactivated successfully. Apr 18 00:39:02.267347 containerd[1491]: time="2026-04-18T00:39:02.267082601Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:02.268619 containerd[1491]: time="2026-04-18T00:39:02.268553234Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1: active requests=0, bytes read=4857008" Apr 18 00:39:02.270559 containerd[1491]: time="2026-04-18T00:39:02.269921806Z" level=info msg="ImageCreate event name:\"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:02.273392 containerd[1491]: time="2026-04-18T00:39:02.273324057Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:02.276204 containerd[1491]: time="2026-04-18T00:39:02.275046139Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" with image id \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\", repo tag \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\", repo digest \"ghcr.io/flannel-io/flannel-cni-plugin@sha256:f1812994f0edbcb5bb5ccb63be2147ba6ad10e1faaa7ca9fcdad4f441739d84f\", size \"4856838\" in 1.438055814s" Apr 18 00:39:02.276204 containerd[1491]: time="2026-04-18T00:39:02.275100828Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel-cni-plugin:v1.6.2-flannel1\" returns image reference \"sha256:55ce2385d9d8c6f720091c177fbe885a21c9dc07c9e480bfb4d94b3001f58182\"" Apr 18 00:39:02.280499 containerd[1491]: time="2026-04-18T00:39:02.280454764Z" level=info msg="CreateContainer within sandbox \"54e42006e8a27815fac0b107294c76faa9eeb568d92af42a41b29755188d5636\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Apr 18 00:39:02.294683 containerd[1491]: time="2026-04-18T00:39:02.294615530Z" level=info msg="CreateContainer within sandbox \"54e42006e8a27815fac0b107294c76faa9eeb568d92af42a41b29755188d5636\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"5d7a43229005642466cd273f8c47a72b18f6ba3ecbed19066e9d6a4c17e4e138\"" Apr 18 00:39:02.297122 containerd[1491]: time="2026-04-18T00:39:02.295890543Z" level=info msg="StartContainer for \"5d7a43229005642466cd273f8c47a72b18f6ba3ecbed19066e9d6a4c17e4e138\"" Apr 18 00:39:02.337265 systemd[1]: Started cri-containerd-5d7a43229005642466cd273f8c47a72b18f6ba3ecbed19066e9d6a4c17e4e138.scope - libcontainer container 5d7a43229005642466cd273f8c47a72b18f6ba3ecbed19066e9d6a4c17e4e138. Apr 18 00:39:02.357679 kubelet[1848]: E0418 00:39:02.357511 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:02.374750 containerd[1491]: time="2026-04-18T00:39:02.373873537Z" level=info msg="StartContainer for \"5d7a43229005642466cd273f8c47a72b18f6ba3ecbed19066e9d6a4c17e4e138\" returns successfully" Apr 18 00:39:02.377260 systemd[1]: cri-containerd-5d7a43229005642466cd273f8c47a72b18f6ba3ecbed19066e9d6a4c17e4e138.scope: Deactivated successfully. Apr 18 00:39:02.630788 containerd[1491]: time="2026-04-18T00:39:02.630670528Z" level=info msg="shim disconnected" id=5d7a43229005642466cd273f8c47a72b18f6ba3ecbed19066e9d6a4c17e4e138 namespace=k8s.io Apr 18 00:39:02.631138 containerd[1491]: time="2026-04-18T00:39:02.630800842Z" level=warning msg="cleaning up after shim disconnected" id=5d7a43229005642466cd273f8c47a72b18f6ba3ecbed19066e9d6a4c17e4e138 namespace=k8s.io Apr 18 00:39:02.631138 containerd[1491]: time="2026-04-18T00:39:02.630823884Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 18 00:39:03.041926 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-5d7a43229005642466cd273f8c47a72b18f6ba3ecbed19066e9d6a4c17e4e138-rootfs.mount: Deactivated successfully. Apr 18 00:39:03.358466 kubelet[1848]: E0418 00:39:03.358253 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:03.558468 containerd[1491]: time="2026-04-18T00:39:03.558119468Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\"" Apr 18 00:39:04.359415 kubelet[1848]: E0418 00:39:04.359336 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:05.360075 kubelet[1848]: E0418 00:39:05.359967 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:06.108884 containerd[1491]: time="2026-04-18T00:39:06.108813360Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel:v0.26.7\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:06.111040 containerd[1491]: time="2026-04-18T00:39:06.110075172Z" level=info msg="stop pulling image ghcr.io/flannel-io/flannel:v0.26.7: active requests=0, bytes read=29354574" Apr 18 00:39:06.111390 containerd[1491]: time="2026-04-18T00:39:06.111345999Z" level=info msg="ImageCreate event name:\"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:06.116664 containerd[1491]: time="2026-04-18T00:39:06.116627707Z" level=info msg="ImageCreate event name:\"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:06.118870 containerd[1491]: time="2026-04-18T00:39:06.118832224Z" level=info msg="Pulled image \"ghcr.io/flannel-io/flannel:v0.26.7\" with image id \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\", repo tag \"ghcr.io/flannel-io/flannel:v0.26.7\", repo digest \"ghcr.io/flannel-io/flannel@sha256:7f471907fa940f944867270de4ed78121b8b4c5d564e17f940dc787cb16dea82\", size \"32996046\" in 2.560656568s" Apr 18 00:39:06.119030 containerd[1491]: time="2026-04-18T00:39:06.118990895Z" level=info msg="PullImage \"ghcr.io/flannel-io/flannel:v0.26.7\" returns image reference \"sha256:965b9dd4aa4c1b6b68a4c54a166692b4645b6e6f8a5937d8dc17736cb63f515e\"" Apr 18 00:39:06.124054 containerd[1491]: time="2026-04-18T00:39:06.124002237Z" level=info msg="CreateContainer within sandbox \"54e42006e8a27815fac0b107294c76faa9eeb568d92af42a41b29755188d5636\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Apr 18 00:39:06.139730 containerd[1491]: time="2026-04-18T00:39:06.139355767Z" level=info msg="CreateContainer within sandbox \"54e42006e8a27815fac0b107294c76faa9eeb568d92af42a41b29755188d5636\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"ce50bd1fa0fda9ef74c1ef978321b2aafbd2c543ca91ee932dd98f75043ef047\"" Apr 18 00:39:06.140286 containerd[1491]: time="2026-04-18T00:39:06.140255704Z" level=info msg="StartContainer for \"ce50bd1fa0fda9ef74c1ef978321b2aafbd2c543ca91ee932dd98f75043ef047\"" Apr 18 00:39:06.191282 systemd[1]: Started cri-containerd-ce50bd1fa0fda9ef74c1ef978321b2aafbd2c543ca91ee932dd98f75043ef047.scope - libcontainer container ce50bd1fa0fda9ef74c1ef978321b2aafbd2c543ca91ee932dd98f75043ef047. Apr 18 00:39:06.227285 systemd[1]: cri-containerd-ce50bd1fa0fda9ef74c1ef978321b2aafbd2c543ca91ee932dd98f75043ef047.scope: Deactivated successfully. Apr 18 00:39:06.231439 containerd[1491]: time="2026-04-18T00:39:06.231189636Z" level=info msg="StartContainer for \"ce50bd1fa0fda9ef74c1ef978321b2aafbd2c543ca91ee932dd98f75043ef047\" returns successfully" Apr 18 00:39:06.243002 kubelet[1848]: I0418 00:39:06.242147 1848 kubelet_node_status.go:439] "Fast updating node status as it just became ready" Apr 18 00:39:06.258427 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-ce50bd1fa0fda9ef74c1ef978321b2aafbd2c543ca91ee932dd98f75043ef047-rootfs.mount: Deactivated successfully. Apr 18 00:39:06.361069 kubelet[1848]: E0418 00:39:06.360857 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:06.446062 containerd[1491]: time="2026-04-18T00:39:06.445800693Z" level=info msg="shim disconnected" id=ce50bd1fa0fda9ef74c1ef978321b2aafbd2c543ca91ee932dd98f75043ef047 namespace=k8s.io Apr 18 00:39:06.446062 containerd[1491]: time="2026-04-18T00:39:06.445903520Z" level=warning msg="cleaning up after shim disconnected" id=ce50bd1fa0fda9ef74c1ef978321b2aafbd2c543ca91ee932dd98f75043ef047 namespace=k8s.io Apr 18 00:39:06.446062 containerd[1491]: time="2026-04-18T00:39:06.445919993Z" level=info msg="cleaning up dead shim" namespace=k8s.io Apr 18 00:39:06.578010 containerd[1491]: time="2026-04-18T00:39:06.577823448Z" level=info msg="CreateContainer within sandbox \"54e42006e8a27815fac0b107294c76faa9eeb568d92af42a41b29755188d5636\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Apr 18 00:39:06.591227 containerd[1491]: time="2026-04-18T00:39:06.591191571Z" level=info msg="CreateContainer within sandbox \"54e42006e8a27815fac0b107294c76faa9eeb568d92af42a41b29755188d5636\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"02ba56493ece0095a0f6504ba765a24bec10b2e17629c73ed0dc7a8ec33b13ea\"" Apr 18 00:39:06.591724 containerd[1491]: time="2026-04-18T00:39:06.591658656Z" level=info msg="StartContainer for \"02ba56493ece0095a0f6504ba765a24bec10b2e17629c73ed0dc7a8ec33b13ea\"" Apr 18 00:39:06.624255 systemd[1]: Started cri-containerd-02ba56493ece0095a0f6504ba765a24bec10b2e17629c73ed0dc7a8ec33b13ea.scope - libcontainer container 02ba56493ece0095a0f6504ba765a24bec10b2e17629c73ed0dc7a8ec33b13ea. Apr 18 00:39:06.659240 containerd[1491]: time="2026-04-18T00:39:06.659116617Z" level=info msg="StartContainer for \"02ba56493ece0095a0f6504ba765a24bec10b2e17629c73ed0dc7a8ec33b13ea\" returns successfully" Apr 18 00:39:07.361815 kubelet[1848]: E0418 00:39:07.361732 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:07.666480 kubelet[1848]: I0418 00:39:07.666361 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-8ffhw" podStartSLOduration=5.429723579 podStartE2EDuration="12.666324446s" podCreationTimestamp="2026-04-18 00:38:55 +0000 UTC" firstStartedPulling="2026-04-18 00:38:58.884235192 +0000 UTC m=+4.512462757" lastFinishedPulling="2026-04-18 00:39:06.120836047 +0000 UTC m=+11.749063624" observedRunningTime="2026-04-18 00:39:07.601112075 +0000 UTC m=+13.229339668" watchObservedRunningTime="2026-04-18 00:39:07.666324446 +0000 UTC m=+13.294552014" Apr 18 00:39:07.675927 systemd[1]: Created slice kubepods-besteffort-pod0962be71_a617_4575_8355_e3ffd6ed492b.slice - libcontainer container kubepods-besteffort-pod0962be71_a617_4575_8355_e3ffd6ed492b.slice. Apr 18 00:39:07.772895 systemd-networkd[1416]: flannel.1: Link UP Apr 18 00:39:07.772907 systemd-networkd[1416]: flannel.1: Gained carrier Apr 18 00:39:07.780272 kubelet[1848]: I0418 00:39:07.780188 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-9b4ld\" (UniqueName: \"kubernetes.io/projected/0962be71-a617-4575-8355-e3ffd6ed492b-kube-api-access-9b4ld\") pod \"nginx-deployment-bb8f74bfb-ps5kj\" (UID: \"0962be71-a617-4575-8355-e3ffd6ed492b\") " pod="default/nginx-deployment-bb8f74bfb-ps5kj" Apr 18 00:39:07.991302 containerd[1491]: time="2026-04-18T00:39:07.990970991Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-ps5kj,Uid:0962be71-a617-4575-8355-e3ffd6ed492b,Namespace:default,Attempt:0,}" Apr 18 00:39:08.035200 systemd-networkd[1416]: cni0: Link UP Apr 18 00:39:08.035213 systemd-networkd[1416]: cni0: Gained carrier Apr 18 00:39:08.035680 systemd-networkd[1416]: cni0: Lost carrier Apr 18 00:39:08.050978 systemd-networkd[1416]: veth6b4ee0ac: Link UP Apr 18 00:39:08.055527 kernel: cni0: port 1(veth6b4ee0ac) entered blocking state Apr 18 00:39:08.055686 kernel: cni0: port 1(veth6b4ee0ac) entered disabled state Apr 18 00:39:08.058733 kernel: veth6b4ee0ac: entered allmulticast mode Apr 18 00:39:08.058792 kernel: veth6b4ee0ac: entered promiscuous mode Apr 18 00:39:08.064316 kernel: cni0: port 1(veth6b4ee0ac) entered blocking state Apr 18 00:39:08.064731 kernel: cni0: port 1(veth6b4ee0ac) entered forwarding state Apr 18 00:39:08.064993 kernel: cni0: port 1(veth6b4ee0ac) entered disabled state Apr 18 00:39:08.081821 kernel: cni0: port 1(veth6b4ee0ac) entered blocking state Apr 18 00:39:08.081933 kernel: cni0: port 1(veth6b4ee0ac) entered forwarding state Apr 18 00:39:08.083241 systemd-networkd[1416]: veth6b4ee0ac: Gained carrier Apr 18 00:39:08.083692 systemd-networkd[1416]: cni0: Gained carrier Apr 18 00:39:08.091068 containerd[1491]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00008c950), "name":"cbr0", "type":"bridge"} Apr 18 00:39:08.091068 containerd[1491]: delegateAdd: netconf sent to delegate plugin: Apr 18 00:39:08.132202 containerd[1491]: {"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="2026-04-18T00:39:08.131902045Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 18 00:39:08.132778 containerd[1491]: time="2026-04-18T00:39:08.132023478Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 18 00:39:08.132778 containerd[1491]: time="2026-04-18T00:39:08.132492886Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:39:08.132778 containerd[1491]: time="2026-04-18T00:39:08.132633707Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:39:08.165289 systemd[1]: Started cri-containerd-c4fe279a91b37949268ab997604ce2ed051113b54db5abcedd1431104abccfb5.scope - libcontainer container c4fe279a91b37949268ab997604ce2ed051113b54db5abcedd1431104abccfb5. Apr 18 00:39:08.219778 containerd[1491]: time="2026-04-18T00:39:08.219726313Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-bb8f74bfb-ps5kj,Uid:0962be71-a617-4575-8355-e3ffd6ed492b,Namespace:default,Attempt:0,} returns sandbox id \"c4fe279a91b37949268ab997604ce2ed051113b54db5abcedd1431104abccfb5\"" Apr 18 00:39:08.222701 containerd[1491]: time="2026-04-18T00:39:08.222611076Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 18 00:39:08.363543 kubelet[1848]: E0418 00:39:08.362396 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:09.347334 systemd-networkd[1416]: flannel.1: Gained IPv6LL Apr 18 00:39:09.362957 kubelet[1848]: E0418 00:39:09.362902 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:09.476381 systemd-networkd[1416]: cni0: Gained IPv6LL Apr 18 00:39:09.603523 systemd-networkd[1416]: veth6b4ee0ac: Gained IPv6LL Apr 18 00:39:10.364712 kubelet[1848]: E0418 00:39:10.363742 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:11.367006 kubelet[1848]: E0418 00:39:11.365812 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:11.512639 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1897093108.mount: Deactivated successfully. Apr 18 00:39:12.366745 kubelet[1848]: E0418 00:39:12.366674 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:12.407347 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 18 00:39:12.752148 containerd[1491]: time="2026-04-18T00:39:12.751542238Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:12.756509 containerd[1491]: time="2026-04-18T00:39:12.756423032Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=63909824" Apr 18 00:39:12.759055 containerd[1491]: time="2026-04-18T00:39:12.757633185Z" level=info msg="ImageCreate event name:\"sha256:fda7399e8104578dd71a8491d53621a60c1c5ed6b7f3befb583d4f164244255e\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:12.763624 containerd[1491]: time="2026-04-18T00:39:12.763572497Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:37746262896e4e1a260f21898a0759befa3e3bc64a33bd95f7cd1b8400a9b03b\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:12.765066 containerd[1491]: time="2026-04-18T00:39:12.765005743Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:fda7399e8104578dd71a8491d53621a60c1c5ed6b7f3befb583d4f164244255e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:37746262896e4e1a260f21898a0759befa3e3bc64a33bd95f7cd1b8400a9b03b\", size \"63909702\" in 4.542293826s" Apr 18 00:39:12.765206 containerd[1491]: time="2026-04-18T00:39:12.765178959Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:fda7399e8104578dd71a8491d53621a60c1c5ed6b7f3befb583d4f164244255e\"" Apr 18 00:39:12.770341 containerd[1491]: time="2026-04-18T00:39:12.770297854Z" level=info msg="CreateContainer within sandbox \"c4fe279a91b37949268ab997604ce2ed051113b54db5abcedd1431104abccfb5\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Apr 18 00:39:12.784885 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount822481735.mount: Deactivated successfully. Apr 18 00:39:12.787742 containerd[1491]: time="2026-04-18T00:39:12.787701127Z" level=info msg="CreateContainer within sandbox \"c4fe279a91b37949268ab997604ce2ed051113b54db5abcedd1431104abccfb5\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"4b5e604d342057091cc7a1d6d47953ec45e51ae60579445081348d1cc440aad0\"" Apr 18 00:39:12.788823 containerd[1491]: time="2026-04-18T00:39:12.788792779Z" level=info msg="StartContainer for \"4b5e604d342057091cc7a1d6d47953ec45e51ae60579445081348d1cc440aad0\"" Apr 18 00:39:12.838279 systemd[1]: Started cri-containerd-4b5e604d342057091cc7a1d6d47953ec45e51ae60579445081348d1cc440aad0.scope - libcontainer container 4b5e604d342057091cc7a1d6d47953ec45e51ae60579445081348d1cc440aad0. Apr 18 00:39:12.887977 containerd[1491]: time="2026-04-18T00:39:12.887823229Z" level=info msg="StartContainer for \"4b5e604d342057091cc7a1d6d47953ec45e51ae60579445081348d1cc440aad0\" returns successfully" Apr 18 00:39:13.366965 kubelet[1848]: E0418 00:39:13.366874 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:13.488960 sshd[1895]: Connection closed by 66.132.172.183 port 35396 [preauth] Apr 18 00:39:13.491866 systemd[1]: sshd@7-10.230.47.182:22-66.132.172.183:35396.service: Deactivated successfully. Apr 18 00:39:13.612031 kubelet[1848]: I0418 00:39:13.611899 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-bb8f74bfb-ps5kj" podStartSLOduration=2.066840171 podStartE2EDuration="6.611853691s" podCreationTimestamp="2026-04-18 00:39:07 +0000 UTC" firstStartedPulling="2026-04-18 00:39:08.221853413 +0000 UTC m=+13.850080986" lastFinishedPulling="2026-04-18 00:39:12.766866935 +0000 UTC m=+18.395094506" observedRunningTime="2026-04-18 00:39:13.611096474 +0000 UTC m=+19.239324059" watchObservedRunningTime="2026-04-18 00:39:13.611853691 +0000 UTC m=+19.240081265" Apr 18 00:39:14.367993 kubelet[1848]: E0418 00:39:14.367909 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:15.351525 kubelet[1848]: E0418 00:39:15.351442 1848 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:15.368996 kubelet[1848]: E0418 00:39:15.368948 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:16.369706 kubelet[1848]: E0418 00:39:16.369609 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:17.370316 kubelet[1848]: E0418 00:39:17.370249 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:18.370846 kubelet[1848]: E0418 00:39:18.370761 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:19.371590 kubelet[1848]: E0418 00:39:19.371511 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:20.372047 kubelet[1848]: E0418 00:39:20.371951 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:20.754140 systemd[1]: Created slice kubepods-besteffort-podf2fdd0d9_124b_4e54_a634_015addaa659b.slice - libcontainer container kubepods-besteffort-podf2fdd0d9_124b_4e54_a634_015addaa659b.slice. Apr 18 00:39:20.868365 kubelet[1848]: I0418 00:39:20.868271 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/f2fdd0d9-124b-4e54-a634-015addaa659b-data\") pod \"nfs-server-provisioner-0\" (UID: \"f2fdd0d9-124b-4e54-a634-015addaa659b\") " pod="default/nfs-server-provisioner-0" Apr 18 00:39:20.868365 kubelet[1848]: I0418 00:39:20.868368 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-kztzg\" (UniqueName: \"kubernetes.io/projected/f2fdd0d9-124b-4e54-a634-015addaa659b-kube-api-access-kztzg\") pod \"nfs-server-provisioner-0\" (UID: \"f2fdd0d9-124b-4e54-a634-015addaa659b\") " pod="default/nfs-server-provisioner-0" Apr 18 00:39:21.062120 containerd[1491]: time="2026-04-18T00:39:21.061264917Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f2fdd0d9-124b-4e54-a634-015addaa659b,Namespace:default,Attempt:0,}" Apr 18 00:39:21.115635 kernel: cni0: port 2(veth9d10344f) entered blocking state Apr 18 00:39:21.115826 kernel: cni0: port 2(veth9d10344f) entered disabled state Apr 18 00:39:21.113330 systemd-networkd[1416]: veth9d10344f: Link UP Apr 18 00:39:21.118710 kernel: veth9d10344f: entered allmulticast mode Apr 18 00:39:21.118795 kernel: veth9d10344f: entered promiscuous mode Apr 18 00:39:21.132215 kernel: cni0: port 2(veth9d10344f) entered blocking state Apr 18 00:39:21.132314 kernel: cni0: port 2(veth9d10344f) entered forwarding state Apr 18 00:39:21.133168 systemd-networkd[1416]: veth9d10344f: Gained carrier Apr 18 00:39:21.143643 containerd[1491]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0001047f0), "name":"cbr0", "type":"bridge"} Apr 18 00:39:21.143643 containerd[1491]: delegateAdd: netconf sent to delegate plugin: Apr 18 00:39:21.185645 containerd[1491]: {"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="2026-04-18T00:39:21.185460973Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 18 00:39:21.185645 containerd[1491]: time="2026-04-18T00:39:21.185575739Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 18 00:39:21.185645 containerd[1491]: time="2026-04-18T00:39:21.185598458Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:39:21.186268 containerd[1491]: time="2026-04-18T00:39:21.185725345Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:39:21.221293 systemd[1]: Started cri-containerd-cc636ca75c885d23f7eac8c5a57efbcfecd6d9d4e3c0e59a6afc4656e5c30b71.scope - libcontainer container cc636ca75c885d23f7eac8c5a57efbcfecd6d9d4e3c0e59a6afc4656e5c30b71. Apr 18 00:39:21.280876 containerd[1491]: time="2026-04-18T00:39:21.280782613Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:f2fdd0d9-124b-4e54-a634-015addaa659b,Namespace:default,Attempt:0,} returns sandbox id \"cc636ca75c885d23f7eac8c5a57efbcfecd6d9d4e3c0e59a6afc4656e5c30b71\"" Apr 18 00:39:21.284047 containerd[1491]: time="2026-04-18T00:39:21.283953603Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Apr 18 00:39:21.372205 kubelet[1848]: E0418 00:39:21.372135 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:22.373448 kubelet[1848]: E0418 00:39:22.373296 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:22.916565 systemd-networkd[1416]: veth9d10344f: Gained IPv6LL Apr 18 00:39:23.374533 kubelet[1848]: E0418 00:39:23.374393 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:24.372934 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount991832447.mount: Deactivated successfully. Apr 18 00:39:24.376994 kubelet[1848]: E0418 00:39:24.375278 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:24.808826 update_engine[1479]: I20260418 00:39:24.807291 1479 update_attempter.cc:509] Updating boot flags... Apr 18 00:39:24.875733 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 32 scanned by (udev-worker) (2756) Apr 18 00:39:25.043261 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 32 scanned by (udev-worker) (2759) Apr 18 00:39:25.376303 kubelet[1848]: E0418 00:39:25.376250 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:26.376785 kubelet[1848]: E0418 00:39:26.376740 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:27.362422 containerd[1491]: time="2026-04-18T00:39:27.362120499Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:27.364312 containerd[1491]: time="2026-04-18T00:39:27.363798823Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039414" Apr 18 00:39:27.365798 containerd[1491]: time="2026-04-18T00:39:27.365268480Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:27.372040 containerd[1491]: time="2026-04-18T00:39:27.371656499Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:27.375505 containerd[1491]: time="2026-04-18T00:39:27.375456757Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 6.091372036s" Apr 18 00:39:27.375608 containerd[1491]: time="2026-04-18T00:39:27.375522261Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Apr 18 00:39:27.379964 kubelet[1848]: E0418 00:39:27.379268 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:27.388122 containerd[1491]: time="2026-04-18T00:39:27.386421146Z" level=info msg="CreateContainer within sandbox \"cc636ca75c885d23f7eac8c5a57efbcfecd6d9d4e3c0e59a6afc4656e5c30b71\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Apr 18 00:39:27.404680 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2115796694.mount: Deactivated successfully. Apr 18 00:39:27.422204 containerd[1491]: time="2026-04-18T00:39:27.422137811Z" level=info msg="CreateContainer within sandbox \"cc636ca75c885d23f7eac8c5a57efbcfecd6d9d4e3c0e59a6afc4656e5c30b71\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"8f0916f37ee2042ab53a658dd26c8e94e111e1b29b314776d2f5d39b080756fa\"" Apr 18 00:39:27.423002 containerd[1491]: time="2026-04-18T00:39:27.422956081Z" level=info msg="StartContainer for \"8f0916f37ee2042ab53a658dd26c8e94e111e1b29b314776d2f5d39b080756fa\"" Apr 18 00:39:27.505355 systemd[1]: Started cri-containerd-8f0916f37ee2042ab53a658dd26c8e94e111e1b29b314776d2f5d39b080756fa.scope - libcontainer container 8f0916f37ee2042ab53a658dd26c8e94e111e1b29b314776d2f5d39b080756fa. Apr 18 00:39:27.570166 containerd[1491]: time="2026-04-18T00:39:27.568680462Z" level=info msg="StartContainer for \"8f0916f37ee2042ab53a658dd26c8e94e111e1b29b314776d2f5d39b080756fa\" returns successfully" Apr 18 00:39:27.655189 kubelet[1848]: I0418 00:39:27.655096 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.561248118 podStartE2EDuration="7.655063953s" podCreationTimestamp="2026-04-18 00:39:20 +0000 UTC" firstStartedPulling="2026-04-18 00:39:21.282816439 +0000 UTC m=+26.911044007" lastFinishedPulling="2026-04-18 00:39:27.376632274 +0000 UTC m=+33.004859842" observedRunningTime="2026-04-18 00:39:27.654727032 +0000 UTC m=+33.282954620" watchObservedRunningTime="2026-04-18 00:39:27.655063953 +0000 UTC m=+33.283291532" Apr 18 00:39:28.380227 kubelet[1848]: E0418 00:39:28.380142 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:29.381317 kubelet[1848]: E0418 00:39:29.381189 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:30.382085 kubelet[1848]: E0418 00:39:30.381982 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:31.382980 kubelet[1848]: E0418 00:39:31.382819 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:32.383234 kubelet[1848]: E0418 00:39:32.383165 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:32.956501 systemd[1]: Created slice kubepods-besteffort-podad9d8d14_e498_4bfa_9a7c_19e97684bb49.slice - libcontainer container kubepods-besteffort-podad9d8d14_e498_4bfa_9a7c_19e97684bb49.slice. Apr 18 00:39:33.052032 kubelet[1848]: I0418 00:39:33.051946 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-5cl84\" (UniqueName: \"kubernetes.io/projected/ad9d8d14-e498-4bfa-9a7c-19e97684bb49-kube-api-access-5cl84\") pod \"test-pod-1\" (UID: \"ad9d8d14-e498-4bfa-9a7c-19e97684bb49\") " pod="default/test-pod-1" Apr 18 00:39:33.052359 kubelet[1848]: I0418 00:39:33.052047 1848 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-b27d6e2c-ffea-40f3-8bd7-f1b20eb3cecd\" (UniqueName: \"kubernetes.io/nfs/ad9d8d14-e498-4bfa-9a7c-19e97684bb49-pvc-b27d6e2c-ffea-40f3-8bd7-f1b20eb3cecd\") pod \"test-pod-1\" (UID: \"ad9d8d14-e498-4bfa-9a7c-19e97684bb49\") " pod="default/test-pod-1" Apr 18 00:39:33.219066 kernel: FS-Cache: Loaded Apr 18 00:39:33.304345 kernel: RPC: Registered named UNIX socket transport module. Apr 18 00:39:33.304572 kernel: RPC: Registered udp transport module. Apr 18 00:39:33.304631 kernel: RPC: Registered tcp transport module. Apr 18 00:39:33.305120 kernel: RPC: Registered tcp-with-tls transport module. Apr 18 00:39:33.306265 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 18 00:39:33.383473 kubelet[1848]: E0418 00:39:33.383418 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:33.627348 kernel: NFS: Registering the id_resolver key type Apr 18 00:39:33.627499 kernel: Key type id_resolver registered Apr 18 00:39:33.627539 kernel: Key type id_legacy registered Apr 18 00:39:33.678389 nfsidmap[2913]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'gb1.brightbox.com' Apr 18 00:39:33.686822 nfsidmap[2916]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'gb1.brightbox.com' Apr 18 00:39:33.863219 containerd[1491]: time="2026-04-18T00:39:33.863111094Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ad9d8d14-e498-4bfa-9a7c-19e97684bb49,Namespace:default,Attempt:0,}" Apr 18 00:39:33.905248 systemd-networkd[1416]: veth10d6c7a7: Link UP Apr 18 00:39:33.909093 kernel: cni0: port 3(veth10d6c7a7) entered blocking state Apr 18 00:39:33.909179 kernel: cni0: port 3(veth10d6c7a7) entered disabled state Apr 18 00:39:33.909219 kernel: veth10d6c7a7: entered allmulticast mode Apr 18 00:39:33.909737 kernel: veth10d6c7a7: entered promiscuous mode Apr 18 00:39:33.924710 kernel: cni0: port 3(veth10d6c7a7) entered blocking state Apr 18 00:39:33.925003 kernel: cni0: port 3(veth10d6c7a7) entered forwarding state Apr 18 00:39:33.924818 systemd-networkd[1416]: veth10d6c7a7: Gained carrier Apr 18 00:39:33.933039 containerd[1491]: 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), MTU:0, AdvMSS:0, Priority:0, Table:(*int)(nil), Scope:(*int)(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000129a0), "name":"cbr0", "type":"bridge"} Apr 18 00:39:33.933039 containerd[1491]: delegateAdd: netconf sent to delegate plugin: Apr 18 00:39:33.986942 containerd[1491]: {"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="2026-04-18T00:39:33.986430467Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Apr 18 00:39:33.986942 containerd[1491]: time="2026-04-18T00:39:33.986579717Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Apr 18 00:39:33.986942 containerd[1491]: time="2026-04-18T00:39:33.986604758Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:39:33.986942 containerd[1491]: time="2026-04-18T00:39:33.986766773Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Apr 18 00:39:34.045276 systemd[1]: Started cri-containerd-122d7293488dab575211c57cb4a59debfd8a28c98d95aa7b132963e6b17c6319.scope - libcontainer container 122d7293488dab575211c57cb4a59debfd8a28c98d95aa7b132963e6b17c6319. Apr 18 00:39:34.102315 containerd[1491]: time="2026-04-18T00:39:34.102141157Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:ad9d8d14-e498-4bfa-9a7c-19e97684bb49,Namespace:default,Attempt:0,} returns sandbox id \"122d7293488dab575211c57cb4a59debfd8a28c98d95aa7b132963e6b17c6319\"" Apr 18 00:39:34.104232 containerd[1491]: time="2026-04-18T00:39:34.104194319Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Apr 18 00:39:34.384262 kubelet[1848]: E0418 00:39:34.384191 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:34.435645 containerd[1491]: time="2026-04-18T00:39:34.435408028Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Apr 18 00:39:34.436307 containerd[1491]: time="2026-04-18T00:39:34.436231550Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Apr 18 00:39:34.440913 containerd[1491]: time="2026-04-18T00:39:34.440866651Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:fda7399e8104578dd71a8491d53621a60c1c5ed6b7f3befb583d4f164244255e\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:37746262896e4e1a260f21898a0759befa3e3bc64a33bd95f7cd1b8400a9b03b\", size \"63909702\" in 336.626165ms" Apr 18 00:39:34.441153 containerd[1491]: time="2026-04-18T00:39:34.441034542Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:fda7399e8104578dd71a8491d53621a60c1c5ed6b7f3befb583d4f164244255e\"" Apr 18 00:39:34.446145 containerd[1491]: time="2026-04-18T00:39:34.446086847Z" level=info msg="CreateContainer within sandbox \"122d7293488dab575211c57cb4a59debfd8a28c98d95aa7b132963e6b17c6319\" for container &ContainerMetadata{Name:test,Attempt:0,}" Apr 18 00:39:34.461675 containerd[1491]: time="2026-04-18T00:39:34.461531995Z" level=info msg="CreateContainer within sandbox \"122d7293488dab575211c57cb4a59debfd8a28c98d95aa7b132963e6b17c6319\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"8be885e00d42fb35df7d6f1d49ccdb0cdd8d603cd486f0003526ef1b52098b9c\"" Apr 18 00:39:34.462445 containerd[1491]: time="2026-04-18T00:39:34.462275282Z" level=info msg="StartContainer for \"8be885e00d42fb35df7d6f1d49ccdb0cdd8d603cd486f0003526ef1b52098b9c\"" Apr 18 00:39:34.504243 systemd[1]: Started cri-containerd-8be885e00d42fb35df7d6f1d49ccdb0cdd8d603cd486f0003526ef1b52098b9c.scope - libcontainer container 8be885e00d42fb35df7d6f1d49ccdb0cdd8d603cd486f0003526ef1b52098b9c. Apr 18 00:39:34.549563 containerd[1491]: time="2026-04-18T00:39:34.549422830Z" level=info msg="StartContainer for \"8be885e00d42fb35df7d6f1d49ccdb0cdd8d603cd486f0003526ef1b52098b9c\" returns successfully" Apr 18 00:39:34.674904 kubelet[1848]: I0418 00:39:34.674616 1848 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=13.336202452 podStartE2EDuration="13.674546233s" podCreationTimestamp="2026-04-18 00:39:21 +0000 UTC" firstStartedPulling="2026-04-18 00:39:34.103601702 +0000 UTC m=+39.731829274" lastFinishedPulling="2026-04-18 00:39:34.44194549 +0000 UTC m=+40.070173055" observedRunningTime="2026-04-18 00:39:34.672451408 +0000 UTC m=+40.300678998" watchObservedRunningTime="2026-04-18 00:39:34.674546233 +0000 UTC m=+40.302773811" Apr 18 00:39:35.352285 kubelet[1848]: E0418 00:39:35.352186 1848 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:35.385209 kubelet[1848]: E0418 00:39:35.385149 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:35.779339 systemd-networkd[1416]: veth10d6c7a7: Gained IPv6LL Apr 18 00:39:36.385437 kubelet[1848]: E0418 00:39:36.385350 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:37.386368 kubelet[1848]: E0418 00:39:37.386299 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:38.387277 kubelet[1848]: E0418 00:39:38.387174 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:39.388309 kubelet[1848]: E0418 00:39:39.388167 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Apr 18 00:39:40.389397 kubelet[1848]: E0418 00:39:40.389324 1848 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"