Feb 13 23:10:22.949027 kernel: Linux version 6.6.74-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p1) 13.3.1 20240614, GNU ld (Gentoo 2.42 p6) 2.42.0) #1 SMP PREEMPT_DYNAMIC Thu Feb 13 17:44:05 -00 2025 Feb 13 23:10:22.949056 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=ed9b5d8ea73d2e47b8decea8124089e04dd398ef43013c1b1a5809314044b1c3 Feb 13 23:10:22.949066 kernel: BIOS-provided physical RAM map: Feb 13 23:10:22.949077 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Feb 13 23:10:22.949084 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Feb 13 23:10:22.949091 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Feb 13 23:10:22.949099 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdbfff] usable Feb 13 23:10:22.949107 kernel: BIOS-e820: [mem 0x000000007ffdc000-0x000000007fffffff] reserved Feb 13 23:10:22.949114 kernel: BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved Feb 13 23:10:22.949121 kernel: BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved Feb 13 23:10:22.949129 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Feb 13 23:10:22.949136 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Feb 13 23:10:22.949146 kernel: NX (Execute Disable) protection: active Feb 13 23:10:22.949154 kernel: APIC: Static calls initialized Feb 13 23:10:22.949163 kernel: SMBIOS 2.8 present. Feb 13 23:10:22.949172 kernel: DMI: Red Hat KVM/RHEL-AV, BIOS 1.13.0-2.module_el8.5.0+2608+72063365 04/01/2014 Feb 13 23:10:22.949180 kernel: Hypervisor detected: KVM Feb 13 23:10:22.949191 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Feb 13 23:10:22.949200 kernel: kvm-clock: using sched offset of 3937142924 cycles Feb 13 23:10:22.949209 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Feb 13 23:10:22.949217 kernel: tsc: Detected 2294.608 MHz processor Feb 13 23:10:22.949226 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Feb 13 23:10:22.949235 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Feb 13 23:10:22.949243 kernel: last_pfn = 0x7ffdc max_arch_pfn = 0x400000000 Feb 13 23:10:22.949252 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Feb 13 23:10:22.949261 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Feb 13 23:10:22.949272 kernel: Using GB pages for direct mapping Feb 13 23:10:22.949290 kernel: ACPI: Early table checksum verification disabled Feb 13 23:10:22.949298 kernel: ACPI: RSDP 0x00000000000F5AA0 000014 (v00 BOCHS ) Feb 13 23:10:22.949305 kernel: ACPI: RSDT 0x000000007FFE47A5 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 23:10:22.949313 kernel: ACPI: FACP 0x000000007FFE438D 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 23:10:22.949321 kernel: ACPI: DSDT 0x000000007FFDFD80 00460D (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 23:10:22.949328 kernel: ACPI: FACS 0x000000007FFDFD40 000040 Feb 13 23:10:22.949336 kernel: ACPI: APIC 0x000000007FFE4481 0000F0 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 23:10:22.949344 kernel: ACPI: SRAT 0x000000007FFE4571 0001D0 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 23:10:22.949354 kernel: ACPI: MCFG 0x000000007FFE4741 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 23:10:22.949361 kernel: ACPI: WAET 0x000000007FFE477D 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Feb 13 23:10:22.949369 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe438d-0x7ffe4480] Feb 13 23:10:22.949376 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffdfd80-0x7ffe438c] Feb 13 23:10:22.949384 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffdfd40-0x7ffdfd7f] Feb 13 23:10:22.949396 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe4481-0x7ffe4570] Feb 13 23:10:22.949404 kernel: ACPI: Reserving SRAT table memory at [mem 0x7ffe4571-0x7ffe4740] Feb 13 23:10:22.949414 kernel: ACPI: Reserving MCFG table memory at [mem 0x7ffe4741-0x7ffe477c] Feb 13 23:10:22.949430 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe477d-0x7ffe47a4] Feb 13 23:10:22.949438 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Feb 13 23:10:22.949447 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Feb 13 23:10:22.949454 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Feb 13 23:10:22.949462 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Feb 13 23:10:22.949470 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Feb 13 23:10:22.949478 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Feb 13 23:10:22.949489 kernel: SRAT: PXM 0 -> APIC 0x06 -> Node 0 Feb 13 23:10:22.949497 kernel: SRAT: PXM 0 -> APIC 0x07 -> Node 0 Feb 13 23:10:22.949505 kernel: SRAT: PXM 0 -> APIC 0x08 -> Node 0 Feb 13 23:10:22.949513 kernel: SRAT: PXM 0 -> APIC 0x09 -> Node 0 Feb 13 23:10:22.949521 kernel: SRAT: PXM 0 -> APIC 0x0a -> Node 0 Feb 13 23:10:22.949529 kernel: SRAT: PXM 0 -> APIC 0x0b -> Node 0 Feb 13 23:10:22.949537 kernel: SRAT: PXM 0 -> APIC 0x0c -> Node 0 Feb 13 23:10:22.949544 kernel: SRAT: PXM 0 -> APIC 0x0d -> Node 0 Feb 13 23:10:22.949552 kernel: SRAT: PXM 0 -> APIC 0x0e -> Node 0 Feb 13 23:10:22.949563 kernel: SRAT: PXM 0 -> APIC 0x0f -> Node 0 Feb 13 23:10:22.949571 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Feb 13 23:10:22.949580 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0x7fffffff] Feb 13 23:10:22.949588 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x20800fffff] hotplug Feb 13 23:10:22.951611 kernel: NUMA: Node 0 [mem 0x00000000-0x0009ffff] + [mem 0x00100000-0x7ffdbfff] -> [mem 0x00000000-0x7ffdbfff] Feb 13 23:10:22.951621 kernel: NODE_DATA(0) allocated [mem 0x7ffd6000-0x7ffdbfff] Feb 13 23:10:22.951630 kernel: Zone ranges: Feb 13 23:10:22.951639 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Feb 13 23:10:22.951648 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdbfff] Feb 13 23:10:22.951661 kernel: Normal empty Feb 13 23:10:22.951670 kernel: Movable zone start for each node Feb 13 23:10:22.951679 kernel: Early memory node ranges Feb 13 23:10:22.951688 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Feb 13 23:10:22.951697 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdbfff] Feb 13 23:10:22.951706 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdbfff] Feb 13 23:10:22.951715 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Feb 13 23:10:22.951724 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Feb 13 23:10:22.951733 kernel: On node 0, zone DMA32: 36 pages in unavailable ranges Feb 13 23:10:22.951742 kernel: ACPI: PM-Timer IO Port: 0x608 Feb 13 23:10:22.951754 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Feb 13 23:10:22.951763 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Feb 13 23:10:22.951772 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Feb 13 23:10:22.951781 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Feb 13 23:10:22.951790 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Feb 13 23:10:22.951799 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Feb 13 23:10:22.951808 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Feb 13 23:10:22.951817 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Feb 13 23:10:22.951826 kernel: TSC deadline timer available Feb 13 23:10:22.951838 kernel: smpboot: Allowing 16 CPUs, 14 hotplug CPUs Feb 13 23:10:22.951847 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Feb 13 23:10:22.951856 kernel: [mem 0xc0000000-0xfed1bfff] available for PCI devices Feb 13 23:10:22.951865 kernel: Booting paravirtualized kernel on KVM Feb 13 23:10:22.951874 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Feb 13 23:10:22.951883 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:16 nr_cpu_ids:16 nr_node_ids:1 Feb 13 23:10:22.951892 kernel: percpu: Embedded 58 pages/cpu s197032 r8192 d32344 u262144 Feb 13 23:10:22.951901 kernel: pcpu-alloc: s197032 r8192 d32344 u262144 alloc=1*2097152 Feb 13 23:10:22.951910 kernel: pcpu-alloc: [0] 00 01 02 03 04 05 06 07 [0] 08 09 10 11 12 13 14 15 Feb 13 23:10:22.951922 kernel: kvm-guest: PV spinlocks enabled Feb 13 23:10:22.951931 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Feb 13 23:10:22.951941 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=ed9b5d8ea73d2e47b8decea8124089e04dd398ef43013c1b1a5809314044b1c3 Feb 13 23:10:22.951950 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Feb 13 23:10:22.951959 kernel: random: crng init done Feb 13 23:10:22.951968 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Feb 13 23:10:22.951987 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Feb 13 23:10:22.951995 kernel: Fallback order for Node 0: 0 Feb 13 23:10:22.952006 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515804 Feb 13 23:10:22.952014 kernel: Policy zone: DMA32 Feb 13 23:10:22.952022 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 13 23:10:22.952030 kernel: software IO TLB: area num 16. Feb 13 23:10:22.952039 kernel: Memory: 1901532K/2096616K available (12288K kernel code, 2301K rwdata, 22736K rodata, 42976K init, 2216K bss, 194824K reserved, 0K cma-reserved) Feb 13 23:10:22.952064 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=16, Nodes=1 Feb 13 23:10:22.952073 kernel: ftrace: allocating 37923 entries in 149 pages Feb 13 23:10:22.952082 kernel: ftrace: allocated 149 pages with 4 groups Feb 13 23:10:22.952091 kernel: Dynamic Preempt: voluntary Feb 13 23:10:22.952102 kernel: rcu: Preemptible hierarchical RCU implementation. Feb 13 23:10:22.952112 kernel: rcu: RCU event tracing is enabled. Feb 13 23:10:22.952121 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=16. Feb 13 23:10:22.952131 kernel: Trampoline variant of Tasks RCU enabled. Feb 13 23:10:22.952140 kernel: Rude variant of Tasks RCU enabled. Feb 13 23:10:22.952158 kernel: Tracing variant of Tasks RCU enabled. Feb 13 23:10:22.952170 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Feb 13 23:10:22.952180 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=16 Feb 13 23:10:22.952189 kernel: NR_IRQS: 33024, nr_irqs: 552, preallocated irqs: 16 Feb 13 23:10:22.952199 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 13 23:10:22.952208 kernel: Console: colour VGA+ 80x25 Feb 13 23:10:22.952218 kernel: printk: console [tty0] enabled Feb 13 23:10:22.952230 kernel: printk: console [ttyS0] enabled Feb 13 23:10:22.952240 kernel: ACPI: Core revision 20230628 Feb 13 23:10:22.952250 kernel: APIC: Switch to symmetric I/O mode setup Feb 13 23:10:22.952260 kernel: x2apic enabled Feb 13 23:10:22.952269 kernel: APIC: Switched APIC routing to: physical x2apic Feb 13 23:10:22.952282 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x21134f58f0d, max_idle_ns: 440795217993 ns Feb 13 23:10:22.952292 kernel: Calibrating delay loop (skipped) preset value.. 4589.21 BogoMIPS (lpj=2294608) Feb 13 23:10:22.952302 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Feb 13 23:10:22.952312 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Feb 13 23:10:22.952321 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Feb 13 23:10:22.952331 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Feb 13 23:10:22.952340 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on vm exit Feb 13 23:10:22.952349 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on syscall Feb 13 23:10:22.952359 kernel: Spectre V2 : Mitigation: Enhanced / Automatic IBRS Feb 13 23:10:22.952369 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Feb 13 23:10:22.952381 kernel: Spectre V2 : Spectre v2 / PBRSB-eIBRS: Retire a single CALL on VMEXIT Feb 13 23:10:22.952390 kernel: RETBleed: Mitigation: Enhanced IBRS Feb 13 23:10:22.952400 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Feb 13 23:10:22.952410 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Feb 13 23:10:22.952425 kernel: TAA: Mitigation: Clear CPU buffers Feb 13 23:10:22.952434 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Feb 13 23:10:22.952444 kernel: GDS: Unknown: Dependent on hypervisor status Feb 13 23:10:22.952453 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Feb 13 23:10:22.952463 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Feb 13 23:10:22.952472 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Feb 13 23:10:22.952485 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Feb 13 23:10:22.952494 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Feb 13 23:10:22.952504 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Feb 13 23:10:22.952514 kernel: x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers' Feb 13 23:10:22.952523 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Feb 13 23:10:22.952533 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Feb 13 23:10:22.952542 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Feb 13 23:10:22.952552 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Feb 13 23:10:22.952561 kernel: x86/fpu: xstate_offset[9]: 2432, xstate_sizes[9]: 8 Feb 13 23:10:22.952571 kernel: x86/fpu: Enabled xstate features 0x2e7, context size is 2440 bytes, using 'compacted' format. Feb 13 23:10:22.952580 kernel: Freeing SMP alternatives memory: 32K Feb 13 23:10:22.952599 kernel: pid_max: default: 32768 minimum: 301 Feb 13 23:10:22.952612 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Feb 13 23:10:22.952622 kernel: landlock: Up and running. Feb 13 23:10:22.952631 kernel: SELinux: Initializing. Feb 13 23:10:22.952641 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Feb 13 23:10:22.952651 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Feb 13 23:10:22.952660 kernel: smpboot: CPU0: Intel Xeon Processor (Cascadelake) (family: 0x6, model: 0x55, stepping: 0x6) Feb 13 23:10:22.952670 kernel: RCU Tasks: Setting shift to 4 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=16. Feb 13 23:10:22.952680 kernel: RCU Tasks Rude: Setting shift to 4 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=16. Feb 13 23:10:22.952690 kernel: RCU Tasks Trace: Setting shift to 4 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=16. Feb 13 23:10:22.952699 kernel: Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. Feb 13 23:10:22.952712 kernel: signal: max sigframe size: 3632 Feb 13 23:10:22.952721 kernel: rcu: Hierarchical SRCU implementation. Feb 13 23:10:22.952731 kernel: rcu: Max phase no-delay instances is 400. Feb 13 23:10:22.952741 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Feb 13 23:10:22.952751 kernel: smp: Bringing up secondary CPUs ... Feb 13 23:10:22.952760 kernel: smpboot: x86: Booting SMP configuration: Feb 13 23:10:22.952770 kernel: .... node #0, CPUs: #1 Feb 13 23:10:22.952779 kernel: smpboot: CPU 1 Converting physical 0 to logical die 1 Feb 13 23:10:22.952789 kernel: smp: Brought up 1 node, 2 CPUs Feb 13 23:10:22.952801 kernel: smpboot: Max logical packages: 16 Feb 13 23:10:22.952811 kernel: smpboot: Total of 2 processors activated (9178.43 BogoMIPS) Feb 13 23:10:22.952821 kernel: devtmpfs: initialized Feb 13 23:10:22.952830 kernel: x86/mm: Memory block size: 128MB Feb 13 23:10:22.952840 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Feb 13 23:10:22.952850 kernel: futex hash table entries: 4096 (order: 6, 262144 bytes, linear) Feb 13 23:10:22.952859 kernel: pinctrl core: initialized pinctrl subsystem Feb 13 23:10:22.952869 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 13 23:10:22.952879 kernel: audit: initializing netlink subsys (disabled) Feb 13 23:10:22.952891 kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 13 23:10:22.952900 kernel: thermal_sys: Registered thermal governor 'user_space' Feb 13 23:10:22.952910 kernel: audit: type=2000 audit(1739488222.195:1): state=initialized audit_enabled=0 res=1 Feb 13 23:10:22.952920 kernel: cpuidle: using governor menu Feb 13 23:10:22.952930 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Feb 13 23:10:22.952939 kernel: dca service started, version 1.12.1 Feb 13 23:10:22.952949 kernel: PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) Feb 13 23:10:22.952959 kernel: PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved as E820 entry Feb 13 23:10:22.952969 kernel: PCI: Using configuration type 1 for base access Feb 13 23:10:22.952981 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Feb 13 23:10:22.952991 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Feb 13 23:10:22.953000 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Feb 13 23:10:22.953010 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Feb 13 23:10:22.953019 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Feb 13 23:10:22.953029 kernel: ACPI: Added _OSI(Module Device) Feb 13 23:10:22.953039 kernel: ACPI: Added _OSI(Processor Device) Feb 13 23:10:22.953048 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Feb 13 23:10:22.953058 kernel: ACPI: Added _OSI(Processor Aggregator Device) Feb 13 23:10:22.953070 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Feb 13 23:10:22.953080 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Feb 13 23:10:22.953090 kernel: ACPI: Interpreter enabled Feb 13 23:10:22.953099 kernel: ACPI: PM: (supports S0 S5) Feb 13 23:10:22.953109 kernel: ACPI: Using IOAPIC for interrupt routing Feb 13 23:10:22.953119 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Feb 13 23:10:22.953128 kernel: PCI: Using E820 reservations for host bridge windows Feb 13 23:10:22.953138 kernel: ACPI: Enabled 2 GPEs in block 00 to 3F Feb 13 23:10:22.953147 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Feb 13 23:10:22.953305 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Feb 13 23:10:22.953407 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Feb 13 23:10:22.953505 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Feb 13 23:10:22.953518 kernel: PCI host bridge to bus 0000:00 Feb 13 23:10:22.958681 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Feb 13 23:10:22.958783 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Feb 13 23:10:22.958872 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Feb 13 23:10:22.958953 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xafffffff window] Feb 13 23:10:22.959031 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] Feb 13 23:10:22.959110 kernel: pci_bus 0000:00: root bus resource [mem 0x20c0000000-0x28bfffffff window] Feb 13 23:10:22.959189 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 13 23:10:22.959301 kernel: pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 Feb 13 23:10:22.959417 kernel: pci 0000:00:01.0: [1013:00b8] type 00 class 0x030000 Feb 13 23:10:22.959521 kernel: pci 0000:00:01.0: reg 0x10: [mem 0xfa000000-0xfbffffff pref] Feb 13 23:10:22.959625 kernel: pci 0000:00:01.0: reg 0x14: [mem 0xfea50000-0xfea50fff] Feb 13 23:10:22.959716 kernel: pci 0000:00:01.0: reg 0x30: [mem 0xfea40000-0xfea4ffff pref] Feb 13 23:10:22.959805 kernel: pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Feb 13 23:10:22.959912 kernel: pci 0000:00:02.0: [1b36:000c] type 01 class 0x060400 Feb 13 23:10:22.960005 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfea51000-0xfea51fff] Feb 13 23:10:22.960103 kernel: pci 0000:00:02.1: [1b36:000c] type 01 class 0x060400 Feb 13 23:10:22.960198 kernel: pci 0000:00:02.1: reg 0x10: [mem 0xfea52000-0xfea52fff] Feb 13 23:10:22.960303 kernel: pci 0000:00:02.2: [1b36:000c] type 01 class 0x060400 Feb 13 23:10:22.960394 kernel: pci 0000:00:02.2: reg 0x10: [mem 0xfea53000-0xfea53fff] Feb 13 23:10:22.960498 kernel: pci 0000:00:02.3: [1b36:000c] type 01 class 0x060400 Feb 13 23:10:22.960602 kernel: pci 0000:00:02.3: reg 0x10: [mem 0xfea54000-0xfea54fff] Feb 13 23:10:22.960704 kernel: pci 0000:00:02.4: [1b36:000c] type 01 class 0x060400 Feb 13 23:10:22.960799 kernel: pci 0000:00:02.4: reg 0x10: [mem 0xfea55000-0xfea55fff] Feb 13 23:10:22.960900 kernel: pci 0000:00:02.5: [1b36:000c] type 01 class 0x060400 Feb 13 23:10:22.960989 kernel: pci 0000:00:02.5: reg 0x10: [mem 0xfea56000-0xfea56fff] Feb 13 23:10:22.961088 kernel: pci 0000:00:02.6: [1b36:000c] type 01 class 0x060400 Feb 13 23:10:22.961180 kernel: pci 0000:00:02.6: reg 0x10: [mem 0xfea57000-0xfea57fff] Feb 13 23:10:22.961282 kernel: pci 0000:00:02.7: [1b36:000c] type 01 class 0x060400 Feb 13 23:10:22.961376 kernel: pci 0000:00:02.7: reg 0x10: [mem 0xfea58000-0xfea58fff] Feb 13 23:10:22.961487 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Feb 13 23:10:22.961577 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc0c0-0xc0df] Feb 13 23:10:22.961682 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfea59000-0xfea59fff] Feb 13 23:10:22.961776 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfd000000-0xfd003fff 64bit pref] Feb 13 23:10:22.961870 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfea00000-0xfea3ffff pref] Feb 13 23:10:22.961973 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 Feb 13 23:10:22.962070 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] Feb 13 23:10:22.962158 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfea5a000-0xfea5afff] Feb 13 23:10:22.962248 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfd004000-0xfd007fff 64bit pref] Feb 13 23:10:22.962346 kernel: pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 Feb 13 23:10:22.962446 kernel: pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO Feb 13 23:10:22.962546 kernel: pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 Feb 13 23:10:22.964085 kernel: pci 0000:00:1f.2: reg 0x20: [io 0xc0e0-0xc0ff] Feb 13 23:10:22.964180 kernel: pci 0000:00:1f.2: reg 0x24: [mem 0xfea5b000-0xfea5bfff] Feb 13 23:10:22.964272 kernel: pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 Feb 13 23:10:22.964355 kernel: pci 0000:00:1f.3: reg 0x20: [io 0x0700-0x073f] Feb 13 23:10:22.964458 kernel: pci 0000:01:00.0: [1b36:000e] type 01 class 0x060400 Feb 13 23:10:22.964545 kernel: pci 0000:01:00.0: reg 0x10: [mem 0xfda00000-0xfda000ff 64bit] Feb 13 23:10:22.964673 kernel: pci 0000:00:02.0: PCI bridge to [bus 01-02] Feb 13 23:10:22.964763 kernel: pci 0000:00:02.0: bridge window [mem 0xfd800000-0xfdbfffff] Feb 13 23:10:22.964854 kernel: pci 0000:00:02.0: bridge window [mem 0xfce00000-0xfcffffff 64bit pref] Feb 13 23:10:22.964952 kernel: pci_bus 0000:02: extended config space not accessible Feb 13 23:10:22.965055 kernel: pci 0000:02:01.0: [8086:25ab] type 00 class 0x088000 Feb 13 23:10:22.965153 kernel: pci 0000:02:01.0: reg 0x10: [mem 0xfd800000-0xfd80000f] Feb 13 23:10:22.965247 kernel: pci 0000:01:00.0: PCI bridge to [bus 02] Feb 13 23:10:22.965344 kernel: pci 0000:01:00.0: bridge window [mem 0xfd800000-0xfd9fffff] Feb 13 23:10:22.965488 kernel: pci 0000:03:00.0: [1b36:000d] type 00 class 0x0c0330 Feb 13 23:10:22.965582 kernel: pci 0000:03:00.0: reg 0x10: [mem 0xfe800000-0xfe803fff 64bit] Feb 13 23:10:22.972213 kernel: pci 0000:00:02.1: PCI bridge to [bus 03] Feb 13 23:10:22.972318 kernel: pci 0000:00:02.1: bridge window [mem 0xfe800000-0xfe9fffff] Feb 13 23:10:22.972411 kernel: pci 0000:00:02.1: bridge window [mem 0xfcc00000-0xfcdfffff 64bit pref] Feb 13 23:10:22.972520 kernel: pci 0000:04:00.0: [1af4:1044] type 00 class 0x00ff00 Feb 13 23:10:22.972644 kernel: pci 0000:04:00.0: reg 0x20: [mem 0xfca00000-0xfca03fff 64bit pref] Feb 13 23:10:22.972738 kernel: pci 0000:00:02.2: PCI bridge to [bus 04] Feb 13 23:10:22.972827 kernel: pci 0000:00:02.2: bridge window [mem 0xfe600000-0xfe7fffff] Feb 13 23:10:22.972915 kernel: pci 0000:00:02.2: bridge window [mem 0xfca00000-0xfcbfffff 64bit pref] Feb 13 23:10:22.973007 kernel: pci 0000:00:02.3: PCI bridge to [bus 05] Feb 13 23:10:22.973095 kernel: pci 0000:00:02.3: bridge window [mem 0xfe400000-0xfe5fffff] Feb 13 23:10:22.973184 kernel: pci 0000:00:02.3: bridge window [mem 0xfc800000-0xfc9fffff 64bit pref] Feb 13 23:10:22.973276 kernel: pci 0000:00:02.4: PCI bridge to [bus 06] Feb 13 23:10:22.973371 kernel: pci 0000:00:02.4: bridge window [mem 0xfe200000-0xfe3fffff] Feb 13 23:10:22.973469 kernel: pci 0000:00:02.4: bridge window [mem 0xfc600000-0xfc7fffff 64bit pref] Feb 13 23:10:22.973561 kernel: pci 0000:00:02.5: PCI bridge to [bus 07] Feb 13 23:10:22.973666 kernel: pci 0000:00:02.5: bridge window [mem 0xfe000000-0xfe1fffff] Feb 13 23:10:22.973755 kernel: pci 0000:00:02.5: bridge window [mem 0xfc400000-0xfc5fffff 64bit pref] Feb 13 23:10:22.973845 kernel: pci 0000:00:02.6: PCI bridge to [bus 08] Feb 13 23:10:22.973934 kernel: pci 0000:00:02.6: bridge window [mem 0xfde00000-0xfdffffff] Feb 13 23:10:22.974021 kernel: pci 0000:00:02.6: bridge window [mem 0xfc200000-0xfc3fffff 64bit pref] Feb 13 23:10:22.974118 kernel: pci 0000:00:02.7: PCI bridge to [bus 09] Feb 13 23:10:22.974209 kernel: pci 0000:00:02.7: bridge window [mem 0xfdc00000-0xfddfffff] Feb 13 23:10:22.974300 kernel: pci 0000:00:02.7: bridge window [mem 0xfc000000-0xfc1fffff 64bit pref] Feb 13 23:10:22.974312 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Feb 13 23:10:22.974323 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Feb 13 23:10:22.974333 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Feb 13 23:10:22.974342 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Feb 13 23:10:22.974352 kernel: ACPI: PCI: Interrupt link LNKE configured for IRQ 10 Feb 13 23:10:22.974365 kernel: ACPI: PCI: Interrupt link LNKF configured for IRQ 10 Feb 13 23:10:22.974375 kernel: ACPI: PCI: Interrupt link LNKG configured for IRQ 11 Feb 13 23:10:22.974384 kernel: ACPI: PCI: Interrupt link LNKH configured for IRQ 11 Feb 13 23:10:22.974394 kernel: ACPI: PCI: Interrupt link GSIA configured for IRQ 16 Feb 13 23:10:22.974404 kernel: ACPI: PCI: Interrupt link GSIB configured for IRQ 17 Feb 13 23:10:22.974413 kernel: ACPI: PCI: Interrupt link GSIC configured for IRQ 18 Feb 13 23:10:22.974429 kernel: ACPI: PCI: Interrupt link GSID configured for IRQ 19 Feb 13 23:10:22.974439 kernel: ACPI: PCI: Interrupt link GSIE configured for IRQ 20 Feb 13 23:10:22.974448 kernel: ACPI: PCI: Interrupt link GSIF configured for IRQ 21 Feb 13 23:10:22.974461 kernel: ACPI: PCI: Interrupt link GSIG configured for IRQ 22 Feb 13 23:10:22.974470 kernel: ACPI: PCI: Interrupt link GSIH configured for IRQ 23 Feb 13 23:10:22.974480 kernel: iommu: Default domain type: Translated Feb 13 23:10:22.974490 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Feb 13 23:10:22.974499 kernel: PCI: Using ACPI for IRQ routing Feb 13 23:10:22.974509 kernel: PCI: pci_cache_line_size set to 64 bytes Feb 13 23:10:22.974519 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Feb 13 23:10:22.974529 kernel: e820: reserve RAM buffer [mem 0x7ffdc000-0x7fffffff] Feb 13 23:10:22.974642 kernel: pci 0000:00:01.0: vgaarb: setting as boot VGA device Feb 13 23:10:22.974737 kernel: pci 0000:00:01.0: vgaarb: bridge control possible Feb 13 23:10:22.974825 kernel: pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Feb 13 23:10:22.974838 kernel: vgaarb: loaded Feb 13 23:10:22.974848 kernel: clocksource: Switched to clocksource kvm-clock Feb 13 23:10:22.974858 kernel: VFS: Disk quotas dquot_6.6.0 Feb 13 23:10:22.974868 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Feb 13 23:10:22.974877 kernel: pnp: PnP ACPI init Feb 13 23:10:22.974976 kernel: system 00:04: [mem 0xb0000000-0xbfffffff window] has been reserved Feb 13 23:10:22.974993 kernel: pnp: PnP ACPI: found 5 devices Feb 13 23:10:22.975003 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Feb 13 23:10:22.975013 kernel: NET: Registered PF_INET protocol family Feb 13 23:10:22.975023 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Feb 13 23:10:22.975033 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Feb 13 23:10:22.975042 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 13 23:10:22.975052 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 13 23:10:22.975062 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Feb 13 23:10:22.975071 kernel: TCP: Hash tables configured (established 16384 bind 16384) Feb 13 23:10:22.975084 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Feb 13 23:10:22.975094 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Feb 13 23:10:22.975103 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 13 23:10:22.975113 kernel: NET: Registered PF_XDP protocol family Feb 13 23:10:22.975202 kernel: pci 0000:00:02.0: bridge window [io 0x1000-0x0fff] to [bus 01-02] add_size 1000 Feb 13 23:10:22.975295 kernel: pci 0000:00:02.1: bridge window [io 0x1000-0x0fff] to [bus 03] add_size 1000 Feb 13 23:10:22.975387 kernel: pci 0000:00:02.2: bridge window [io 0x1000-0x0fff] to [bus 04] add_size 1000 Feb 13 23:10:22.975490 kernel: pci 0000:00:02.3: bridge window [io 0x1000-0x0fff] to [bus 05] add_size 1000 Feb 13 23:10:22.975583 kernel: pci 0000:00:02.4: bridge window [io 0x1000-0x0fff] to [bus 06] add_size 1000 Feb 13 23:10:22.975706 kernel: pci 0000:00:02.5: bridge window [io 0x1000-0x0fff] to [bus 07] add_size 1000 Feb 13 23:10:22.975801 kernel: pci 0000:00:02.6: bridge window [io 0x1000-0x0fff] to [bus 08] add_size 1000 Feb 13 23:10:22.975911 kernel: pci 0000:00:02.7: bridge window [io 0x1000-0x0fff] to [bus 09] add_size 1000 Feb 13 23:10:22.976000 kernel: pci 0000:00:02.0: BAR 13: assigned [io 0x1000-0x1fff] Feb 13 23:10:22.976092 kernel: pci 0000:00:02.1: BAR 13: assigned [io 0x2000-0x2fff] Feb 13 23:10:22.976182 kernel: pci 0000:00:02.2: BAR 13: assigned [io 0x3000-0x3fff] Feb 13 23:10:22.976270 kernel: pci 0000:00:02.3: BAR 13: assigned [io 0x4000-0x4fff] Feb 13 23:10:22.976359 kernel: pci 0000:00:02.4: BAR 13: assigned [io 0x5000-0x5fff] Feb 13 23:10:22.976456 kernel: pci 0000:00:02.5: BAR 13: assigned [io 0x6000-0x6fff] Feb 13 23:10:22.976545 kernel: pci 0000:00:02.6: BAR 13: assigned [io 0x7000-0x7fff] Feb 13 23:10:22.976673 kernel: pci 0000:00:02.7: BAR 13: assigned [io 0x8000-0x8fff] Feb 13 23:10:22.976767 kernel: pci 0000:01:00.0: PCI bridge to [bus 02] Feb 13 23:10:22.976864 kernel: pci 0000:01:00.0: bridge window [mem 0xfd800000-0xfd9fffff] Feb 13 23:10:22.976955 kernel: pci 0000:00:02.0: PCI bridge to [bus 01-02] Feb 13 23:10:22.977043 kernel: pci 0000:00:02.0: bridge window [io 0x1000-0x1fff] Feb 13 23:10:22.977134 kernel: pci 0000:00:02.0: bridge window [mem 0xfd800000-0xfdbfffff] Feb 13 23:10:22.977226 kernel: pci 0000:00:02.0: bridge window [mem 0xfce00000-0xfcffffff 64bit pref] Feb 13 23:10:22.977315 kernel: pci 0000:00:02.1: PCI bridge to [bus 03] Feb 13 23:10:22.977407 kernel: pci 0000:00:02.1: bridge window [io 0x2000-0x2fff] Feb 13 23:10:22.977504 kernel: pci 0000:00:02.1: bridge window [mem 0xfe800000-0xfe9fffff] Feb 13 23:10:22.977604 kernel: pci 0000:00:02.1: bridge window [mem 0xfcc00000-0xfcdfffff 64bit pref] Feb 13 23:10:22.977696 kernel: pci 0000:00:02.2: PCI bridge to [bus 04] Feb 13 23:10:22.977787 kernel: pci 0000:00:02.2: bridge window [io 0x3000-0x3fff] Feb 13 23:10:22.977876 kernel: pci 0000:00:02.2: bridge window [mem 0xfe600000-0xfe7fffff] Feb 13 23:10:22.977965 kernel: pci 0000:00:02.2: bridge window [mem 0xfca00000-0xfcbfffff 64bit pref] Feb 13 23:10:22.978055 kernel: pci 0000:00:02.3: PCI bridge to [bus 05] Feb 13 23:10:22.978145 kernel: pci 0000:00:02.3: bridge window [io 0x4000-0x4fff] Feb 13 23:10:22.978239 kernel: pci 0000:00:02.3: bridge window [mem 0xfe400000-0xfe5fffff] Feb 13 23:10:22.978329 kernel: pci 0000:00:02.3: bridge window [mem 0xfc800000-0xfc9fffff 64bit pref] Feb 13 23:10:22.978426 kernel: pci 0000:00:02.4: PCI bridge to [bus 06] Feb 13 23:10:22.978517 kernel: pci 0000:00:02.4: bridge window [io 0x5000-0x5fff] Feb 13 23:10:22.980943 kernel: pci 0000:00:02.4: bridge window [mem 0xfe200000-0xfe3fffff] Feb 13 23:10:22.981059 kernel: pci 0000:00:02.4: bridge window [mem 0xfc600000-0xfc7fffff 64bit pref] Feb 13 23:10:22.981157 kernel: pci 0000:00:02.5: PCI bridge to [bus 07] Feb 13 23:10:22.981249 kernel: pci 0000:00:02.5: bridge window [io 0x6000-0x6fff] Feb 13 23:10:22.981340 kernel: pci 0000:00:02.5: bridge window [mem 0xfe000000-0xfe1fffff] Feb 13 23:10:22.981451 kernel: pci 0000:00:02.5: bridge window [mem 0xfc400000-0xfc5fffff 64bit pref] Feb 13 23:10:22.981544 kernel: pci 0000:00:02.6: PCI bridge to [bus 08] Feb 13 23:10:22.981644 kernel: pci 0000:00:02.6: bridge window [io 0x7000-0x7fff] Feb 13 23:10:22.981735 kernel: pci 0000:00:02.6: bridge window [mem 0xfde00000-0xfdffffff] Feb 13 23:10:22.981825 kernel: pci 0000:00:02.6: bridge window [mem 0xfc200000-0xfc3fffff 64bit pref] Feb 13 23:10:22.981920 kernel: pci 0000:00:02.7: PCI bridge to [bus 09] Feb 13 23:10:22.982008 kernel: pci 0000:00:02.7: bridge window [io 0x8000-0x8fff] Feb 13 23:10:22.982098 kernel: pci 0000:00:02.7: bridge window [mem 0xfdc00000-0xfddfffff] Feb 13 23:10:22.982186 kernel: pci 0000:00:02.7: bridge window [mem 0xfc000000-0xfc1fffff 64bit pref] Feb 13 23:10:22.982271 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Feb 13 23:10:22.982352 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Feb 13 23:10:22.982440 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Feb 13 23:10:22.982520 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xafffffff window] Feb 13 23:10:22.984076 kernel: pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] Feb 13 23:10:22.984179 kernel: pci_bus 0000:00: resource 9 [mem 0x20c0000000-0x28bfffffff window] Feb 13 23:10:22.984274 kernel: pci_bus 0000:01: resource 0 [io 0x1000-0x1fff] Feb 13 23:10:22.984363 kernel: pci_bus 0000:01: resource 1 [mem 0xfd800000-0xfdbfffff] Feb 13 23:10:22.984461 kernel: pci_bus 0000:01: resource 2 [mem 0xfce00000-0xfcffffff 64bit pref] Feb 13 23:10:22.984554 kernel: pci_bus 0000:02: resource 1 [mem 0xfd800000-0xfd9fffff] Feb 13 23:10:22.984667 kernel: pci_bus 0000:03: resource 0 [io 0x2000-0x2fff] Feb 13 23:10:22.984759 kernel: pci_bus 0000:03: resource 1 [mem 0xfe800000-0xfe9fffff] Feb 13 23:10:22.984844 kernel: pci_bus 0000:03: resource 2 [mem 0xfcc00000-0xfcdfffff 64bit pref] Feb 13 23:10:22.984935 kernel: pci_bus 0000:04: resource 0 [io 0x3000-0x3fff] Feb 13 23:10:22.985018 kernel: pci_bus 0000:04: resource 1 [mem 0xfe600000-0xfe7fffff] Feb 13 23:10:22.985102 kernel: pci_bus 0000:04: resource 2 [mem 0xfca00000-0xfcbfffff 64bit pref] Feb 13 23:10:22.985193 kernel: pci_bus 0000:05: resource 0 [io 0x4000-0x4fff] Feb 13 23:10:22.985277 kernel: pci_bus 0000:05: resource 1 [mem 0xfe400000-0xfe5fffff] Feb 13 23:10:22.985371 kernel: pci_bus 0000:05: resource 2 [mem 0xfc800000-0xfc9fffff 64bit pref] Feb 13 23:10:22.985468 kernel: pci_bus 0000:06: resource 0 [io 0x5000-0x5fff] Feb 13 23:10:22.985563 kernel: pci_bus 0000:06: resource 1 [mem 0xfe200000-0xfe3fffff] Feb 13 23:10:22.985764 kernel: pci_bus 0000:06: resource 2 [mem 0xfc600000-0xfc7fffff 64bit pref] Feb 13 23:10:22.985857 kernel: pci_bus 0000:07: resource 0 [io 0x6000-0x6fff] Feb 13 23:10:22.985940 kernel: pci_bus 0000:07: resource 1 [mem 0xfe000000-0xfe1fffff] Feb 13 23:10:22.986027 kernel: pci_bus 0000:07: resource 2 [mem 0xfc400000-0xfc5fffff 64bit pref] Feb 13 23:10:22.986115 kernel: pci_bus 0000:08: resource 0 [io 0x7000-0x7fff] Feb 13 23:10:22.986200 kernel: pci_bus 0000:08: resource 1 [mem 0xfde00000-0xfdffffff] Feb 13 23:10:22.986282 kernel: pci_bus 0000:08: resource 2 [mem 0xfc200000-0xfc3fffff 64bit pref] Feb 13 23:10:22.986370 kernel: pci_bus 0000:09: resource 0 [io 0x8000-0x8fff] Feb 13 23:10:22.986461 kernel: pci_bus 0000:09: resource 1 [mem 0xfdc00000-0xfddfffff] Feb 13 23:10:22.986544 kernel: pci_bus 0000:09: resource 2 [mem 0xfc000000-0xfc1fffff 64bit pref] Feb 13 23:10:22.986562 kernel: ACPI: \_SB_.GSIG: Enabled at IRQ 22 Feb 13 23:10:22.986573 kernel: PCI: CLS 0 bytes, default 64 Feb 13 23:10:22.986584 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Feb 13 23:10:22.986603 kernel: software IO TLB: mapped [mem 0x0000000079800000-0x000000007d800000] (64MB) Feb 13 23:10:22.986613 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Feb 13 23:10:22.986624 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x21134f58f0d, max_idle_ns: 440795217993 ns Feb 13 23:10:22.986634 kernel: Initialise system trusted keyrings Feb 13 23:10:22.986644 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Feb 13 23:10:22.986655 kernel: Key type asymmetric registered Feb 13 23:10:22.986667 kernel: Asymmetric key parser 'x509' registered Feb 13 23:10:22.986677 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Feb 13 23:10:22.986691 kernel: io scheduler mq-deadline registered Feb 13 23:10:22.986701 kernel: io scheduler kyber registered Feb 13 23:10:22.986711 kernel: io scheduler bfq registered Feb 13 23:10:22.986806 kernel: pcieport 0000:00:02.0: PME: Signaling with IRQ 24 Feb 13 23:10:22.986900 kernel: pcieport 0000:00:02.0: AER: enabled with IRQ 24 Feb 13 23:10:22.986998 kernel: pcieport 0000:00:02.0: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Feb 13 23:10:22.987085 kernel: pcieport 0000:00:02.1: PME: Signaling with IRQ 25 Feb 13 23:10:22.987166 kernel: pcieport 0000:00:02.1: AER: enabled with IRQ 25 Feb 13 23:10:22.987248 kernel: pcieport 0000:00:02.1: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Feb 13 23:10:22.987331 kernel: pcieport 0000:00:02.2: PME: Signaling with IRQ 26 Feb 13 23:10:22.987413 kernel: pcieport 0000:00:02.2: AER: enabled with IRQ 26 Feb 13 23:10:22.987503 kernel: pcieport 0000:00:02.2: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Feb 13 23:10:22.987599 kernel: pcieport 0000:00:02.3: PME: Signaling with IRQ 27 Feb 13 23:10:22.987682 kernel: pcieport 0000:00:02.3: AER: enabled with IRQ 27 Feb 13 23:10:22.987764 kernel: pcieport 0000:00:02.3: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Feb 13 23:10:22.987847 kernel: pcieport 0000:00:02.4: PME: Signaling with IRQ 28 Feb 13 23:10:22.987951 kernel: pcieport 0000:00:02.4: AER: enabled with IRQ 28 Feb 13 23:10:22.988041 kernel: pcieport 0000:00:02.4: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Feb 13 23:10:22.988135 kernel: pcieport 0000:00:02.5: PME: Signaling with IRQ 29 Feb 13 23:10:22.988225 kernel: pcieport 0000:00:02.5: AER: enabled with IRQ 29 Feb 13 23:10:22.988315 kernel: pcieport 0000:00:02.5: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Feb 13 23:10:22.988407 kernel: pcieport 0000:00:02.6: PME: Signaling with IRQ 30 Feb 13 23:10:22.988504 kernel: pcieport 0000:00:02.6: AER: enabled with IRQ 30 Feb 13 23:10:22.988600 kernel: pcieport 0000:00:02.6: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Feb 13 23:10:22.988694 kernel: pcieport 0000:00:02.7: PME: Signaling with IRQ 31 Feb 13 23:10:22.988785 kernel: pcieport 0000:00:02.7: AER: enabled with IRQ 31 Feb 13 23:10:22.988877 kernel: pcieport 0000:00:02.7: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ Feb 13 23:10:22.988891 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Feb 13 23:10:22.988902 kernel: ACPI: \_SB_.GSIH: Enabled at IRQ 23 Feb 13 23:10:22.988913 kernel: ACPI: \_SB_.GSIE: Enabled at IRQ 20 Feb 13 23:10:22.988923 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Feb 13 23:10:22.988937 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Feb 13 23:10:22.988948 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Feb 13 23:10:22.988959 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Feb 13 23:10:22.988969 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Feb 13 23:10:22.989064 kernel: rtc_cmos 00:03: RTC can wake from S4 Feb 13 23:10:22.989078 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Feb 13 23:10:22.989159 kernel: rtc_cmos 00:03: registered as rtc0 Feb 13 23:10:22.989245 kernel: rtc_cmos 00:03: setting system clock to 2025-02-13T23:10:22 UTC (1739488222) Feb 13 23:10:22.989323 kernel: rtc_cmos 00:03: alarms up to one day, y3k, 242 bytes nvram Feb 13 23:10:22.989335 kernel: intel_pstate: CPU model not supported Feb 13 23:10:22.989345 kernel: NET: Registered PF_INET6 protocol family Feb 13 23:10:22.989354 kernel: Segment Routing with IPv6 Feb 13 23:10:22.989364 kernel: In-situ OAM (IOAM) with IPv6 Feb 13 23:10:22.989373 kernel: NET: Registered PF_PACKET protocol family Feb 13 23:10:22.989383 kernel: Key type dns_resolver registered Feb 13 23:10:22.989392 kernel: IPI shorthand broadcast: enabled Feb 13 23:10:22.989401 kernel: sched_clock: Marking stable (967003747, 125037212)->(1185127108, -93086149) Feb 13 23:10:22.989414 kernel: registered taskstats version 1 Feb 13 23:10:22.989428 kernel: Loading compiled-in X.509 certificates Feb 13 23:10:22.989438 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.74-flatcar: 0cc219a306b9e46e583adebba1820decbdc4307b' Feb 13 23:10:22.989447 kernel: Key type .fscrypt registered Feb 13 23:10:22.989456 kernel: Key type fscrypt-provisioning registered Feb 13 23:10:22.989465 kernel: ima: No TPM chip found, activating TPM-bypass! Feb 13 23:10:22.989475 kernel: ima: Allocated hash algorithm: sha1 Feb 13 23:10:22.989484 kernel: ima: No architecture policies found Feb 13 23:10:22.989494 kernel: clk: Disabling unused clocks Feb 13 23:10:22.989506 kernel: Freeing unused kernel image (initmem) memory: 42976K Feb 13 23:10:22.989515 kernel: Write protecting the kernel read-only data: 36864k Feb 13 23:10:22.989525 kernel: Freeing unused kernel image (rodata/data gap) memory: 1840K Feb 13 23:10:22.989534 kernel: Run /init as init process Feb 13 23:10:22.989543 kernel: with arguments: Feb 13 23:10:22.989553 kernel: /init Feb 13 23:10:22.989562 kernel: with environment: Feb 13 23:10:22.989571 kernel: HOME=/ Feb 13 23:10:22.989580 kernel: TERM=linux Feb 13 23:10:22.989598 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Feb 13 23:10:22.989609 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) Feb 13 23:10:22.989621 systemd[1]: Detected virtualization kvm. Feb 13 23:10:22.989631 systemd[1]: Detected architecture x86-64. Feb 13 23:10:22.989641 systemd[1]: Running in initrd. Feb 13 23:10:22.989650 systemd[1]: No hostname configured, using default hostname. Feb 13 23:10:22.989660 systemd[1]: Hostname set to . Feb 13 23:10:22.989672 systemd[1]: Initializing machine ID from VM UUID. Feb 13 23:10:22.989682 systemd[1]: Queued start job for default target initrd.target. Feb 13 23:10:22.989692 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Feb 13 23:10:22.989702 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Feb 13 23:10:22.989712 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Feb 13 23:10:22.989721 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Feb 13 23:10:22.989731 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Feb 13 23:10:22.989741 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Feb 13 23:10:22.989755 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Feb 13 23:10:22.989765 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Feb 13 23:10:22.989775 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Feb 13 23:10:22.989785 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Feb 13 23:10:22.989795 systemd[1]: Reached target paths.target - Path Units. Feb 13 23:10:22.989804 systemd[1]: Reached target slices.target - Slice Units. Feb 13 23:10:22.989814 systemd[1]: Reached target swap.target - Swaps. Feb 13 23:10:22.989826 systemd[1]: Reached target timers.target - Timer Units. Feb 13 23:10:22.989836 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Feb 13 23:10:22.989845 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Feb 13 23:10:22.989855 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Feb 13 23:10:22.989881 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Feb 13 23:10:22.989892 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Feb 13 23:10:22.989903 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Feb 13 23:10:22.989914 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Feb 13 23:10:22.989924 systemd[1]: Reached target sockets.target - Socket Units. Feb 13 23:10:22.989938 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Feb 13 23:10:22.989949 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Feb 13 23:10:22.989959 systemd[1]: Finished network-cleanup.service - Network Cleanup. Feb 13 23:10:22.989970 systemd[1]: Starting systemd-fsck-usr.service... Feb 13 23:10:22.989981 systemd[1]: Starting systemd-journald.service - Journal Service... Feb 13 23:10:22.989991 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Feb 13 23:10:22.990002 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 23:10:22.990013 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Feb 13 23:10:22.990026 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Feb 13 23:10:22.990037 systemd[1]: Finished systemd-fsck-usr.service. Feb 13 23:10:22.990048 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Feb 13 23:10:22.990085 systemd-journald[201]: Collecting audit messages is disabled. Feb 13 23:10:22.990114 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Feb 13 23:10:22.990125 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Feb 13 23:10:22.990136 kernel: Bridge firewalling registered Feb 13 23:10:22.990148 systemd-journald[201]: Journal started Feb 13 23:10:22.990174 systemd-journald[201]: Runtime Journal (/run/log/journal/c93f943d3c5441d18c25dedd04fe76f2) is 4.7M, max 38.0M, 33.2M free. Feb 13 23:10:22.930082 systemd-modules-load[202]: Inserted module 'overlay' Feb 13 23:10:22.983542 systemd-modules-load[202]: Inserted module 'br_netfilter' Feb 13 23:10:22.993847 systemd[1]: Started systemd-journald.service - Journal Service. Feb 13 23:10:22.994787 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Feb 13 23:10:22.995430 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 23:10:23.003768 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Feb 13 23:10:23.007752 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Feb 13 23:10:23.009327 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Feb 13 23:10:23.010427 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Feb 13 23:10:23.022869 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 23:10:23.025754 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Feb 13 23:10:23.042802 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Feb 13 23:10:23.043473 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 13 23:10:23.044070 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 13 23:10:23.047363 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Feb 13 23:10:23.058143 dracut-cmdline[232]: dracut-dracut-053 Feb 13 23:10:23.061294 dracut-cmdline[232]: 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=ed9b5d8ea73d2e47b8decea8124089e04dd398ef43013c1b1a5809314044b1c3 Feb 13 23:10:23.090285 systemd-resolved[237]: Positive Trust Anchors: Feb 13 23:10:23.090309 systemd-resolved[237]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Feb 13 23:10:23.090350 systemd-resolved[237]: 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 Feb 13 23:10:23.093800 systemd-resolved[237]: Defaulting to hostname 'linux'. Feb 13 23:10:23.095110 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Feb 13 23:10:23.096955 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Feb 13 23:10:23.167620 kernel: SCSI subsystem initialized Feb 13 23:10:23.177676 kernel: Loading iSCSI transport class v2.0-870. Feb 13 23:10:23.188639 kernel: iscsi: registered transport (tcp) Feb 13 23:10:23.211642 kernel: iscsi: registered transport (qla4xxx) Feb 13 23:10:23.211788 kernel: QLogic iSCSI HBA Driver Feb 13 23:10:23.272547 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Feb 13 23:10:23.277761 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Feb 13 23:10:23.305864 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Feb 13 23:10:23.305971 kernel: device-mapper: uevent: version 1.0.3 Feb 13 23:10:23.306010 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Feb 13 23:10:23.361659 kernel: raid6: avx512x4 gen() 18027 MB/s Feb 13 23:10:23.378653 kernel: raid6: avx512x2 gen() 17852 MB/s Feb 13 23:10:23.395702 kernel: raid6: avx512x1 gen() 17812 MB/s Feb 13 23:10:23.412664 kernel: raid6: avx2x4 gen() 17724 MB/s Feb 13 23:10:23.429642 kernel: raid6: avx2x2 gen() 17718 MB/s Feb 13 23:10:23.447122 kernel: raid6: avx2x1 gen() 13483 MB/s Feb 13 23:10:23.447227 kernel: raid6: using algorithm avx512x4 gen() 18027 MB/s Feb 13 23:10:23.464710 kernel: raid6: .... xor() 7485 MB/s, rmw enabled Feb 13 23:10:23.464801 kernel: raid6: using avx512x2 recovery algorithm Feb 13 23:10:23.486647 kernel: xor: automatically using best checksumming function avx Feb 13 23:10:23.653631 kernel: Btrfs loaded, zoned=no, fsverity=no Feb 13 23:10:23.666243 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Feb 13 23:10:23.671748 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 13 23:10:23.696314 systemd-udevd[420]: Using default interface naming scheme 'v255'. Feb 13 23:10:23.701359 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 13 23:10:23.708754 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Feb 13 23:10:23.728565 dracut-pre-trigger[427]: rd.md=0: removing MD RAID activation Feb 13 23:10:23.761837 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Feb 13 23:10:23.766770 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Feb 13 23:10:23.826198 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Feb 13 23:10:23.834765 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Feb 13 23:10:23.858746 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Feb 13 23:10:23.861058 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Feb 13 23:10:23.862871 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Feb 13 23:10:23.864041 systemd[1]: Reached target remote-fs.target - Remote File Systems. Feb 13 23:10:23.869737 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Feb 13 23:10:23.890819 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Feb 13 23:10:23.913366 kernel: virtio_blk virtio1: 2/0/0 default/read/poll queues Feb 13 23:10:23.991626 kernel: cryptd: max_cpu_qlen set to 1000 Feb 13 23:10:23.991653 kernel: virtio_blk virtio1: [vda] 125829120 512-byte logical blocks (64.4 GB/60.0 GiB) Feb 13 23:10:23.991773 kernel: ACPI: bus type USB registered Feb 13 23:10:23.991788 kernel: usbcore: registered new interface driver usbfs Feb 13 23:10:23.991801 kernel: usbcore: registered new interface driver hub Feb 13 23:10:23.991814 kernel: usbcore: registered new device driver usb Feb 13 23:10:23.991827 kernel: AVX2 version of gcm_enc/dec engaged. Feb 13 23:10:23.991844 kernel: AES CTR mode by8 optimization enabled Feb 13 23:10:23.991857 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Feb 13 23:10:23.991870 kernel: GPT:17805311 != 125829119 Feb 13 23:10:23.991882 kernel: GPT:Alternate GPT header not at the end of the disk. Feb 13 23:10:23.991894 kernel: GPT:17805311 != 125829119 Feb 13 23:10:23.991906 kernel: GPT: Use GNU Parted to correct GPT errors. Feb 13 23:10:23.991919 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Feb 13 23:10:23.953505 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Feb 13 23:10:24.066073 kernel: libata version 3.00 loaded. Feb 13 23:10:24.066099 kernel: xhci_hcd 0000:03:00.0: xHCI Host Controller Feb 13 23:10:24.066276 kernel: xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 1 Feb 13 23:10:24.066407 kernel: xhci_hcd 0000:03:00.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 Feb 13 23:10:24.066522 kernel: xhci_hcd 0000:03:00.0: xHCI Host Controller Feb 13 23:10:24.066652 kernel: xhci_hcd 0000:03:00.0: new USB bus registered, assigned bus number 2 Feb 13 23:10:24.066763 kernel: xhci_hcd 0000:03:00.0: Host supports USB 3.0 SuperSpeed Feb 13 23:10:24.066875 kernel: BTRFS: device fsid e9c87d9f-3864-4b45-9be4-80a5397f1fc6 devid 1 transid 38 /dev/vda3 scanned by (udev-worker) (468) Feb 13 23:10:24.066890 kernel: hub 1-0:1.0: USB hub found Feb 13 23:10:24.067026 kernel: hub 1-0:1.0: 4 ports detected Feb 13 23:10:24.067145 kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. Feb 13 23:10:24.067326 kernel: hub 2-0:1.0: USB hub found Feb 13 23:10:24.067463 kernel: hub 2-0:1.0: 4 ports detected Feb 13 23:10:24.067582 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by (udev-worker) (465) Feb 13 23:10:24.068627 kernel: ahci 0000:00:1f.2: version 3.0 Feb 13 23:10:24.086635 kernel: ACPI: \_SB_.GSIA: Enabled at IRQ 16 Feb 13 23:10:24.086656 kernel: ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode Feb 13 23:10:24.086790 kernel: ahci 0000:00:1f.2: flags: 64bit ncq only Feb 13 23:10:24.086903 kernel: scsi host0: ahci Feb 13 23:10:24.087037 kernel: scsi host1: ahci Feb 13 23:10:24.087150 kernel: scsi host2: ahci Feb 13 23:10:24.087255 kernel: scsi host3: ahci Feb 13 23:10:24.087367 kernel: scsi host4: ahci Feb 13 23:10:24.087474 kernel: scsi host5: ahci Feb 13 23:10:24.087575 kernel: ata1: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b100 irq 41 Feb 13 23:10:24.087763 kernel: ata2: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b180 irq 41 Feb 13 23:10:24.087783 kernel: ata3: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b200 irq 41 Feb 13 23:10:24.087796 kernel: ata4: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b280 irq 41 Feb 13 23:10:24.087809 kernel: ata5: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b300 irq 41 Feb 13 23:10:24.087821 kernel: ata6: SATA max UDMA/133 abar m4096@0xfea5b000 port 0xfea5b380 irq 41 Feb 13 23:10:23.953667 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 23:10:23.954404 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Feb 13 23:10:23.955164 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Feb 13 23:10:23.955299 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 23:10:23.956230 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 23:10:23.963833 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 23:10:24.056597 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Feb 13 23:10:24.066996 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 23:10:24.075866 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Feb 13 23:10:24.084980 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Feb 13 23:10:24.089070 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Feb 13 23:10:24.094339 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Feb 13 23:10:24.104716 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Feb 13 23:10:24.107731 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Feb 13 23:10:24.111372 disk-uuid[565]: Primary Header is updated. Feb 13 23:10:24.111372 disk-uuid[565]: Secondary Entries is updated. Feb 13 23:10:24.111372 disk-uuid[565]: Secondary Header is updated. Feb 13 23:10:24.116634 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Feb 13 23:10:24.136165 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 23:10:24.273779 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Feb 13 23:10:24.401650 kernel: ata5: SATA link down (SStatus 0 SControl 300) Feb 13 23:10:24.401908 kernel: ata1: SATA link down (SStatus 0 SControl 300) Feb 13 23:10:24.404835 kernel: ata2: SATA link down (SStatus 0 SControl 300) Feb 13 23:10:24.408644 kernel: ata4: SATA link down (SStatus 0 SControl 300) Feb 13 23:10:24.408710 kernel: ata3: SATA link down (SStatus 0 SControl 300) Feb 13 23:10:24.410088 kernel: ata6: SATA link down (SStatus 0 SControl 300) Feb 13 23:10:24.426627 kernel: hid: raw HID events driver (C) Jiri Kosina Feb 13 23:10:24.434790 kernel: usbcore: registered new interface driver usbhid Feb 13 23:10:24.434876 kernel: usbhid: USB HID core driver Feb 13 23:10:24.440642 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 Feb 13 23:10:24.440701 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 Feb 13 23:10:25.128647 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Feb 13 23:10:25.132622 disk-uuid[567]: The operation has completed successfully. Feb 13 23:10:25.167952 systemd[1]: disk-uuid.service: Deactivated successfully. Feb 13 23:10:25.168060 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Feb 13 23:10:25.186739 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Feb 13 23:10:25.198816 sh[586]: Success Feb 13 23:10:25.212626 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Feb 13 23:10:25.263430 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Feb 13 23:10:25.272696 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Feb 13 23:10:25.275225 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Feb 13 23:10:25.297855 kernel: BTRFS info (device dm-0): first mount of filesystem e9c87d9f-3864-4b45-9be4-80a5397f1fc6 Feb 13 23:10:25.297941 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Feb 13 23:10:25.297994 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Feb 13 23:10:25.299051 kernel: BTRFS info (device dm-0): disabling log replay at mount time Feb 13 23:10:25.299906 kernel: BTRFS info (device dm-0): using free space tree Feb 13 23:10:25.307999 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Feb 13 23:10:25.310516 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Feb 13 23:10:25.322827 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Feb 13 23:10:25.327246 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Feb 13 23:10:25.335612 kernel: BTRFS info (device vda6): first mount of filesystem 84d576e4-038f-4c76-aa8e-6cfd81e812ea Feb 13 23:10:25.335649 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Feb 13 23:10:25.335663 kernel: BTRFS info (device vda6): using free space tree Feb 13 23:10:25.338603 kernel: BTRFS info (device vda6): auto enabling async discard Feb 13 23:10:25.348250 systemd[1]: mnt-oem.mount: Deactivated successfully. Feb 13 23:10:25.348928 kernel: BTRFS info (device vda6): last unmount of filesystem 84d576e4-038f-4c76-aa8e-6cfd81e812ea Feb 13 23:10:25.359232 systemd[1]: Finished ignition-setup.service - Ignition (setup). Feb 13 23:10:25.367729 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Feb 13 23:10:25.455422 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Feb 13 23:10:25.462807 systemd[1]: Starting systemd-networkd.service - Network Configuration... Feb 13 23:10:25.485515 ignition[673]: Ignition 2.20.0 Feb 13 23:10:25.485562 ignition[673]: Stage: fetch-offline Feb 13 23:10:25.486205 ignition[673]: no configs at "/usr/lib/ignition/base.d" Feb 13 23:10:25.486218 ignition[673]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 23:10:25.486354 ignition[673]: parsed url from cmdline: "" Feb 13 23:10:25.486359 ignition[673]: no config URL provided Feb 13 23:10:25.486364 ignition[673]: reading system config file "/usr/lib/ignition/user.ign" Feb 13 23:10:25.486372 ignition[673]: no config at "/usr/lib/ignition/user.ign" Feb 13 23:10:25.486377 ignition[673]: failed to fetch config: resource requires networking Feb 13 23:10:25.486573 ignition[673]: Ignition finished successfully Feb 13 23:10:25.491875 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Feb 13 23:10:25.493148 systemd-networkd[774]: lo: Link UP Feb 13 23:10:25.493160 systemd-networkd[774]: lo: Gained carrier Feb 13 23:10:25.494675 systemd-networkd[774]: Enumeration completed Feb 13 23:10:25.495041 systemd-networkd[774]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 23:10:25.495045 systemd-networkd[774]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 13 23:10:25.495108 systemd[1]: Started systemd-networkd.service - Network Configuration. Feb 13 23:10:25.496346 systemd-networkd[774]: eth0: Link UP Feb 13 23:10:25.496350 systemd-networkd[774]: eth0: Gained carrier Feb 13 23:10:25.496358 systemd-networkd[774]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 23:10:25.496746 systemd[1]: Reached target network.target - Network. Feb 13 23:10:25.503761 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Feb 13 23:10:25.510734 systemd-networkd[774]: eth0: DHCPv4 address 10.244.92.250/30, gateway 10.244.92.249 acquired from 10.244.92.249 Feb 13 23:10:25.533203 ignition[777]: Ignition 2.20.0 Feb 13 23:10:25.533220 ignition[777]: Stage: fetch Feb 13 23:10:25.533421 ignition[777]: no configs at "/usr/lib/ignition/base.d" Feb 13 23:10:25.533433 ignition[777]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 23:10:25.533517 ignition[777]: parsed url from cmdline: "" Feb 13 23:10:25.533521 ignition[777]: no config URL provided Feb 13 23:10:25.533526 ignition[777]: reading system config file "/usr/lib/ignition/user.ign" Feb 13 23:10:25.533535 ignition[777]: no config at "/usr/lib/ignition/user.ign" Feb 13 23:10:25.533651 ignition[777]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 Feb 13 23:10:25.533699 ignition[777]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... Feb 13 23:10:25.533728 ignition[777]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... Feb 13 23:10:25.549507 ignition[777]: GET result: OK Feb 13 23:10:25.549805 ignition[777]: parsing config with SHA512: 969ca06bb4bd92dbc1bcd00f75c601c165439c27dd672e710d9a76c2974716cec9a31099fc51d16884e6eede5da451c1102c1ee0b3f613ecd04f5c5bbca0b0b1 Feb 13 23:10:25.552830 unknown[777]: fetched base config from "system" Feb 13 23:10:25.552840 unknown[777]: fetched base config from "system" Feb 13 23:10:25.553108 ignition[777]: fetch: fetch complete Feb 13 23:10:25.552846 unknown[777]: fetched user config from "openstack" Feb 13 23:10:25.553113 ignition[777]: fetch: fetch passed Feb 13 23:10:25.554903 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Feb 13 23:10:25.553157 ignition[777]: Ignition finished successfully Feb 13 23:10:25.569987 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Feb 13 23:10:25.583937 ignition[786]: Ignition 2.20.0 Feb 13 23:10:25.583953 ignition[786]: Stage: kargs Feb 13 23:10:25.585988 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Feb 13 23:10:25.584137 ignition[786]: no configs at "/usr/lib/ignition/base.d" Feb 13 23:10:25.584147 ignition[786]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 23:10:25.584864 ignition[786]: kargs: kargs passed Feb 13 23:10:25.584907 ignition[786]: Ignition finished successfully Feb 13 23:10:25.592870 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Feb 13 23:10:25.604576 ignition[793]: Ignition 2.20.0 Feb 13 23:10:25.604587 ignition[793]: Stage: disks Feb 13 23:10:25.604793 ignition[793]: no configs at "/usr/lib/ignition/base.d" Feb 13 23:10:25.606647 systemd[1]: Finished ignition-disks.service - Ignition (disks). Feb 13 23:10:25.604805 ignition[793]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 23:10:25.608158 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Feb 13 23:10:25.605462 ignition[793]: disks: disks passed Feb 13 23:10:25.608659 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Feb 13 23:10:25.605503 ignition[793]: Ignition finished successfully Feb 13 23:10:25.609446 systemd[1]: Reached target local-fs.target - Local File Systems. Feb 13 23:10:25.610301 systemd[1]: Reached target sysinit.target - System Initialization. Feb 13 23:10:25.611122 systemd[1]: Reached target basic.target - Basic System. Feb 13 23:10:25.618975 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Feb 13 23:10:25.632202 systemd-fsck[801]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Feb 13 23:10:25.634928 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Feb 13 23:10:25.640694 systemd[1]: Mounting sysroot.mount - /sysroot... Feb 13 23:10:25.746637 kernel: EXT4-fs (vda9): mounted filesystem c5993b0e-9201-4b44-aa01-79dc9d6c9fc9 r/w with ordered data mode. Quota mode: none. Feb 13 23:10:25.747267 systemd[1]: Mounted sysroot.mount - /sysroot. Feb 13 23:10:25.748522 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Feb 13 23:10:25.765856 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Feb 13 23:10:25.769792 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Feb 13 23:10:25.770827 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Feb 13 23:10:25.773355 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent... Feb 13 23:10:25.774184 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Feb 13 23:10:25.777024 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Feb 13 23:10:25.781622 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/vda6 scanned by mount (809) Feb 13 23:10:25.784313 kernel: BTRFS info (device vda6): first mount of filesystem 84d576e4-038f-4c76-aa8e-6cfd81e812ea Feb 13 23:10:25.784349 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Feb 13 23:10:25.784364 kernel: BTRFS info (device vda6): using free space tree Feb 13 23:10:25.788620 kernel: BTRFS info (device vda6): auto enabling async discard Feb 13 23:10:25.792897 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Feb 13 23:10:25.798947 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Feb 13 23:10:25.806888 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Feb 13 23:10:25.869117 initrd-setup-root[838]: cut: /sysroot/etc/passwd: No such file or directory Feb 13 23:10:25.879783 initrd-setup-root[845]: cut: /sysroot/etc/group: No such file or directory Feb 13 23:10:25.891072 initrd-setup-root[852]: cut: /sysroot/etc/shadow: No such file or directory Feb 13 23:10:25.899932 initrd-setup-root[859]: cut: /sysroot/etc/gshadow: No such file or directory Feb 13 23:10:26.004502 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Feb 13 23:10:26.010758 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Feb 13 23:10:26.013803 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Feb 13 23:10:26.024621 kernel: BTRFS info (device vda6): last unmount of filesystem 84d576e4-038f-4c76-aa8e-6cfd81e812ea Feb 13 23:10:26.049552 ignition[927]: INFO : Ignition 2.20.0 Feb 13 23:10:26.050537 ignition[927]: INFO : Stage: mount Feb 13 23:10:26.049630 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Feb 13 23:10:26.051399 ignition[927]: INFO : no configs at "/usr/lib/ignition/base.d" Feb 13 23:10:26.051399 ignition[927]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 23:10:26.052472 ignition[927]: INFO : mount: mount passed Feb 13 23:10:26.052472 ignition[927]: INFO : Ignition finished successfully Feb 13 23:10:26.052973 systemd[1]: Finished ignition-mount.service - Ignition (mount). Feb 13 23:10:26.299728 systemd[1]: sysroot-oem.mount: Deactivated successfully. Feb 13 23:10:27.205078 systemd-networkd[774]: eth0: Gained IPv6LL Feb 13 23:10:28.711715 systemd-networkd[774]: eth0: Ignoring DHCPv6 address 2a02:1348:17d:173e:24:19ff:fef4:5cfa/128 (valid for 59min 59s, preferred for 59min 59s) which conflicts with 2a02:1348:17d:173e:24:19ff:fef4:5cfa/64 assigned by NDisc. Feb 13 23:10:28.711727 systemd-networkd[774]: eth0: Hint: use IPv6Token= setting to change the address generated by NDisc or set UseAutonomousPrefix=no. Feb 13 23:10:32.955368 coreos-metadata[811]: Feb 13 23:10:32.955 WARN failed to locate config-drive, using the metadata service API instead Feb 13 23:10:32.973899 coreos-metadata[811]: Feb 13 23:10:32.973 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Feb 13 23:10:32.989431 coreos-metadata[811]: Feb 13 23:10:32.989 INFO Fetch successful Feb 13 23:10:32.990914 coreos-metadata[811]: Feb 13 23:10:32.990 INFO wrote hostname srv-wqs75.gb1.brightbox.com to /sysroot/etc/hostname Feb 13 23:10:32.993639 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. Feb 13 23:10:32.993883 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent. Feb 13 23:10:33.009862 systemd[1]: Starting ignition-files.service - Ignition (files)... Feb 13 23:10:33.020501 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Feb 13 23:10:33.041617 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/vda6 scanned by mount (944) Feb 13 23:10:33.045402 kernel: BTRFS info (device vda6): first mount of filesystem 84d576e4-038f-4c76-aa8e-6cfd81e812ea Feb 13 23:10:33.045441 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Feb 13 23:10:33.045464 kernel: BTRFS info (device vda6): using free space tree Feb 13 23:10:33.048607 kernel: BTRFS info (device vda6): auto enabling async discard Feb 13 23:10:33.050859 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Feb 13 23:10:33.075634 ignition[962]: INFO : Ignition 2.20.0 Feb 13 23:10:33.075634 ignition[962]: INFO : Stage: files Feb 13 23:10:33.075634 ignition[962]: INFO : no configs at "/usr/lib/ignition/base.d" Feb 13 23:10:33.075634 ignition[962]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 23:10:33.077699 ignition[962]: DEBUG : files: compiled without relabeling support, skipping Feb 13 23:10:33.077699 ignition[962]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Feb 13 23:10:33.077699 ignition[962]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Feb 13 23:10:33.082932 ignition[962]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Feb 13 23:10:33.083575 ignition[962]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Feb 13 23:10:33.084334 ignition[962]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Feb 13 23:10:33.084149 unknown[962]: wrote ssh authorized keys file for user: core Feb 13 23:10:33.087265 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/home/core/install.sh" Feb 13 23:10:33.091322 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/home/core/install.sh" Feb 13 23:10:33.091322 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/flatcar/update.conf" Feb 13 23:10:33.091322 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/flatcar/update.conf" Feb 13 23:10:33.091322 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Feb 13 23:10:33.091322 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing link "/sysroot/etc/extensions/kubernetes.raw" -> "/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Feb 13 23:10:33.091322 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Feb 13 23:10:33.091322 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET https://github.com/flatcar/sysext-bakery/releases/download/latest/kubernetes-v1.32.0-x86-64.raw: attempt #1 Feb 13 23:10:33.641752 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(6): GET result: OK Feb 13 23:10:35.166159 ignition[962]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/opt/extensions/kubernetes/kubernetes-v1.32.0-x86-64.raw" Feb 13 23:10:35.176356 ignition[962]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Feb 13 23:10:35.176356 ignition[962]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Feb 13 23:10:35.176356 ignition[962]: INFO : files: files passed Feb 13 23:10:35.176356 ignition[962]: INFO : Ignition finished successfully Feb 13 23:10:35.181029 systemd[1]: Finished ignition-files.service - Ignition (files). Feb 13 23:10:35.186948 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Feb 13 23:10:35.189723 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Feb 13 23:10:35.199474 systemd[1]: ignition-quench.service: Deactivated successfully. Feb 13 23:10:35.199588 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Feb 13 23:10:35.214629 initrd-setup-root-after-ignition[990]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Feb 13 23:10:35.216783 initrd-setup-root-after-ignition[994]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Feb 13 23:10:35.218754 initrd-setup-root-after-ignition[990]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Feb 13 23:10:35.221663 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Feb 13 23:10:35.222413 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Feb 13 23:10:35.227738 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Feb 13 23:10:35.251709 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Feb 13 23:10:35.251823 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Feb 13 23:10:35.252827 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Feb 13 23:10:35.253529 systemd[1]: Reached target initrd.target - Initrd Default Target. Feb 13 23:10:35.254324 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Feb 13 23:10:35.258742 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Feb 13 23:10:35.272166 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Feb 13 23:10:35.276739 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Feb 13 23:10:35.293801 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Feb 13 23:10:35.295193 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Feb 13 23:10:35.296721 systemd[1]: Stopped target timers.target - Timer Units. Feb 13 23:10:35.297912 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Feb 13 23:10:35.298071 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Feb 13 23:10:35.300007 systemd[1]: Stopped target initrd.target - Initrd Default Target. Feb 13 23:10:35.300582 systemd[1]: Stopped target basic.target - Basic System. Feb 13 23:10:35.301458 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Feb 13 23:10:35.302130 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Feb 13 23:10:35.302945 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Feb 13 23:10:35.303786 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Feb 13 23:10:35.304600 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Feb 13 23:10:35.305433 systemd[1]: Stopped target sysinit.target - System Initialization. Feb 13 23:10:35.306266 systemd[1]: Stopped target local-fs.target - Local File Systems. Feb 13 23:10:35.307051 systemd[1]: Stopped target swap.target - Swaps. Feb 13 23:10:35.307739 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Feb 13 23:10:35.307857 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Feb 13 23:10:35.308774 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Feb 13 23:10:35.309326 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Feb 13 23:10:35.310097 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Feb 13 23:10:35.310188 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Feb 13 23:10:35.310937 systemd[1]: dracut-initqueue.service: Deactivated successfully. Feb 13 23:10:35.311039 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Feb 13 23:10:35.312048 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Feb 13 23:10:35.312155 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Feb 13 23:10:35.313202 systemd[1]: ignition-files.service: Deactivated successfully. Feb 13 23:10:35.313318 systemd[1]: Stopped ignition-files.service - Ignition (files). Feb 13 23:10:35.319765 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Feb 13 23:10:35.320185 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Feb 13 23:10:35.320307 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Feb 13 23:10:35.323784 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Feb 13 23:10:35.324181 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Feb 13 23:10:35.324303 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Feb 13 23:10:35.326847 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Feb 13 23:10:35.326956 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Feb 13 23:10:35.333125 systemd[1]: initrd-cleanup.service: Deactivated successfully. Feb 13 23:10:35.333226 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Feb 13 23:10:35.336959 ignition[1014]: INFO : Ignition 2.20.0 Feb 13 23:10:35.338692 ignition[1014]: INFO : Stage: umount Feb 13 23:10:35.338692 ignition[1014]: INFO : no configs at "/usr/lib/ignition/base.d" Feb 13 23:10:35.338692 ignition[1014]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Feb 13 23:10:35.338692 ignition[1014]: INFO : umount: umount passed Feb 13 23:10:35.338692 ignition[1014]: INFO : Ignition finished successfully Feb 13 23:10:35.340480 systemd[1]: ignition-mount.service: Deactivated successfully. Feb 13 23:10:35.340798 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Feb 13 23:10:35.341428 systemd[1]: ignition-disks.service: Deactivated successfully. Feb 13 23:10:35.341474 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Feb 13 23:10:35.341855 systemd[1]: ignition-kargs.service: Deactivated successfully. Feb 13 23:10:35.341889 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Feb 13 23:10:35.342242 systemd[1]: ignition-fetch.service: Deactivated successfully. Feb 13 23:10:35.342279 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Feb 13 23:10:35.343703 systemd[1]: Stopped target network.target - Network. Feb 13 23:10:35.344188 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Feb 13 23:10:35.344241 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Feb 13 23:10:35.345923 systemd[1]: Stopped target paths.target - Path Units. Feb 13 23:10:35.346276 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Feb 13 23:10:35.352211 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Feb 13 23:10:35.353083 systemd[1]: Stopped target slices.target - Slice Units. Feb 13 23:10:35.353881 systemd[1]: Stopped target sockets.target - Socket Units. Feb 13 23:10:35.354620 systemd[1]: iscsid.socket: Deactivated successfully. Feb 13 23:10:35.354658 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Feb 13 23:10:35.355066 systemd[1]: iscsiuio.socket: Deactivated successfully. Feb 13 23:10:35.355099 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Feb 13 23:10:35.355924 systemd[1]: ignition-setup.service: Deactivated successfully. Feb 13 23:10:35.355965 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Feb 13 23:10:35.356827 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Feb 13 23:10:35.356864 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Feb 13 23:10:35.357714 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Feb 13 23:10:35.359068 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Feb 13 23:10:35.360876 systemd[1]: sysroot-boot.mount: Deactivated successfully. Feb 13 23:10:35.361400 systemd[1]: sysroot-boot.service: Deactivated successfully. Feb 13 23:10:35.361479 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Feb 13 23:10:35.362500 systemd[1]: initrd-setup-root.service: Deactivated successfully. Feb 13 23:10:35.362719 systemd-networkd[774]: eth0: DHCPv6 lease lost Feb 13 23:10:35.364285 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Feb 13 23:10:35.365045 systemd[1]: systemd-networkd.service: Deactivated successfully. Feb 13 23:10:35.365158 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Feb 13 23:10:35.366568 systemd[1]: systemd-networkd.socket: Deactivated successfully. Feb 13 23:10:35.366808 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Feb 13 23:10:35.378668 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Feb 13 23:10:35.379482 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Feb 13 23:10:35.379527 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Feb 13 23:10:35.380633 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 13 23:10:35.381914 systemd[1]: systemd-resolved.service: Deactivated successfully. Feb 13 23:10:35.382012 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Feb 13 23:10:35.387203 systemd[1]: systemd-sysctl.service: Deactivated successfully. Feb 13 23:10:35.387293 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Feb 13 23:10:35.389823 systemd[1]: systemd-modules-load.service: Deactivated successfully. Feb 13 23:10:35.389865 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Feb 13 23:10:35.390252 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Feb 13 23:10:35.390284 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 13 23:10:35.392721 systemd[1]: systemd-udevd.service: Deactivated successfully. Feb 13 23:10:35.392866 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 13 23:10:35.393933 systemd[1]: network-cleanup.service: Deactivated successfully. Feb 13 23:10:35.394020 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Feb 13 23:10:35.395529 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Feb 13 23:10:35.395607 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Feb 13 23:10:35.396210 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Feb 13 23:10:35.396248 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Feb 13 23:10:35.397008 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Feb 13 23:10:35.397050 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Feb 13 23:10:35.398145 systemd[1]: dracut-cmdline.service: Deactivated successfully. Feb 13 23:10:35.398183 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Feb 13 23:10:35.400774 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Feb 13 23:10:35.400816 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Feb 13 23:10:35.407717 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Feb 13 23:10:35.408675 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Feb 13 23:10:35.408722 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 13 23:10:35.409760 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Feb 13 23:10:35.409797 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 23:10:35.413502 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Feb 13 23:10:35.413623 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Feb 13 23:10:35.414772 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Feb 13 23:10:35.419710 systemd[1]: Starting initrd-switch-root.service - Switch Root... Feb 13 23:10:35.426372 systemd[1]: Switching root. Feb 13 23:10:35.452148 systemd-journald[201]: Journal stopped Feb 13 23:10:36.520350 systemd-journald[201]: Received SIGTERM from PID 1 (systemd). Feb 13 23:10:36.520427 kernel: SELinux: policy capability network_peer_controls=1 Feb 13 23:10:36.520446 kernel: SELinux: policy capability open_perms=1 Feb 13 23:10:36.520461 kernel: SELinux: policy capability extended_socket_class=1 Feb 13 23:10:36.520481 kernel: SELinux: policy capability always_check_network=0 Feb 13 23:10:36.520493 kernel: SELinux: policy capability cgroup_seclabel=1 Feb 13 23:10:36.520506 kernel: SELinux: policy capability nnp_nosuid_transition=1 Feb 13 23:10:36.520521 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Feb 13 23:10:36.520536 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Feb 13 23:10:36.520548 kernel: audit: type=1403 audit(1739488235.636:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Feb 13 23:10:36.520560 systemd[1]: Successfully loaded SELinux policy in 41.732ms. Feb 13 23:10:36.520588 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 11.374ms. Feb 13 23:10:36.525550 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) Feb 13 23:10:36.525570 systemd[1]: Detected virtualization kvm. Feb 13 23:10:36.525584 systemd[1]: Detected architecture x86-64. Feb 13 23:10:36.525609 systemd[1]: Detected first boot. Feb 13 23:10:36.525710 systemd[1]: Hostname set to . Feb 13 23:10:36.525733 systemd[1]: Initializing machine ID from VM UUID. Feb 13 23:10:36.525746 zram_generator::config[1056]: No configuration found. Feb 13 23:10:36.525768 systemd[1]: Populated /etc with preset unit settings. Feb 13 23:10:36.525782 systemd[1]: initrd-switch-root.service: Deactivated successfully. Feb 13 23:10:36.525800 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Feb 13 23:10:36.525813 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Feb 13 23:10:36.525832 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Feb 13 23:10:36.525845 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Feb 13 23:10:36.525856 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Feb 13 23:10:36.525868 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Feb 13 23:10:36.525881 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Feb 13 23:10:36.525894 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Feb 13 23:10:36.525909 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Feb 13 23:10:36.525928 systemd[1]: Created slice user.slice - User and Session Slice. Feb 13 23:10:36.525942 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Feb 13 23:10:36.525955 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Feb 13 23:10:36.525967 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Feb 13 23:10:36.525980 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Feb 13 23:10:36.525992 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Feb 13 23:10:36.526009 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Feb 13 23:10:36.526022 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Feb 13 23:10:36.526045 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Feb 13 23:10:36.526059 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Feb 13 23:10:36.526072 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Feb 13 23:10:36.526084 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Feb 13 23:10:36.526097 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Feb 13 23:10:36.526110 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Feb 13 23:10:36.526133 systemd[1]: Reached target remote-fs.target - Remote File Systems. Feb 13 23:10:36.526147 systemd[1]: Reached target slices.target - Slice Units. Feb 13 23:10:36.526163 systemd[1]: Reached target swap.target - Swaps. Feb 13 23:10:36.526175 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Feb 13 23:10:36.526188 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Feb 13 23:10:36.526200 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Feb 13 23:10:36.526217 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Feb 13 23:10:36.526230 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Feb 13 23:10:36.526243 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Feb 13 23:10:36.526259 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Feb 13 23:10:36.526275 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Feb 13 23:10:36.526288 systemd[1]: Mounting media.mount - External Media Directory... Feb 13 23:10:36.526300 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 23:10:36.526313 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Feb 13 23:10:36.526337 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Feb 13 23:10:36.526358 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Feb 13 23:10:36.526372 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). Feb 13 23:10:36.526385 systemd[1]: Reached target machines.target - Containers. Feb 13 23:10:36.526397 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Feb 13 23:10:36.526410 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 23:10:36.526422 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Feb 13 23:10:36.526436 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Feb 13 23:10:36.526449 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 13 23:10:36.526461 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Feb 13 23:10:36.526477 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 13 23:10:36.526490 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Feb 13 23:10:36.526503 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 13 23:10:36.526516 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Feb 13 23:10:36.526529 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Feb 13 23:10:36.526542 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Feb 13 23:10:36.526554 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Feb 13 23:10:36.526567 systemd[1]: Stopped systemd-fsck-usr.service. Feb 13 23:10:36.526582 systemd[1]: Starting systemd-journald.service - Journal Service... Feb 13 23:10:36.527612 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Feb 13 23:10:36.530819 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Feb 13 23:10:36.530843 kernel: fuse: init (API version 7.39) Feb 13 23:10:36.530856 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Feb 13 23:10:36.530867 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Feb 13 23:10:36.530879 systemd[1]: verity-setup.service: Deactivated successfully. Feb 13 23:10:36.530890 systemd[1]: Stopped verity-setup.service. Feb 13 23:10:36.530901 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 23:10:36.530918 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Feb 13 23:10:36.530930 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Feb 13 23:10:36.530941 systemd[1]: Mounted media.mount - External Media Directory. Feb 13 23:10:36.530952 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Feb 13 23:10:36.530963 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Feb 13 23:10:36.530980 kernel: ACPI: bus type drm_connector registered Feb 13 23:10:36.530992 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Feb 13 23:10:36.531002 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Feb 13 23:10:36.531013 systemd[1]: modprobe@configfs.service: Deactivated successfully. Feb 13 23:10:36.531024 kernel: loop: module loaded Feb 13 23:10:36.531034 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Feb 13 23:10:36.531045 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 13 23:10:36.531057 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 13 23:10:36.531067 systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 13 23:10:36.531081 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Feb 13 23:10:36.531096 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 13 23:10:36.531107 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 13 23:10:36.531125 systemd[1]: modprobe@fuse.service: Deactivated successfully. Feb 13 23:10:36.531136 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Feb 13 23:10:36.531149 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 13 23:10:36.531160 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 13 23:10:36.531170 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Feb 13 23:10:36.531182 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Feb 13 23:10:36.531192 systemd[1]: Reached target network-pre.target - Preparation for Network. Feb 13 23:10:36.531223 systemd-journald[1142]: Collecting audit messages is disabled. Feb 13 23:10:36.531248 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Feb 13 23:10:36.531264 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Feb 13 23:10:36.531281 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 13 23:10:36.531292 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Feb 13 23:10:36.531303 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Feb 13 23:10:36.531314 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Feb 13 23:10:36.531325 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Feb 13 23:10:36.531336 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Feb 13 23:10:36.531349 systemd[1]: Reached target local-fs.target - Local File Systems. Feb 13 23:10:36.531361 systemd-journald[1142]: Journal started Feb 13 23:10:36.531386 systemd-journald[1142]: Runtime Journal (/run/log/journal/c93f943d3c5441d18c25dedd04fe76f2) is 4.7M, max 38.0M, 33.2M free. Feb 13 23:10:36.199264 systemd[1]: Queued start job for default target multi-user.target. Feb 13 23:10:36.533875 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Feb 13 23:10:36.223942 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Feb 13 23:10:36.224573 systemd[1]: systemd-journald.service: Deactivated successfully. Feb 13 23:10:36.547652 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Feb 13 23:10:36.549608 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Feb 13 23:10:36.551674 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 23:10:36.560916 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Feb 13 23:10:36.563604 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 13 23:10:36.573663 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Feb 13 23:10:36.582629 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Feb 13 23:10:36.584603 systemd[1]: Started systemd-journald.service - Journal Service. Feb 13 23:10:36.593646 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Feb 13 23:10:36.594350 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Feb 13 23:10:36.595219 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Feb 13 23:10:36.596732 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Feb 13 23:10:36.603411 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Feb 13 23:10:36.626615 kernel: loop0: detected capacity change from 0 to 138184 Feb 13 23:10:36.666621 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Feb 13 23:10:36.669657 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 13 23:10:36.676792 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Feb 13 23:10:36.684740 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Feb 13 23:10:36.689448 systemd[1]: Starting systemd-sysusers.service - Create System Users... Feb 13 23:10:36.697060 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Feb 13 23:10:36.702637 kernel: loop1: detected capacity change from 0 to 140992 Feb 13 23:10:36.708783 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Feb 13 23:10:36.711041 systemd-journald[1142]: Time spent on flushing to /var/log/journal/c93f943d3c5441d18c25dedd04fe76f2 is 69.570ms for 1143 entries. Feb 13 23:10:36.711041 systemd-journald[1142]: System Journal (/var/log/journal/c93f943d3c5441d18c25dedd04fe76f2) is 8.0M, max 584.8M, 576.8M free. Feb 13 23:10:36.807527 systemd-journald[1142]: Received client request to flush runtime journal. Feb 13 23:10:36.807576 kernel: loop2: detected capacity change from 0 to 218376 Feb 13 23:10:36.710896 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Feb 13 23:10:36.739200 udevadm[1202]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Feb 13 23:10:36.785403 systemd[1]: Finished systemd-sysusers.service - Create System Users. Feb 13 23:10:36.791827 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Feb 13 23:10:36.813534 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Feb 13 23:10:36.815622 kernel: loop3: detected capacity change from 0 to 8 Feb 13 23:10:36.827030 systemd-tmpfiles[1207]: ACLs are not supported, ignoring. Feb 13 23:10:36.827335 systemd-tmpfiles[1207]: ACLs are not supported, ignoring. Feb 13 23:10:36.831763 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Feb 13 23:10:36.851123 kernel: loop4: detected capacity change from 0 to 138184 Feb 13 23:10:36.868661 kernel: loop5: detected capacity change from 0 to 140992 Feb 13 23:10:36.884651 kernel: loop6: detected capacity change from 0 to 218376 Feb 13 23:10:36.898766 kernel: loop7: detected capacity change from 0 to 8 Feb 13 23:10:36.899975 (sd-merge)[1214]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'kubernetes', 'oem-openstack'. Feb 13 23:10:36.901298 (sd-merge)[1214]: Merged extensions into '/usr'. Feb 13 23:10:36.907839 systemd[1]: Reloading requested from client PID 1173 ('systemd-sysext') (unit systemd-sysext.service)... Feb 13 23:10:36.907853 systemd[1]: Reloading... Feb 13 23:10:37.013614 zram_generator::config[1240]: No configuration found. Feb 13 23:10:37.107210 ldconfig[1169]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Feb 13 23:10:37.189702 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. Feb 13 23:10:37.238397 systemd[1]: Reloading finished in 330 ms. Feb 13 23:10:37.261794 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Feb 13 23:10:37.265377 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Feb 13 23:10:37.273882 systemd[1]: Starting ensure-sysext.service... Feb 13 23:10:37.276972 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Feb 13 23:10:37.285460 systemd[1]: Reloading requested from client PID 1296 ('systemctl') (unit ensure-sysext.service)... Feb 13 23:10:37.285643 systemd[1]: Reloading... Feb 13 23:10:37.327874 systemd-tmpfiles[1297]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Feb 13 23:10:37.328233 systemd-tmpfiles[1297]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Feb 13 23:10:37.329064 systemd-tmpfiles[1297]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Feb 13 23:10:37.329312 systemd-tmpfiles[1297]: ACLs are not supported, ignoring. Feb 13 23:10:37.329367 systemd-tmpfiles[1297]: ACLs are not supported, ignoring. Feb 13 23:10:37.335703 systemd-tmpfiles[1297]: Detected autofs mount point /boot during canonicalization of boot. Feb 13 23:10:37.335715 systemd-tmpfiles[1297]: Skipping /boot Feb 13 23:10:37.355404 systemd-tmpfiles[1297]: Detected autofs mount point /boot during canonicalization of boot. Feb 13 23:10:37.358655 systemd-tmpfiles[1297]: Skipping /boot Feb 13 23:10:37.381855 zram_generator::config[1323]: No configuration found. Feb 13 23:10:37.543936 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. Feb 13 23:10:37.591938 systemd[1]: Reloading finished in 305 ms. Feb 13 23:10:37.607650 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Feb 13 23:10:37.608484 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Feb 13 23:10:37.626798 systemd[1]: Starting audit-rules.service - Load Audit Rules... Feb 13 23:10:37.630746 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Feb 13 23:10:37.636552 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Feb 13 23:10:37.641770 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Feb 13 23:10:37.645259 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Feb 13 23:10:37.647138 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Feb 13 23:10:37.656647 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 23:10:37.656843 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 23:10:37.664886 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Feb 13 23:10:37.667786 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Feb 13 23:10:37.672892 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Feb 13 23:10:37.673434 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 23:10:37.673568 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 23:10:37.676919 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 23:10:37.677132 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 23:10:37.677284 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 23:10:37.683858 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Feb 13 23:10:37.684321 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 23:10:37.689173 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 23:10:37.689406 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Feb 13 23:10:37.697153 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Feb 13 23:10:37.697738 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Feb 13 23:10:37.697880 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Feb 13 23:10:37.701395 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 13 23:10:37.701530 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Feb 13 23:10:37.704795 systemd[1]: Finished ensure-sysext.service. Feb 13 23:10:37.713815 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Feb 13 23:10:37.726646 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Feb 13 23:10:37.739831 systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 13 23:10:37.740670 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Feb 13 23:10:37.752469 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Feb 13 23:10:37.753360 systemd-udevd[1388]: Using default interface naming scheme 'v255'. Feb 13 23:10:37.754121 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 13 23:10:37.754258 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Feb 13 23:10:37.757933 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 13 23:10:37.758012 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). Feb 13 23:10:37.758958 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 13 23:10:37.759519 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Feb 13 23:10:37.761319 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Feb 13 23:10:37.765634 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Feb 13 23:10:37.777805 systemd[1]: Starting systemd-update-done.service - Update is Completed... Feb 13 23:10:37.795105 systemd[1]: Finished systemd-update-done.service - Update is Completed. Feb 13 23:10:37.798571 augenrules[1422]: No rules Feb 13 23:10:37.799210 systemd[1]: audit-rules.service: Deactivated successfully. Feb 13 23:10:37.799403 systemd[1]: Finished audit-rules.service - Load Audit Rules. Feb 13 23:10:37.800022 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Feb 13 23:10:37.811843 systemd[1]: Starting systemd-networkd.service - Network Configuration... Feb 13 23:10:37.812385 systemd[1]: Started systemd-userdbd.service - User Database Manager. Feb 13 23:10:37.897114 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Feb 13 23:10:37.897657 systemd[1]: Reached target time-set.target - System Time Set. Feb 13 23:10:37.931482 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Feb 13 23:10:37.938492 systemd-networkd[1433]: lo: Link UP Feb 13 23:10:37.939730 systemd-networkd[1433]: lo: Gained carrier Feb 13 23:10:37.940465 systemd-networkd[1433]: Enumeration completed Feb 13 23:10:37.940572 systemd[1]: Started systemd-networkd.service - Network Configuration. Feb 13 23:10:37.947765 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Feb 13 23:10:37.967071 systemd-resolved[1385]: Positive Trust Anchors: Feb 13 23:10:37.967095 systemd-resolved[1385]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Feb 13 23:10:37.967148 systemd-resolved[1385]: 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 Feb 13 23:10:37.976540 systemd-resolved[1385]: Using system hostname 'srv-wqs75.gb1.brightbox.com'. Feb 13 23:10:37.984709 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Feb 13 23:10:37.985257 systemd[1]: Reached target network.target - Network. Feb 13 23:10:37.985615 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Feb 13 23:10:37.998621 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1429) Feb 13 23:10:38.008816 systemd-networkd[1433]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 23:10:38.008827 systemd-networkd[1433]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 13 23:10:38.012786 systemd-networkd[1433]: eth0: Link UP Feb 13 23:10:38.012905 systemd-networkd[1433]: eth0: Gained carrier Feb 13 23:10:38.013006 systemd-networkd[1433]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Feb 13 23:10:38.028694 systemd-networkd[1433]: eth0: DHCPv4 address 10.244.92.250/30, gateway 10.244.92.249 acquired from 10.244.92.249 Feb 13 23:10:38.030794 systemd-timesyncd[1402]: Network configuration changed, trying to establish connection. Feb 13 23:10:38.039196 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Feb 13 23:10:38.045793 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Feb 13 23:10:38.075841 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Feb 13 23:10:38.081609 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 Feb 13 23:10:38.092617 kernel: ACPI: button: Power Button [PWRF] Feb 13 23:10:38.103618 kernel: mousedev: PS/2 mouse device common for all mice Feb 13 23:10:38.116704 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4 Feb 13 23:10:38.126627 kernel: i801_smbus 0000:00:1f.3: SMBus using PCI interrupt Feb 13 23:10:38.133483 kernel: i2c i2c-0: 1/1 memory slots populated (from DMI) Feb 13 23:10:38.133716 kernel: i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD Feb 13 23:10:38.177885 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Feb 13 23:10:38.319224 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Feb 13 23:10:38.364343 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Feb 13 23:10:38.373921 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Feb 13 23:10:38.399616 lvm[1472]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 13 23:10:38.428682 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Feb 13 23:10:38.429690 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Feb 13 23:10:38.430287 systemd[1]: Reached target sysinit.target - System Initialization. Feb 13 23:10:38.430977 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Feb 13 23:10:38.431581 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Feb 13 23:10:38.432405 systemd[1]: Started logrotate.timer - Daily rotation of log files. Feb 13 23:10:38.433262 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Feb 13 23:10:38.433816 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Feb 13 23:10:38.434235 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Feb 13 23:10:38.434268 systemd[1]: Reached target paths.target - Path Units. Feb 13 23:10:38.434644 systemd[1]: Reached target timers.target - Timer Units. Feb 13 23:10:38.436099 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Feb 13 23:10:38.438097 systemd[1]: Starting docker.socket - Docker Socket for the API... Feb 13 23:10:38.444818 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Feb 13 23:10:38.449067 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Feb 13 23:10:38.466790 systemd[1]: Listening on docker.socket - Docker Socket for the API. Feb 13 23:10:38.467501 systemd[1]: Reached target sockets.target - Socket Units. Feb 13 23:10:38.467997 systemd[1]: Reached target basic.target - Basic System. Feb 13 23:10:38.468472 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Feb 13 23:10:38.468506 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Feb 13 23:10:38.471670 systemd[1]: Starting containerd.service - containerd container runtime... Feb 13 23:10:38.475303 lvm[1476]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 13 23:10:38.481348 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Feb 13 23:10:38.485768 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Feb 13 23:10:38.488673 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Feb 13 23:10:38.495726 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Feb 13 23:10:38.496165 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). Feb 13 23:10:38.499242 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Feb 13 23:10:38.502814 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Feb 13 23:10:38.507044 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Feb 13 23:10:38.516890 systemd[1]: Starting systemd-logind.service - User Login Management... Feb 13 23:10:38.518089 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Feb 13 23:10:38.518528 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Feb 13 23:10:38.525006 jq[1480]: false Feb 13 23:10:38.529971 systemd[1]: Starting update-engine.service - Update Engine... Feb 13 23:10:38.532970 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Feb 13 23:10:38.534730 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Feb 13 23:10:38.538899 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Feb 13 23:10:38.539073 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Feb 13 23:10:38.558214 dbus-daemon[1479]: [system] SELinux support is enabled Feb 13 23:10:38.560127 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Feb 13 23:10:38.560317 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Feb 13 23:10:38.560773 update_engine[1490]: I20250213 23:10:38.560695 1490 main.cc:92] Flatcar Update Engine starting Feb 13 23:10:38.561820 systemd[1]: Started dbus.service - D-Bus System Message Bus. Feb 13 23:10:38.562939 update_engine[1490]: I20250213 23:10:38.562897 1490 update_check_scheduler.cc:74] Next update check in 5m18s Feb 13 23:10:38.566682 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). Feb 13 23:10:38.566711 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Feb 13 23:10:38.568131 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). Feb 13 23:10:38.568151 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Feb 13 23:10:38.568726 systemd[1]: Started update-engine.service - Update Engine. Feb 13 23:10:38.574784 dbus-daemon[1479]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=244 pid=1433 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Feb 13 23:10:38.578190 systemd[1]: Started locksmithd.service - Cluster reboot manager. Feb 13 23:10:38.578936 systemd[1]: motdgen.service: Deactivated successfully. Feb 13 23:10:38.579105 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Feb 13 23:10:38.589136 jq[1495]: true Feb 13 23:10:38.591938 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Feb 13 23:10:38.592061 extend-filesystems[1481]: Found loop4 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found loop5 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found loop6 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found loop7 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found vda Feb 13 23:10:38.592061 extend-filesystems[1481]: Found vda1 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found vda2 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found vda3 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found usr Feb 13 23:10:38.592061 extend-filesystems[1481]: Found vda4 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found vda6 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found vda7 Feb 13 23:10:38.592061 extend-filesystems[1481]: Found vda9 Feb 13 23:10:38.592061 extend-filesystems[1481]: Checking size of /dev/vda9 Feb 13 23:10:38.612566 (ntainerd)[1511]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Feb 13 23:10:38.622766 extend-filesystems[1481]: Resized partition /dev/vda9 Feb 13 23:10:38.625468 extend-filesystems[1517]: resize2fs 1.47.1 (20-May-2024) Feb 13 23:10:38.629658 jq[1510]: true Feb 13 23:10:38.637651 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 15121403 blocks Feb 13 23:10:38.659641 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1438) Feb 13 23:10:38.721483 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Feb 13 23:10:38.782615 kernel: EXT4-fs (vda9): resized filesystem to 15121403 Feb 13 23:10:38.833713 systemd-logind[1487]: Watching system buttons on /dev/input/event2 (Power Button) Feb 13 23:10:38.834173 systemd-logind[1487]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Feb 13 23:10:38.834559 systemd-logind[1487]: New seat seat0. Feb 13 23:10:38.835974 extend-filesystems[1517]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Feb 13 23:10:38.835974 extend-filesystems[1517]: old_desc_blocks = 1, new_desc_blocks = 8 Feb 13 23:10:38.835974 extend-filesystems[1517]: The filesystem on /dev/vda9 is now 15121403 (4k) blocks long. Feb 13 23:10:38.845760 extend-filesystems[1481]: Resized filesystem in /dev/vda9 Feb 13 23:10:38.850858 bash[1539]: Updated "/home/core/.ssh/authorized_keys" Feb 13 23:10:38.837610 systemd[1]: extend-filesystems.service: Deactivated successfully. Feb 13 23:10:38.838261 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Feb 13 23:10:38.843777 systemd[1]: Started systemd-logind.service - User Login Management. Feb 13 23:10:38.846458 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Feb 13 23:10:38.861784 systemd[1]: Starting sshkeys.service... Feb 13 23:10:38.880659 dbus-daemon[1479]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 13 23:10:38.882658 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Feb 13 23:10:38.886785 dbus-daemon[1479]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.6' (uid=0 pid=1507 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Feb 13 23:10:38.890706 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Feb 13 23:10:38.904188 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Feb 13 23:10:38.910206 systemd[1]: Starting polkit.service - Authorization Manager... Feb 13 23:10:38.934072 containerd[1511]: time="2025-02-13T23:10:38.933909429Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Feb 13 23:10:38.938998 polkitd[1549]: Started polkitd version 121 Feb 13 23:10:38.946016 locksmithd[1505]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Feb 13 23:10:38.950073 polkitd[1549]: Loading rules from directory /etc/polkit-1/rules.d Feb 13 23:10:38.950130 polkitd[1549]: Loading rules from directory /usr/share/polkit-1/rules.d Feb 13 23:10:38.950653 polkitd[1549]: Finished loading, compiling and executing 2 rules Feb 13 23:10:38.951101 dbus-daemon[1479]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Feb 13 23:10:38.951257 systemd[1]: Started polkit.service - Authorization Manager. Feb 13 23:10:38.952163 polkitd[1549]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Feb 13 23:10:38.965208 systemd-hostnamed[1507]: Hostname set to (static) Feb 13 23:10:38.977544 containerd[1511]: time="2025-02-13T23:10:38.977306436Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979125434Z" 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.74-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979152447Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979168606Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979312737Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979326636Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979372720Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979383247Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979527115Z" 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 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979539837Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979551025Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980115 containerd[1511]: time="2025-02-13T23:10:38.979559307Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980404 containerd[1511]: time="2025-02-13T23:10:38.979634693Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980404 containerd[1511]: time="2025-02-13T23:10:38.979815219Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Feb 13 23:10:38.980404 containerd[1511]: time="2025-02-13T23:10:38.979929665Z" 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 Feb 13 23:10:38.980404 containerd[1511]: time="2025-02-13T23:10:38.979942779Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Feb 13 23:10:38.980404 containerd[1511]: time="2025-02-13T23:10:38.980014933Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Feb 13 23:10:38.980404 containerd[1511]: time="2025-02-13T23:10:38.980078802Z" level=info msg="metadata content store policy set" policy=shared Feb 13 23:10:38.983294 containerd[1511]: time="2025-02-13T23:10:38.983271000Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Feb 13 23:10:38.983414 containerd[1511]: time="2025-02-13T23:10:38.983401185Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Feb 13 23:10:38.983504 containerd[1511]: time="2025-02-13T23:10:38.983489874Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Feb 13 23:10:38.983564 containerd[1511]: time="2025-02-13T23:10:38.983554435Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Feb 13 23:10:38.983640 containerd[1511]: time="2025-02-13T23:10:38.983630518Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Feb 13 23:10:38.983798 containerd[1511]: time="2025-02-13T23:10:38.983785131Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Feb 13 23:10:38.984109 containerd[1511]: time="2025-02-13T23:10:38.984092524Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Feb 13 23:10:38.984285 containerd[1511]: time="2025-02-13T23:10:38.984271976Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Feb 13 23:10:38.984343 containerd[1511]: time="2025-02-13T23:10:38.984333521Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Feb 13 23:10:38.984388 containerd[1511]: time="2025-02-13T23:10:38.984379489Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Feb 13 23:10:38.984433 containerd[1511]: time="2025-02-13T23:10:38.984424597Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Feb 13 23:10:38.984480 containerd[1511]: time="2025-02-13T23:10:38.984472140Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Feb 13 23:10:38.984522 containerd[1511]: time="2025-02-13T23:10:38.984513952Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Feb 13 23:10:38.984570 containerd[1511]: time="2025-02-13T23:10:38.984561902Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Feb 13 23:10:38.984632 containerd[1511]: time="2025-02-13T23:10:38.984623396Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Feb 13 23:10:38.984675 containerd[1511]: time="2025-02-13T23:10:38.984667025Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984715800Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984730092Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984750032Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984762298Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984773269Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984791791Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984807211Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984828835Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984841225Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984851928Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984863096Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984876195Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984897550Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985034 containerd[1511]: time="2025-02-13T23:10:38.984910898Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985368 containerd[1511]: time="2025-02-13T23:10:38.984922792Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985368 containerd[1511]: time="2025-02-13T23:10:38.984935634Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Feb 13 23:10:38.985368 containerd[1511]: time="2025-02-13T23:10:38.984956758Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985368 containerd[1511]: time="2025-02-13T23:10:38.984975839Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.985368 containerd[1511]: time="2025-02-13T23:10:38.984987638Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Feb 13 23:10:38.985611 containerd[1511]: time="2025-02-13T23:10:38.985490938Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Feb 13 23:10:38.985611 containerd[1511]: time="2025-02-13T23:10:38.985511186Z" 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 Feb 13 23:10:38.985611 containerd[1511]: time="2025-02-13T23:10:38.985520488Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Feb 13 23:10:38.986501 containerd[1511]: time="2025-02-13T23:10:38.985689318Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Feb 13 23:10:38.986501 containerd[1511]: time="2025-02-13T23:10:38.985703211Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.986501 containerd[1511]: time="2025-02-13T23:10:38.985715653Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Feb 13 23:10:38.986501 containerd[1511]: time="2025-02-13T23:10:38.985724893Z" level=info msg="NRI interface is disabled by configuration." Feb 13 23:10:38.986501 containerd[1511]: time="2025-02-13T23:10:38.985734009Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Feb 13 23:10:38.986655 containerd[1511]: time="2025-02-13T23:10:38.986020294Z" 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}" Feb 13 23:10:38.986655 containerd[1511]: time="2025-02-13T23:10:38.986064984Z" level=info msg="Connect containerd service" Feb 13 23:10:38.986655 containerd[1511]: time="2025-02-13T23:10:38.986100208Z" level=info msg="using legacy CRI server" Feb 13 23:10:38.986655 containerd[1511]: time="2025-02-13T23:10:38.986108207Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Feb 13 23:10:38.986655 containerd[1511]: time="2025-02-13T23:10:38.986221148Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Feb 13 23:10:38.987268 containerd[1511]: time="2025-02-13T23:10:38.987247289Z" 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" Feb 13 23:10:38.987468 containerd[1511]: time="2025-02-13T23:10:38.987429233Z" level=info msg="Start subscribing containerd event" Feb 13 23:10:38.987531 containerd[1511]: time="2025-02-13T23:10:38.987522426Z" level=info msg="Start recovering state" Feb 13 23:10:38.987826 containerd[1511]: time="2025-02-13T23:10:38.987813380Z" level=info msg="Start event monitor" Feb 13 23:10:38.987922 containerd[1511]: time="2025-02-13T23:10:38.987911106Z" level=info msg="Start snapshots syncer" Feb 13 23:10:38.988016 containerd[1511]: time="2025-02-13T23:10:38.988005961Z" level=info msg="Start cni network conf syncer for default" Feb 13 23:10:38.988069 containerd[1511]: time="2025-02-13T23:10:38.988061568Z" level=info msg="Start streaming server" Feb 13 23:10:38.988509 containerd[1511]: time="2025-02-13T23:10:38.988494510Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Feb 13 23:10:38.988674 containerd[1511]: time="2025-02-13T23:10:38.988662522Z" level=info msg=serving... address=/run/containerd/containerd.sock Feb 13 23:10:38.988802 containerd[1511]: time="2025-02-13T23:10:38.988767136Z" level=info msg="containerd successfully booted in 0.060792s" Feb 13 23:10:38.988908 systemd[1]: Started containerd.service - containerd container runtime. Feb 13 23:10:39.003692 sshd_keygen[1494]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Feb 13 23:10:39.025871 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Feb 13 23:10:39.033968 systemd[1]: Starting issuegen.service - Generate /run/issue... Feb 13 23:10:39.035935 systemd[1]: Started sshd@0-10.244.92.250:22-147.75.109.163:35206.service - OpenSSH per-connection server daemon (147.75.109.163:35206). Feb 13 23:10:39.049144 systemd[1]: issuegen.service: Deactivated successfully. Feb 13 23:10:39.049586 systemd[1]: Finished issuegen.service - Generate /run/issue. Feb 13 23:10:39.061752 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Feb 13 23:10:39.074813 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Feb 13 23:10:39.087983 systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 13 23:10:39.089764 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Feb 13 23:10:39.090363 systemd[1]: Reached target getty.target - Login Prompts. Feb 13 23:10:39.689256 systemd-networkd[1433]: eth0: Gained IPv6LL Feb 13 23:10:39.692509 systemd-timesyncd[1402]: Network configuration changed, trying to establish connection. Feb 13 23:10:39.698973 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Feb 13 23:10:39.700549 systemd[1]: Reached target network-online.target - Network is Online. Feb 13 23:10:39.706869 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Feb 13 23:10:39.708919 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Feb 13 23:10:39.733256 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Feb 13 23:10:39.957540 sshd[1574]: Accepted publickey for core from 147.75.109.163 port 35206 ssh2: RSA SHA256:Yx7fWtREze/vjbfbVXgsOsi8+bAvCeghI7ZLGsIJS+I Feb 13 23:10:39.960073 sshd-session[1574]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 23:10:39.973465 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Feb 13 23:10:39.987199 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Feb 13 23:10:39.996984 systemd-logind[1487]: New session 1 of user core. Feb 13 23:10:40.005035 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Feb 13 23:10:40.015333 systemd[1]: Starting user@500.service - User Manager for UID 500... Feb 13 23:10:40.020673 (systemd)[1598]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Feb 13 23:10:40.031552 systemd-timesyncd[1402]: Network configuration changed, trying to establish connection. Feb 13 23:10:40.034743 systemd-networkd[1433]: eth0: Ignoring DHCPv6 address 2a02:1348:17d:173e:24:19ff:fef4:5cfa/128 (valid for 59min 59s, preferred for 59min 59s) which conflicts with 2a02:1348:17d:173e:24:19ff:fef4:5cfa/64 assigned by NDisc. Feb 13 23:10:40.034911 systemd-networkd[1433]: eth0: Hint: use IPv6Token= setting to change the address generated by NDisc or set UseAutonomousPrefix=no. Feb 13 23:10:40.131244 systemd[1598]: Queued start job for default target default.target. Feb 13 23:10:40.142035 systemd[1598]: Created slice app.slice - User Application Slice. Feb 13 23:10:40.142069 systemd[1598]: Reached target paths.target - Paths. Feb 13 23:10:40.142287 systemd[1598]: Reached target timers.target - Timers. Feb 13 23:10:40.145781 systemd[1598]: Starting dbus.socket - D-Bus User Message Bus Socket... Feb 13 23:10:40.160512 systemd[1598]: Listening on dbus.socket - D-Bus User Message Bus Socket. Feb 13 23:10:40.160649 systemd[1598]: Reached target sockets.target - Sockets. Feb 13 23:10:40.160666 systemd[1598]: Reached target basic.target - Basic System. Feb 13 23:10:40.160764 systemd[1]: Started user@500.service - User Manager for UID 500. Feb 13 23:10:40.161249 systemd[1598]: Reached target default.target - Main User Target. Feb 13 23:10:40.161290 systemd[1598]: Startup finished in 132ms. Feb 13 23:10:40.173937 systemd[1]: Started session-1.scope - Session 1 of User core. Feb 13 23:10:40.592410 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Feb 13 23:10:40.597139 (kubelet)[1614]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Feb 13 23:10:40.816968 systemd[1]: Started sshd@1-10.244.92.250:22-147.75.109.163:54222.service - OpenSSH per-connection server daemon (147.75.109.163:54222). Feb 13 23:10:41.140273 kubelet[1614]: E0213 23:10:41.140227 1614 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" Feb 13 23:10:41.143027 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Feb 13 23:10:41.143182 systemd[1]: kubelet.service: Failed with result 'exit-code'. Feb 13 23:10:41.143579 systemd[1]: kubelet.service: Consumed 1.091s CPU time. Feb 13 23:10:41.478629 systemd-timesyncd[1402]: Network configuration changed, trying to establish connection. Feb 13 23:10:41.715275 sshd[1620]: Accepted publickey for core from 147.75.109.163 port 54222 ssh2: RSA SHA256:Yx7fWtREze/vjbfbVXgsOsi8+bAvCeghI7ZLGsIJS+I Feb 13 23:10:41.718570 sshd-session[1620]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 23:10:41.729179 systemd-logind[1487]: New session 2 of user core. Feb 13 23:10:41.734867 systemd[1]: Started session-2.scope - Session 2 of User core. Feb 13 23:10:42.341208 sshd[1625]: Connection closed by 147.75.109.163 port 54222 Feb 13 23:10:42.340882 sshd-session[1620]: pam_unix(sshd:session): session closed for user core Feb 13 23:10:42.348537 systemd[1]: sshd@1-10.244.92.250:22-147.75.109.163:54222.service: Deactivated successfully. Feb 13 23:10:42.352487 systemd[1]: session-2.scope: Deactivated successfully. Feb 13 23:10:42.354051 systemd-logind[1487]: Session 2 logged out. Waiting for processes to exit. Feb 13 23:10:42.355915 systemd-logind[1487]: Removed session 2. Feb 13 23:10:42.504427 systemd[1]: Started sshd@2-10.244.92.250:22-147.75.109.163:54232.service - OpenSSH per-connection server daemon (147.75.109.163:54232). Feb 13 23:10:43.421193 sshd[1631]: Accepted publickey for core from 147.75.109.163 port 54232 ssh2: RSA SHA256:Yx7fWtREze/vjbfbVXgsOsi8+bAvCeghI7ZLGsIJS+I Feb 13 23:10:43.424329 sshd-session[1631]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 23:10:43.434824 systemd-logind[1487]: New session 3 of user core. Feb 13 23:10:43.451901 systemd[1]: Started session-3.scope - Session 3 of User core. Feb 13 23:10:44.049340 sshd[1633]: Connection closed by 147.75.109.163 port 54232 Feb 13 23:10:44.050748 sshd-session[1631]: pam_unix(sshd:session): session closed for user core Feb 13 23:10:44.059461 systemd[1]: sshd@2-10.244.92.250:22-147.75.109.163:54232.service: Deactivated successfully. Feb 13 23:10:44.064241 systemd[1]: session-3.scope: Deactivated successfully. Feb 13 23:10:44.066173 systemd-logind[1487]: Session 3 logged out. Waiting for processes to exit. Feb 13 23:10:44.067983 systemd-logind[1487]: Removed session 3. Feb 13 23:10:44.143343 login[1582]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Feb 13 23:10:44.147002 login[1581]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Feb 13 23:10:44.149502 systemd-logind[1487]: New session 4 of user core. Feb 13 23:10:44.153824 systemd[1]: Started session-4.scope - Session 4 of User core. Feb 13 23:10:44.157509 systemd-logind[1487]: New session 5 of user core. Feb 13 23:10:44.162805 systemd[1]: Started session-5.scope - Session 5 of User core. Feb 13 23:10:45.565424 coreos-metadata[1478]: Feb 13 23:10:45.565 WARN failed to locate config-drive, using the metadata service API instead Feb 13 23:10:45.589725 coreos-metadata[1478]: Feb 13 23:10:45.589 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1 Feb 13 23:10:45.595573 coreos-metadata[1478]: Feb 13 23:10:45.595 INFO Fetch failed with 404: resource not found Feb 13 23:10:45.595573 coreos-metadata[1478]: Feb 13 23:10:45.595 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Feb 13 23:10:45.596381 coreos-metadata[1478]: Feb 13 23:10:45.596 INFO Fetch successful Feb 13 23:10:45.596568 coreos-metadata[1478]: Feb 13 23:10:45.596 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 Feb 13 23:10:45.609473 coreos-metadata[1478]: Feb 13 23:10:45.609 INFO Fetch successful Feb 13 23:10:45.609473 coreos-metadata[1478]: Feb 13 23:10:45.609 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 Feb 13 23:10:45.624306 coreos-metadata[1478]: Feb 13 23:10:45.624 INFO Fetch successful Feb 13 23:10:45.624306 coreos-metadata[1478]: Feb 13 23:10:45.624 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 Feb 13 23:10:45.642117 coreos-metadata[1478]: Feb 13 23:10:45.641 INFO Fetch successful Feb 13 23:10:45.642117 coreos-metadata[1478]: Feb 13 23:10:45.642 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 Feb 13 23:10:45.662692 coreos-metadata[1478]: Feb 13 23:10:45.662 INFO Fetch successful Feb 13 23:10:45.714904 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Feb 13 23:10:45.716978 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Feb 13 23:10:46.007990 coreos-metadata[1548]: Feb 13 23:10:46.007 WARN failed to locate config-drive, using the metadata service API instead Feb 13 23:10:46.033179 coreos-metadata[1548]: Feb 13 23:10:46.033 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 Feb 13 23:10:46.063503 coreos-metadata[1548]: Feb 13 23:10:46.063 INFO Fetch successful Feb 13 23:10:46.064094 coreos-metadata[1548]: Feb 13 23:10:46.063 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 Feb 13 23:10:46.092636 coreos-metadata[1548]: Feb 13 23:10:46.092 INFO Fetch successful Feb 13 23:10:46.095362 unknown[1548]: wrote ssh authorized keys file for user: core Feb 13 23:10:46.134150 update-ssh-keys[1673]: Updated "/home/core/.ssh/authorized_keys" Feb 13 23:10:46.134838 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Feb 13 23:10:46.138473 systemd[1]: Finished sshkeys.service. Feb 13 23:10:46.140369 systemd[1]: Reached target multi-user.target - Multi-User System. Feb 13 23:10:46.140543 systemd[1]: Startup finished in 1.119s (kernel) + 12.925s (initrd) + 10.543s (userspace) = 24.588s. Feb 13 23:10:51.174810 systemd[1]: kubelet.service: Scheduled restart job, restart counter is at 1. Feb 13 23:10:51.189053 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Feb 13 23:10:51.314061 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Feb 13 23:10:51.327959 (kubelet)[1684]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS, KUBELET_KUBEADM_ARGS Feb 13 23:10:51.386785 kubelet[1684]: E0213 23:10:51.386720 1684 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" Feb 13 23:10:51.391397 systemd[1]: kubelet.service: Main process exited, code=exited, status=1/FAILURE Feb 13 23:10:51.391546 systemd[1]: kubelet.service: Failed with result 'exit-code'. Feb 13 23:10:54.217130 systemd[1]: Started sshd@3-10.244.92.250:22-147.75.109.163:49066.service - OpenSSH per-connection server daemon (147.75.109.163:49066). Feb 13 23:10:55.124545 sshd[1692]: Accepted publickey for core from 147.75.109.163 port 49066 ssh2: RSA SHA256:Yx7fWtREze/vjbfbVXgsOsi8+bAvCeghI7ZLGsIJS+I Feb 13 23:10:55.127850 sshd-session[1692]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 23:10:55.137080 systemd-logind[1487]: New session 6 of user core. Feb 13 23:10:55.148872 systemd[1]: Started session-6.scope - Session 6 of User core. Feb 13 23:10:55.751167 sshd[1694]: Connection closed by 147.75.109.163 port 49066 Feb 13 23:10:55.752723 sshd-session[1692]: pam_unix(sshd:session): session closed for user core Feb 13 23:10:55.760992 systemd[1]: sshd@3-10.244.92.250:22-147.75.109.163:49066.service: Deactivated successfully. Feb 13 23:10:55.763894 systemd[1]: session-6.scope: Deactivated successfully. Feb 13 23:10:55.765215 systemd-logind[1487]: Session 6 logged out. Waiting for processes to exit. Feb 13 23:10:55.766735 systemd-logind[1487]: Removed session 6. Feb 13 23:10:55.924201 systemd[1]: Started sshd@4-10.244.92.250:22-147.75.109.163:49080.service - OpenSSH per-connection server daemon (147.75.109.163:49080). Feb 13 23:10:56.823961 sshd[1699]: Accepted publickey for core from 147.75.109.163 port 49080 ssh2: RSA SHA256:Yx7fWtREze/vjbfbVXgsOsi8+bAvCeghI7ZLGsIJS+I Feb 13 23:10:56.826874 sshd-session[1699]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 23:10:56.834933 systemd-logind[1487]: New session 7 of user core. Feb 13 23:10:56.842829 systemd[1]: Started session-7.scope - Session 7 of User core. Feb 13 23:10:57.442791 sshd[1701]: Connection closed by 147.75.109.163 port 49080 Feb 13 23:10:57.444286 sshd-session[1699]: pam_unix(sshd:session): session closed for user core Feb 13 23:10:57.451977 systemd[1]: sshd@4-10.244.92.250:22-147.75.109.163:49080.service: Deactivated successfully. Feb 13 23:10:57.455882 systemd[1]: session-7.scope: Deactivated successfully. Feb 13 23:10:57.458504 systemd-logind[1487]: Session 7 logged out. Waiting for processes to exit. Feb 13 23:10:57.461066 systemd-logind[1487]: Removed session 7. Feb 13 23:10:57.601240 systemd[1]: Started sshd@5-10.244.92.250:22-147.75.109.163:49086.service - OpenSSH per-connection server daemon (147.75.109.163:49086). Feb 13 23:10:58.518495 sshd[1706]: Accepted publickey for core from 147.75.109.163 port 49086 ssh2: RSA SHA256:Yx7fWtREze/vjbfbVXgsOsi8+bAvCeghI7ZLGsIJS+I Feb 13 23:10:58.521774 sshd-session[1706]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 23:10:58.532158 systemd-logind[1487]: New session 8 of user core. Feb 13 23:10:58.551030 systemd[1]: Started session-8.scope - Session 8 of User core. Feb 13 23:10:59.145077 sshd[1708]: Connection closed by 147.75.109.163 port 49086 Feb 13 23:10:59.146690 sshd-session[1706]: pam_unix(sshd:session): session closed for user core Feb 13 23:10:59.152726 systemd[1]: sshd@5-10.244.92.250:22-147.75.109.163:49086.service: Deactivated successfully. Feb 13 23:10:59.156199 systemd[1]: session-8.scope: Deactivated successfully. Feb 13 23:10:59.160473 systemd-logind[1487]: Session 8 logged out. Waiting for processes to exit. Feb 13 23:10:59.162205 systemd-logind[1487]: Removed session 8. Feb 13 23:10:59.311261 systemd[1]: Started sshd@6-10.244.92.250:22-147.75.109.163:45074.service - OpenSSH per-connection server daemon (147.75.109.163:45074). Feb 13 23:11:00.209784 sshd[1713]: Accepted publickey for core from 147.75.109.163 port 45074 ssh2: RSA SHA256:Yx7fWtREze/vjbfbVXgsOsi8+bAvCeghI7ZLGsIJS+I Feb 13 23:11:00.213114 sshd-session[1713]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Feb 13 23:11:00.223404 systemd-logind[1487]: New session 9 of user core. Feb 13 23:11:00.230800 systemd[1]: Started session-9.scope - Session 9 of User core. Feb 13 23:11:00.708739 sudo[1716]: core : PWD=/home/core ; USER=root ; COMMAND=/home/core/install.sh Feb 13 23:11:00.709624 sudo[1716]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Feb 13 23:11:01.364159 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Feb 13 23:11:01.371825 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Feb 13 23:11:01.395309 systemd[1]: Reloading requested from client PID 1750 ('systemctl') (unit session-9.scope)... Feb 13 23:11:01.395332 systemd[1]: Reloading... Feb 13 23:11:01.505661 zram_generator::config[1789]: No configuration found. Feb 13 23:11:01.638208 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. Feb 13 23:11:01.712279 systemd[1]: Reloading finished in 316 ms. Feb 13 23:11:01.762436 systemd[1]: kubelet.service: Control process exited, code=killed, status=15/TERM Feb 13 23:11:01.762514 systemd[1]: kubelet.service: Failed with result 'signal'. Feb 13 23:11:01.762805 systemd[1]: Stopped kubelet.service - kubelet: The Kubernetes Node Agent. Feb 13 23:11:01.768814 systemd[1]: Starting kubelet.service - kubelet: The Kubernetes Node Agent... Feb 13 23:11:01.906335 systemd[1]: Started kubelet.service - kubelet: The Kubernetes Node Agent. Feb 13 23:11:01.923546 (kubelet)[1856]: kubelet.service: Referenced but unset environment variable evaluates to an empty string: KUBELET_EXTRA_ARGS Feb 13 23:11:01.981911 kubelet[1856]: Flag --container-runtime-endpoint has been deprecated, This parameter should be set via the config file specified by the Kubelet's --config flag. See https://kubernetes.io/docs/tasks/administer-cluster/kubelet-config-file/ for more information. Feb 13 23:11:01.981911 kubelet[1856]: Flag --pod-infra-container-image has been deprecated, will be removed in 1.35. Image garbage collector will get sandbox image information from CRI. Feb 13 23:11:01.981911 kubelet[1856]: 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. Feb 13 23:11:01.981911 kubelet[1856]: I0213 23:11:01.980882 1856 server.go:215] "--pod-infra-container-image will not be pruned by the image garbage collector in kubelet and should also be set in the remote runtime" Feb 13 23:11:02.373622 kubelet[1856]: I0213 23:11:02.372951 1856 server.go:520] "Kubelet version" kubeletVersion="v1.32.0" Feb 13 23:11:02.373622 kubelet[1856]: I0213 23:11:02.373034 1856 server.go:522] "Golang settings" GOGC="" GOMAXPROCS="" GOTRACEBACK="" Feb 13 23:11:02.374089 kubelet[1856]: I0213 23:11:02.373713 1856 server.go:954] "Client rotation is on, will bootstrap in background" Feb 13 23:11:02.408447 kubelet[1856]: I0213 23:11:02.407956 1856 dynamic_cafile_content.go:161] "Starting controller" name="client-ca-bundle::/etc/kubernetes/pki/ca.crt" Feb 13 23:11:02.425830 kubelet[1856]: E0213 23:11:02.425776 1856 log.go:32] "RuntimeConfig from runtime service failed" err="rpc error: code = Unimplemented desc = unknown method RuntimeConfig for service runtime.v1.RuntimeService" Feb 13 23:11:02.425830 kubelet[1856]: I0213 23:11:02.425834 1856 server.go:1421] "CRI implementation should be updated to support RuntimeConfig when KubeletCgroupDriverFromCRI feature gate has been enabled. Falling back to using cgroupDriver from kubelet config." Feb 13 23:11:02.432492 kubelet[1856]: I0213 23:11:02.432457 1856 server.go:772] "--cgroups-per-qos enabled, but --cgroup-root was not specified. defaulting to /" Feb 13 23:11:02.434576 kubelet[1856]: I0213 23:11:02.433971 1856 container_manager_linux.go:268] "Container manager verified user specified cgroup-root exists" cgroupRoot=[] Feb 13 23:11:02.434576 kubelet[1856]: I0213 23:11:02.434052 1856 container_manager_linux.go:273] "Creating Container Manager object based on Node Config" nodeConfig={"NodeName":"10.244.92.250","RuntimeCgroupsName":"","SystemCgroupsName":"","KubeletCgroupsName":"","KubeletOOMScoreAdj":-999,"ContainerRuntime":"","CgroupsPerQOS":true,"CgroupRoot":"/","CgroupDriver":"systemd","KubeletRootDir":"/var/lib/kubelet","ProtectKernelDefaults":false,"KubeReservedCgroupName":"","SystemReservedCgroupName":"","ReservedSystemCPUs":{},"EnforceNodeAllocatable":{"pods":{}},"KubeReserved":null,"SystemReserved":null,"HardEvictionThresholds":[{"Signal":"memory.available","Operator":"LessThan","Value":{"Quantity":"100Mi","Percentage":0},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.1},"GracePeriod":0,"MinReclaim":null},{"Signal":"nodefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.available","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.15},"GracePeriod":0,"MinReclaim":null},{"Signal":"imagefs.inodesFree","Operator":"LessThan","Value":{"Quantity":null,"Percentage":0.05},"GracePeriod":0,"MinReclaim":null}],"QOSReserved":{},"CPUManagerPolicy":"none","CPUManagerPolicyOptions":null,"TopologyManagerScope":"container","CPUManagerReconcilePeriod":10000000000,"ExperimentalMemoryManagerPolicy":"None","ExperimentalMemoryManagerReservedMemory":null,"PodPidsLimit":-1,"EnforceCPULimits":true,"CPUCFSQuotaPeriod":100000000,"TopologyManagerPolicy":"none","TopologyManagerPolicyOptions":null,"CgroupVersion":2} Feb 13 23:11:02.434576 kubelet[1856]: I0213 23:11:02.434503 1856 topology_manager.go:138] "Creating topology manager with none policy" Feb 13 23:11:02.434576 kubelet[1856]: I0213 23:11:02.434527 1856 container_manager_linux.go:304] "Creating device plugin manager" Feb 13 23:11:02.434927 kubelet[1856]: I0213 23:11:02.434907 1856 state_mem.go:36] "Initialized new in-memory state store" Feb 13 23:11:02.438692 kubelet[1856]: I0213 23:11:02.438667 1856 kubelet.go:446] "Attempting to sync node with API server" Feb 13 23:11:02.438692 kubelet[1856]: I0213 23:11:02.438696 1856 kubelet.go:341] "Adding static pod path" path="/etc/kubernetes/manifests" Feb 13 23:11:02.438981 kubelet[1856]: I0213 23:11:02.438724 1856 kubelet.go:352] "Adding apiserver pod source" Feb 13 23:11:02.438981 kubelet[1856]: I0213 23:11:02.438750 1856 apiserver.go:42] "Waiting for node sync before watching apiserver pods" Feb 13 23:11:02.440262 kubelet[1856]: E0213 23:11:02.440226 1856 file.go:98] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:02.440455 kubelet[1856]: E0213 23:11:02.440442 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:02.442213 kubelet[1856]: I0213 23:11:02.442154 1856 kuberuntime_manager.go:269] "Container runtime initialized" containerRuntime="containerd" version="v1.7.23" apiVersion="v1" Feb 13 23:11:02.442615 kubelet[1856]: I0213 23:11:02.442582 1856 kubelet.go:890] "Not starting ClusterTrustBundle informer because we are in static kubelet mode" Feb 13 23:11:02.443260 kubelet[1856]: W0213 23:11:02.443240 1856 probe.go:272] Flexvolume plugin directory at /opt/libexec/kubernetes/kubelet-plugins/volume/exec/ does not exist. Recreating. Feb 13 23:11:02.445521 kubelet[1856]: I0213 23:11:02.445494 1856 watchdog_linux.go:99] "Systemd watchdog is not enabled" Feb 13 23:11:02.445521 kubelet[1856]: I0213 23:11:02.445533 1856 server.go:1287] "Started kubelet" Feb 13 23:11:02.448619 kubelet[1856]: I0213 23:11:02.447569 1856 server.go:169] "Starting to listen" address="0.0.0.0" port=10250 Feb 13 23:11:02.448852 kubelet[1856]: I0213 23:11:02.448835 1856 server.go:490] "Adding debug handlers to kubelet server" Feb 13 23:11:02.450827 kubelet[1856]: I0213 23:11:02.450778 1856 ratelimit.go:55] "Setting rate limiting for endpoint" service="podresources" qps=100 burstTokens=10 Feb 13 23:11:02.451051 kubelet[1856]: I0213 23:11:02.451032 1856 server.go:243] "Starting to serve the podresources API" endpoint="unix:/var/lib/kubelet/pod-resources/kubelet.sock" Feb 13 23:11:02.451545 kubelet[1856]: I0213 23:11:02.451530 1856 fs_resource_analyzer.go:67] "Starting FS ResourceAnalyzer" Feb 13 23:11:02.457131 kubelet[1856]: I0213 23:11:02.457114 1856 dynamic_serving_content.go:135] "Starting controller" name="kubelet-server-cert-files::/var/lib/kubelet/pki/kubelet.crt::/var/lib/kubelet/pki/kubelet.key" Feb 13 23:11:02.460753 kubelet[1856]: I0213 23:11:02.460739 1856 volume_manager.go:297] "Starting Kubelet Volume Manager" Feb 13 23:11:02.461510 kubelet[1856]: E0213 23:11:02.461484 1856 kubelet.go:1561] "Image garbage collection failed once. Stats initialization may not have completed yet" err="invalid capacity 0 on image filesystem" Feb 13 23:11:02.461754 kubelet[1856]: I0213 23:11:02.461710 1856 desired_state_of_world_populator.go:149] "Desired state populator starts to run" Feb 13 23:11:02.461922 kubelet[1856]: I0213 23:11:02.461877 1856 factory.go:219] Registration of the crio container factory failed: Get "http://%2Fvar%2Frun%2Fcrio%2Fcrio.sock/info": dial unix /var/run/crio/crio.sock: connect: no such file or directory Feb 13 23:11:02.462403 kubelet[1856]: E0213 23:11:02.461018 1856 kubelet_node_status.go:467] "Error getting the current node from lister" err="node \"10.244.92.250\" not found" Feb 13 23:11:02.462403 kubelet[1856]: I0213 23:11:02.462318 1856 reconciler.go:26] "Reconciler: start to sync state" Feb 13 23:11:02.464121 kubelet[1856]: I0213 23:11:02.464108 1856 factory.go:221] Registration of the containerd container factory successfully Feb 13 23:11:02.464212 kubelet[1856]: I0213 23:11:02.464204 1856 factory.go:221] Registration of the systemd container factory successfully Feb 13 23:11:02.468610 kubelet[1856]: E0213 23:11:02.467317 1856 nodelease.go:49] "Failed to get node when trying to set owner ref to the node lease" err="nodes \"10.244.92.250\" not found" node="10.244.92.250" Feb 13 23:11:02.494874 kubelet[1856]: I0213 23:11:02.494852 1856 cpu_manager.go:221] "Starting CPU manager" policy="none" Feb 13 23:11:02.495034 kubelet[1856]: I0213 23:11:02.495023 1856 cpu_manager.go:222] "Reconciling" reconcilePeriod="10s" Feb 13 23:11:02.495107 kubelet[1856]: I0213 23:11:02.495099 1856 state_mem.go:36] "Initialized new in-memory state store" Feb 13 23:11:02.496360 kubelet[1856]: I0213 23:11:02.496344 1856 policy_none.go:49] "None policy: Start" Feb 13 23:11:02.496471 kubelet[1856]: I0213 23:11:02.496463 1856 memory_manager.go:186] "Starting memorymanager" policy="None" Feb 13 23:11:02.496550 kubelet[1856]: I0213 23:11:02.496542 1856 state_mem.go:35] "Initializing new in-memory state store" Feb 13 23:11:02.505967 systemd[1]: Created slice kubepods.slice - libcontainer container kubepods.slice. Feb 13 23:11:02.516666 systemd[1]: Created slice kubepods-burstable.slice - libcontainer container kubepods-burstable.slice. Feb 13 23:11:02.520942 systemd[1]: Created slice kubepods-besteffort.slice - libcontainer container kubepods-besteffort.slice. Feb 13 23:11:02.528396 kubelet[1856]: I0213 23:11:02.527424 1856 manager.go:519] "Failed to read data from checkpoint" checkpoint="kubelet_internal_checkpoint" err="checkpoint is not found" Feb 13 23:11:02.528396 kubelet[1856]: I0213 23:11:02.527613 1856 eviction_manager.go:189] "Eviction manager: starting control loop" Feb 13 23:11:02.528396 kubelet[1856]: I0213 23:11:02.527639 1856 container_log_manager.go:189] "Initializing container log rotate workers" workers=1 monitorPeriod="10s" Feb 13 23:11:02.528396 kubelet[1856]: I0213 23:11:02.528281 1856 plugin_manager.go:118] "Starting Kubelet Plugin Manager" Feb 13 23:11:02.533924 kubelet[1856]: E0213 23:11:02.533909 1856 eviction_manager.go:267] "eviction manager: failed to check if we have separate container filesystem. Ignoring." err="no imagefs label for configured runtime" Feb 13 23:11:02.534162 kubelet[1856]: E0213 23:11:02.534150 1856 eviction_manager.go:292] "Eviction manager: failed to get summary stats" err="failed to get node info: node \"10.244.92.250\" not found" Feb 13 23:11:02.538381 kubelet[1856]: I0213 23:11:02.538355 1856 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv4" Feb 13 23:11:02.539902 kubelet[1856]: I0213 23:11:02.539885 1856 kubelet_network_linux.go:50] "Initialized iptables rules." protocol="IPv6" Feb 13 23:11:02.540021 kubelet[1856]: I0213 23:11:02.540012 1856 status_manager.go:227] "Starting to sync pod status with apiserver" Feb 13 23:11:02.540716 kubelet[1856]: I0213 23:11:02.540698 1856 watchdog_linux.go:127] "Systemd watchdog is not enabled or the interval is invalid, so health checking will not be started." Feb 13 23:11:02.541054 kubelet[1856]: I0213 23:11:02.540789 1856 kubelet.go:2388] "Starting kubelet main sync loop" Feb 13 23:11:02.541054 kubelet[1856]: E0213 23:11:02.540852 1856 kubelet.go:2412] "Skipping pod synchronization" err="PLEG is not healthy: pleg has yet to be successful" Feb 13 23:11:02.633336 kubelet[1856]: I0213 23:11:02.629899 1856 kubelet_node_status.go:76] "Attempting to register node" node="10.244.92.250" Feb 13 23:11:02.638507 kubelet[1856]: I0213 23:11:02.638464 1856 kubelet_node_status.go:79] "Successfully registered node" node="10.244.92.250" Feb 13 23:11:02.747243 kubelet[1856]: I0213 23:11:02.747198 1856 kuberuntime_manager.go:1702] "Updating runtime config through cri with podcidr" CIDR="192.168.1.0/24" Feb 13 23:11:02.748061 containerd[1511]: time="2025-02-13T23:11:02.747932953Z" level=info msg="No cni config template is specified, wait for other system components to drop the config." Feb 13 23:11:02.749215 kubelet[1856]: I0213 23:11:02.748938 1856 kubelet_network.go:61] "Updating Pod CIDR" originalPodCIDR="" newPodCIDR="192.168.1.0/24" Feb 13 23:11:02.968068 sudo[1716]: pam_unix(sudo:session): session closed for user root Feb 13 23:11:03.114709 sshd[1715]: Connection closed by 147.75.109.163 port 45074 Feb 13 23:11:03.116185 sshd-session[1713]: pam_unix(sshd:session): session closed for user core Feb 13 23:11:03.124025 systemd[1]: sshd@6-10.244.92.250:22-147.75.109.163:45074.service: Deactivated successfully. Feb 13 23:11:03.127788 systemd[1]: session-9.scope: Deactivated successfully. Feb 13 23:11:03.130825 systemd-logind[1487]: Session 9 logged out. Waiting for processes to exit. Feb 13 23:11:03.134203 systemd-logind[1487]: Removed session 9. Feb 13 23:11:03.377578 kubelet[1856]: I0213 23:11:03.376981 1856 transport.go:147] "Certificate rotation detected, shutting down client connections to start using new credentials" Feb 13 23:11:03.377578 kubelet[1856]: W0213 23:11:03.377394 1856 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.Service ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Feb 13 23:11:03.377578 kubelet[1856]: W0213 23:11:03.377428 1856 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.RuntimeClass ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Feb 13 23:11:03.377578 kubelet[1856]: W0213 23:11:03.377454 1856 reflector.go:492] k8s.io/client-go/informers/factory.go:160: watch of *v1.CSIDriver ended with: very short watch: k8s.io/client-go/informers/factory.go:160: Unexpected watch close - watch lasted less than a second and no items received Feb 13 23:11:03.441406 kubelet[1856]: E0213 23:11:03.441097 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:03.441406 kubelet[1856]: I0213 23:11:03.441234 1856 apiserver.go:52] "Watching apiserver" Feb 13 23:11:03.461749 systemd[1]: Created slice kubepods-besteffort-podb6e1e74b_215f_4e84_b336_f978270c7083.slice - libcontainer container kubepods-besteffort-podb6e1e74b_215f_4e84_b336_f978270c7083.slice. Feb 13 23:11:03.462148 kubelet[1856]: I0213 23:11:03.462113 1856 desired_state_of_world_populator.go:157] "Finished populating initial desired state of world" Feb 13 23:11:03.467013 kubelet[1856]: I0213 23:11:03.466991 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni-plugin\" (UniqueName: \"kubernetes.io/host-path/836af91f-2d8c-4dee-b30b-a227154edffa-cni-plugin\") pod \"kube-flannel-ds-6lszk\" (UID: \"836af91f-2d8c-4dee-b30b-a227154edffa\") " pod="kube-flannel/kube-flannel-ds-6lszk" Feb 13 23:11:03.467110 kubelet[1856]: I0213 23:11:03.467020 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"cni\" (UniqueName: \"kubernetes.io/host-path/836af91f-2d8c-4dee-b30b-a227154edffa-cni\") pod \"kube-flannel-ds-6lszk\" (UID: \"836af91f-2d8c-4dee-b30b-a227154edffa\") " pod="kube-flannel/kube-flannel-ds-6lszk" Feb 13 23:11:03.467110 kubelet[1856]: I0213 23:11:03.467051 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"flannel-cfg\" (UniqueName: \"kubernetes.io/configmap/836af91f-2d8c-4dee-b30b-a227154edffa-flannel-cfg\") pod \"kube-flannel-ds-6lszk\" (UID: \"836af91f-2d8c-4dee-b30b-a227154edffa\") " pod="kube-flannel/kube-flannel-ds-6lszk" Feb 13 23:11:03.467110 kubelet[1856]: I0213 23:11:03.467067 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/836af91f-2d8c-4dee-b30b-a227154edffa-xtables-lock\") pod \"kube-flannel-ds-6lszk\" (UID: \"836af91f-2d8c-4dee-b30b-a227154edffa\") " pod="kube-flannel/kube-flannel-ds-6lszk" Feb 13 23:11:03.467110 kubelet[1856]: I0213 23:11:03.467084 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-ht4r9\" (UniqueName: \"kubernetes.io/projected/836af91f-2d8c-4dee-b30b-a227154edffa-kube-api-access-ht4r9\") pod \"kube-flannel-ds-6lszk\" (UID: \"836af91f-2d8c-4dee-b30b-a227154edffa\") " pod="kube-flannel/kube-flannel-ds-6lszk" Feb 13 23:11:03.467110 kubelet[1856]: I0213 23:11:03.467100 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-wwx57\" (UniqueName: \"kubernetes.io/projected/b6e1e74b-215f-4e84-b336-f978270c7083-kube-api-access-wwx57\") pod \"kube-proxy-swc4c\" (UID: \"b6e1e74b-215f-4e84-b336-f978270c7083\") " pod="kube-system/kube-proxy-swc4c" Feb 13 23:11:03.467291 kubelet[1856]: I0213 23:11:03.467115 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"run\" (UniqueName: \"kubernetes.io/host-path/836af91f-2d8c-4dee-b30b-a227154edffa-run\") pod \"kube-flannel-ds-6lszk\" (UID: \"836af91f-2d8c-4dee-b30b-a227154edffa\") " pod="kube-flannel/kube-flannel-ds-6lszk" Feb 13 23:11:03.467291 kubelet[1856]: I0213 23:11:03.467130 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-proxy\" (UniqueName: \"kubernetes.io/configmap/b6e1e74b-215f-4e84-b336-f978270c7083-kube-proxy\") pod \"kube-proxy-swc4c\" (UID: \"b6e1e74b-215f-4e84-b336-f978270c7083\") " pod="kube-system/kube-proxy-swc4c" Feb 13 23:11:03.467291 kubelet[1856]: I0213 23:11:03.467154 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"xtables-lock\" (UniqueName: \"kubernetes.io/host-path/b6e1e74b-215f-4e84-b336-f978270c7083-xtables-lock\") pod \"kube-proxy-swc4c\" (UID: \"b6e1e74b-215f-4e84-b336-f978270c7083\") " pod="kube-system/kube-proxy-swc4c" Feb 13 23:11:03.467291 kubelet[1856]: I0213 23:11:03.467171 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"lib-modules\" (UniqueName: \"kubernetes.io/host-path/b6e1e74b-215f-4e84-b336-f978270c7083-lib-modules\") pod \"kube-proxy-swc4c\" (UID: \"b6e1e74b-215f-4e84-b336-f978270c7083\") " pod="kube-system/kube-proxy-swc4c" Feb 13 23:11:03.471510 systemd[1]: Created slice kubepods-burstable-pod836af91f_2d8c_4dee_b30b_a227154edffa.slice - libcontainer container kubepods-burstable-pod836af91f_2d8c_4dee_b30b_a227154edffa.slice. Feb 13 23:11:03.771094 containerd[1511]: time="2025-02-13T23:11:03.771007482Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-swc4c,Uid:b6e1e74b-215f-4e84-b336-f978270c7083,Namespace:kube-system,Attempt:0,}" Feb 13 23:11:03.782095 containerd[1511]: time="2025-02-13T23:11:03.781328646Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-6lszk,Uid:836af91f-2d8c-4dee-b30b-a227154edffa,Namespace:kube-flannel,Attempt:0,}" Feb 13 23:11:04.442389 kubelet[1856]: E0213 23:11:04.442232 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:04.522491 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2753048580.mount: Deactivated successfully. Feb 13 23:11:04.526864 containerd[1511]: time="2025-02-13T23:11:04.526800579Z" 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\"}" Feb 13 23:11:04.528242 containerd[1511]: time="2025-02-13T23:11:04.528191505Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=312064" Feb 13 23:11:04.528370 containerd[1511]: time="2025-02-13T23:11:04.528261588Z" level=info msg="stop pulling image registry.k8s.io/pause:3.8: active requests=0, bytes read=0" Feb 13 23:11:04.528854 containerd[1511]: time="2025-02-13T23:11:04.528811884Z" 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\"}" Feb 13 23:11:04.531364 containerd[1511]: time="2025-02-13T23:11:04.531331476Z" level=info msg="ImageCreate event name:\"sha256:4873874c08efc72e9729683a83ffbb7502ee729e9a5ac097723806ea7fa13517\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"} labels:{key:\"io.cri-containerd.pinned\" value:\"pinned\"}" Feb 13 23:11:04.533543 containerd[1511]: time="2025-02-13T23:11:04.532827222Z" 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 761.368334ms" Feb 13 23:11:04.534582 containerd[1511]: time="2025-02-13T23:11:04.534542991Z" 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\"}" Feb 13 23:11:04.535179 containerd[1511]: time="2025-02-13T23:11:04.534905846Z" 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 753.397057ms" Feb 13 23:11:04.661199 containerd[1511]: time="2025-02-13T23:11:04.661069739Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 23:11:04.661199 containerd[1511]: time="2025-02-13T23:11:04.661154598Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 23:11:04.661632 containerd[1511]: time="2025-02-13T23:11:04.661570840Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:04.662917 containerd[1511]: time="2025-02-13T23:11:04.662818398Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:04.672883 containerd[1511]: time="2025-02-13T23:11:04.672626967Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 23:11:04.672883 containerd[1511]: time="2025-02-13T23:11:04.672713414Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 23:11:04.672883 containerd[1511]: time="2025-02-13T23:11:04.672739337Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:04.672883 containerd[1511]: time="2025-02-13T23:11:04.672819549Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:04.742752 systemd[1]: Started cri-containerd-61e05a82f4f39bf96c85d63b8e7d31d7e3262ce7dbcd937167d24a1fe7428132.scope - libcontainer container 61e05a82f4f39bf96c85d63b8e7d31d7e3262ce7dbcd937167d24a1fe7428132. Feb 13 23:11:04.745717 systemd[1]: Started cri-containerd-81d01a299a5787d727f65e2f3d5a67d778f992565b55a0e66df4869afd164713.scope - libcontainer container 81d01a299a5787d727f65e2f3d5a67d778f992565b55a0e66df4869afd164713. Feb 13 23:11:04.781876 containerd[1511]: time="2025-02-13T23:11:04.781843327Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-proxy-swc4c,Uid:b6e1e74b-215f-4e84-b336-f978270c7083,Namespace:kube-system,Attempt:0,} returns sandbox id \"61e05a82f4f39bf96c85d63b8e7d31d7e3262ce7dbcd937167d24a1fe7428132\"" Feb 13 23:11:04.784982 containerd[1511]: time="2025-02-13T23:11:04.784915522Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.2\"" Feb 13 23:11:04.801758 containerd[1511]: time="2025-02-13T23:11:04.801707852Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:kube-flannel-ds-6lszk,Uid:836af91f-2d8c-4dee-b30b-a227154edffa,Namespace:kube-flannel,Attempt:0,} returns sandbox id \"81d01a299a5787d727f65e2f3d5a67d778f992565b55a0e66df4869afd164713\"" Feb 13 23:11:05.443442 kubelet[1856]: E0213 23:11:05.443344 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:06.134938 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3979583856.mount: Deactivated successfully. Feb 13 23:11:06.444133 kubelet[1856]: E0213 23:11:06.443892 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:06.534311 containerd[1511]: time="2025-02-13T23:11:06.534267810Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy:v1.32.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:06.535685 containerd[1511]: time="2025-02-13T23:11:06.535626209Z" level=info msg="stop pulling image registry.k8s.io/kube-proxy:v1.32.2: active requests=0, bytes read=30908847" Feb 13 23:11:06.536620 containerd[1511]: time="2025-02-13T23:11:06.536231550Z" level=info msg="ImageCreate event name:\"sha256:f1332858868e1c6a905123b21e2e322ab45a5b99a3532e68ff49a87c2266ebc5\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:06.537828 containerd[1511]: time="2025-02-13T23:11:06.537783126Z" level=info msg="ImageCreate event name:\"registry.k8s.io/kube-proxy@sha256:83c025f0faa6799fab6645102a98138e39a9a7db2be3bc792c79d72659b1805d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:06.538683 containerd[1511]: time="2025-02-13T23:11:06.538514328Z" level=info msg="Pulled image \"registry.k8s.io/kube-proxy:v1.32.2\" with image id \"sha256:f1332858868e1c6a905123b21e2e322ab45a5b99a3532e68ff49a87c2266ebc5\", repo tag \"registry.k8s.io/kube-proxy:v1.32.2\", repo digest \"registry.k8s.io/kube-proxy@sha256:83c025f0faa6799fab6645102a98138e39a9a7db2be3bc792c79d72659b1805d\", size \"30907858\" in 1.753566875s" Feb 13 23:11:06.538683 containerd[1511]: time="2025-02-13T23:11:06.538543394Z" level=info msg="PullImage \"registry.k8s.io/kube-proxy:v1.32.2\" returns image reference \"sha256:f1332858868e1c6a905123b21e2e322ab45a5b99a3532e68ff49a87c2266ebc5\"" Feb 13 23:11:06.540261 containerd[1511]: time="2025-02-13T23:11:06.540160672Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\"" Feb 13 23:11:06.541586 containerd[1511]: time="2025-02-13T23:11:06.541453126Z" level=info msg="CreateContainer within sandbox \"61e05a82f4f39bf96c85d63b8e7d31d7e3262ce7dbcd937167d24a1fe7428132\" for container &ContainerMetadata{Name:kube-proxy,Attempt:0,}" Feb 13 23:11:06.563976 containerd[1511]: time="2025-02-13T23:11:06.563899574Z" level=info msg="CreateContainer within sandbox \"61e05a82f4f39bf96c85d63b8e7d31d7e3262ce7dbcd937167d24a1fe7428132\" for &ContainerMetadata{Name:kube-proxy,Attempt:0,} returns container id \"860985ee23cc4aad8521f1bdb42ca2a4c4a72294158adb764fcba5b9911245ec\"" Feb 13 23:11:06.564969 containerd[1511]: time="2025-02-13T23:11:06.564714360Z" level=info msg="StartContainer for \"860985ee23cc4aad8521f1bdb42ca2a4c4a72294158adb764fcba5b9911245ec\"" Feb 13 23:11:06.605780 systemd[1]: Started cri-containerd-860985ee23cc4aad8521f1bdb42ca2a4c4a72294158adb764fcba5b9911245ec.scope - libcontainer container 860985ee23cc4aad8521f1bdb42ca2a4c4a72294158adb764fcba5b9911245ec. Feb 13 23:11:06.641396 containerd[1511]: time="2025-02-13T23:11:06.641321725Z" level=info msg="StartContainer for \"860985ee23cc4aad8521f1bdb42ca2a4c4a72294158adb764fcba5b9911245ec\" returns successfully" Feb 13 23:11:07.445068 kubelet[1856]: E0213 23:11:07.444930 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:07.579636 kubelet[1856]: I0213 23:11:07.579432 1856 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-system/kube-proxy-swc4c" podStartSLOduration=2.824173554 podStartE2EDuration="4.579376044s" podCreationTimestamp="2025-02-13 23:11:03 +0000 UTC" firstStartedPulling="2025-02-13 23:11:04.784304114 +0000 UTC m=+2.850662066" lastFinishedPulling="2025-02-13 23:11:06.539506609 +0000 UTC m=+4.605864556" observedRunningTime="2025-02-13 23:11:07.579230961 +0000 UTC m=+5.645588929" watchObservedRunningTime="2025-02-13 23:11:07.579376044 +0000 UTC m=+5.645734041" Feb 13 23:11:08.446241 kubelet[1856]: E0213 23:11:08.446143 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:08.998226 systemd[1]: Started sshd@7-10.244.92.250:22-27.254.163.61:40048.service - OpenSSH per-connection server daemon (27.254.163.61:40048). Feb 13 23:11:09.229156 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount2005315639.mount: Deactivated successfully. Feb 13 23:11:09.277299 containerd[1511]: time="2025-02-13T23:11:09.277177827Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin:v1.1.2\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:09.277896 containerd[1511]: time="2025-02-13T23:11:09.277856247Z" level=info msg="stop pulling image docker.io/flannel/flannel-cni-plugin:v1.1.2: active requests=0, bytes read=3852937" Feb 13 23:11:09.278753 containerd[1511]: time="2025-02-13T23:11:09.278723079Z" level=info msg="ImageCreate event name:\"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:09.280815 containerd[1511]: time="2025-02-13T23:11:09.280761507Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:09.281709 containerd[1511]: time="2025-02-13T23:11:09.281572651Z" level=info msg="Pulled image \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" with image id \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\", repo tag \"docker.io/flannel/flannel-cni-plugin:v1.1.2\", repo digest \"docker.io/flannel/flannel-cni-plugin@sha256:bf4b62b131666d040f35a327d906ee5a3418280b68a88d9b9c7e828057210443\", size \"3842055\" in 2.741385168s" Feb 13 23:11:09.281709 containerd[1511]: time="2025-02-13T23:11:09.281617782Z" level=info msg="PullImage \"docker.io/flannel/flannel-cni-plugin:v1.1.2\" returns image reference \"sha256:7a2dcab94698c786e7e41360faf8cd0ea2b29952469be75becc34c61902240e0\"" Feb 13 23:11:09.283639 containerd[1511]: time="2025-02-13T23:11:09.283514142Z" level=info msg="CreateContainer within sandbox \"81d01a299a5787d727f65e2f3d5a67d778f992565b55a0e66df4869afd164713\" for container &ContainerMetadata{Name:install-cni-plugin,Attempt:0,}" Feb 13 23:11:09.298359 containerd[1511]: time="2025-02-13T23:11:09.298320102Z" level=info msg="CreateContainer within sandbox \"81d01a299a5787d727f65e2f3d5a67d778f992565b55a0e66df4869afd164713\" for &ContainerMetadata{Name:install-cni-plugin,Attempt:0,} returns container id \"b6741651fb4e27ae770d985e586d342d920c72447059a2523c94b21c4f4a3d1b\"" Feb 13 23:11:09.298791 containerd[1511]: time="2025-02-13T23:11:09.298726517Z" level=info msg="StartContainer for \"b6741651fb4e27ae770d985e586d342d920c72447059a2523c94b21c4f4a3d1b\"" Feb 13 23:11:09.330746 systemd[1]: Started cri-containerd-b6741651fb4e27ae770d985e586d342d920c72447059a2523c94b21c4f4a3d1b.scope - libcontainer container b6741651fb4e27ae770d985e586d342d920c72447059a2523c94b21c4f4a3d1b. Feb 13 23:11:09.357435 containerd[1511]: time="2025-02-13T23:11:09.357393006Z" level=info msg="StartContainer for \"b6741651fb4e27ae770d985e586d342d920c72447059a2523c94b21c4f4a3d1b\" returns successfully" Feb 13 23:11:09.357924 systemd[1]: cri-containerd-b6741651fb4e27ae770d985e586d342d920c72447059a2523c94b21c4f4a3d1b.scope: Deactivated successfully. Feb 13 23:11:09.446322 kubelet[1856]: E0213 23:11:09.446274 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:09.487852 containerd[1511]: time="2025-02-13T23:11:09.487696940Z" level=info msg="shim disconnected" id=b6741651fb4e27ae770d985e586d342d920c72447059a2523c94b21c4f4a3d1b namespace=k8s.io Feb 13 23:11:09.488325 containerd[1511]: time="2025-02-13T23:11:09.488100762Z" level=warning msg="cleaning up after shim disconnected" id=b6741651fb4e27ae770d985e586d342d920c72447059a2523c94b21c4f4a3d1b namespace=k8s.io Feb 13 23:11:09.488325 containerd[1511]: time="2025-02-13T23:11:09.488121204Z" level=info msg="cleaning up dead shim" namespace=k8s.io Feb 13 23:11:09.576655 containerd[1511]: time="2025-02-13T23:11:09.575773621Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\"" Feb 13 23:11:10.073565 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 13 23:11:10.146810 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-b6741651fb4e27ae770d985e586d342d920c72447059a2523c94b21c4f4a3d1b-rootfs.mount: Deactivated successfully. Feb 13 23:11:10.345470 sshd[2168]: Invalid user xyx from 27.254.163.61 port 40048 Feb 13 23:11:10.447561 kubelet[1856]: E0213 23:11:10.447449 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:10.603761 sshd[2168]: Received disconnect from 27.254.163.61 port 40048:11: Bye Bye [preauth] Feb 13 23:11:10.603761 sshd[2168]: Disconnected from invalid user xyx 27.254.163.61 port 40048 [preauth] Feb 13 23:11:10.606424 systemd[1]: sshd@7-10.244.92.250:22-27.254.163.61:40048.service: Deactivated successfully. Feb 13 23:11:11.449162 kubelet[1856]: E0213 23:11:11.448996 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:12.614584 systemd-resolved[1385]: Clock change detected. Flushing caches. Feb 13 23:11:12.614701 systemd-timesyncd[1402]: Contacted time server [2a01:7e00::f03c:91ff:fe96:f129]:123 (2.flatcar.pool.ntp.org). Feb 13 23:11:12.614764 systemd-timesyncd[1402]: Initial clock synchronization to Thu 2025-02-13 23:11:12.614205 UTC. Feb 13 23:11:13.049570 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3413420014.mount: Deactivated successfully. Feb 13 23:11:13.241855 kubelet[1856]: E0213 23:11:13.241602 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:13.809453 containerd[1511]: time="2025-02-13T23:11:13.807759270Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel:v0.22.0\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:13.809453 containerd[1511]: time="2025-02-13T23:11:13.808437679Z" level=info msg="stop pulling image docker.io/flannel/flannel:v0.22.0: active requests=0, bytes read=26866358" Feb 13 23:11:13.811812 containerd[1511]: time="2025-02-13T23:11:13.811751239Z" level=info msg="ImageCreate event name:\"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:13.816283 containerd[1511]: time="2025-02-13T23:11:13.816234909Z" level=info msg="ImageCreate event name:\"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:13.817976 containerd[1511]: time="2025-02-13T23:11:13.817943044Z" level=info msg="Pulled image \"docker.io/flannel/flannel:v0.22.0\" with image id \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\", repo tag \"docker.io/flannel/flannel:v0.22.0\", repo digest \"docker.io/flannel/flannel@sha256:5f83f1243057458e27249157394e3859cf31cc075354af150d497f2ebc8b54db\", size \"26855532\" in 3.450324984s" Feb 13 23:11:13.818176 containerd[1511]: time="2025-02-13T23:11:13.818154158Z" level=info msg="PullImage \"docker.io/flannel/flannel:v0.22.0\" returns image reference \"sha256:38c11b8f4aa1904512c0b3e93d34604de20ba24b38d4365d27fe05b7a4ce6f68\"" Feb 13 23:11:13.821652 containerd[1511]: time="2025-02-13T23:11:13.821619011Z" level=info msg="CreateContainer within sandbox \"81d01a299a5787d727f65e2f3d5a67d778f992565b55a0e66df4869afd164713\" for container &ContainerMetadata{Name:install-cni,Attempt:0,}" Feb 13 23:11:13.836471 containerd[1511]: time="2025-02-13T23:11:13.836440074Z" level=info msg="CreateContainer within sandbox \"81d01a299a5787d727f65e2f3d5a67d778f992565b55a0e66df4869afd164713\" for &ContainerMetadata{Name:install-cni,Attempt:0,} returns container id \"d0f7c4611bb2b5a77daa2060a03daac12c4cd9c102484b48ac13c844cbc0c63f\"" Feb 13 23:11:13.837332 containerd[1511]: time="2025-02-13T23:11:13.837304633Z" level=info msg="StartContainer for \"d0f7c4611bb2b5a77daa2060a03daac12c4cd9c102484b48ac13c844cbc0c63f\"" Feb 13 23:11:13.873564 systemd[1]: Started cri-containerd-d0f7c4611bb2b5a77daa2060a03daac12c4cd9c102484b48ac13c844cbc0c63f.scope - libcontainer container d0f7c4611bb2b5a77daa2060a03daac12c4cd9c102484b48ac13c844cbc0c63f. Feb 13 23:11:13.899603 systemd[1]: cri-containerd-d0f7c4611bb2b5a77daa2060a03daac12c4cd9c102484b48ac13c844cbc0c63f.scope: Deactivated successfully. Feb 13 23:11:13.899917 containerd[1511]: time="2025-02-13T23:11:13.899665201Z" level=info msg="StartContainer for \"d0f7c4611bb2b5a77daa2060a03daac12c4cd9c102484b48ac13c844cbc0c63f\" returns successfully" Feb 13 23:11:13.919610 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-d0f7c4611bb2b5a77daa2060a03daac12c4cd9c102484b48ac13c844cbc0c63f-rootfs.mount: Deactivated successfully. Feb 13 23:11:13.968770 kubelet[1856]: I0213 23:11:13.967826 1856 kubelet_node_status.go:502] "Fast updating node status as it just became ready" Feb 13 23:11:13.996477 containerd[1511]: time="2025-02-13T23:11:13.996288098Z" level=info msg="shim disconnected" id=d0f7c4611bb2b5a77daa2060a03daac12c4cd9c102484b48ac13c844cbc0c63f namespace=k8s.io Feb 13 23:11:13.996901 containerd[1511]: time="2025-02-13T23:11:13.996846807Z" level=warning msg="cleaning up after shim disconnected" id=d0f7c4611bb2b5a77daa2060a03daac12c4cd9c102484b48ac13c844cbc0c63f namespace=k8s.io Feb 13 23:11:13.997233 containerd[1511]: time="2025-02-13T23:11:13.997066302Z" level=info msg="cleaning up dead shim" namespace=k8s.io Feb 13 23:11:14.242942 kubelet[1856]: E0213 23:11:14.242846 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:14.386813 containerd[1511]: time="2025-02-13T23:11:14.384870183Z" level=info msg="CreateContainer within sandbox \"81d01a299a5787d727f65e2f3d5a67d778f992565b55a0e66df4869afd164713\" for container &ContainerMetadata{Name:kube-flannel,Attempt:0,}" Feb 13 23:11:14.400904 containerd[1511]: time="2025-02-13T23:11:14.400855057Z" level=info msg="CreateContainer within sandbox \"81d01a299a5787d727f65e2f3d5a67d778f992565b55a0e66df4869afd164713\" for &ContainerMetadata{Name:kube-flannel,Attempt:0,} returns container id \"9f9e6b932163589156d5d7fdaf6f9ee903260462bb84d6476590385397c6e7fc\"" Feb 13 23:11:14.401786 containerd[1511]: time="2025-02-13T23:11:14.401540240Z" level=info msg="StartContainer for \"9f9e6b932163589156d5d7fdaf6f9ee903260462bb84d6476590385397c6e7fc\"" Feb 13 23:11:14.432719 systemd[1]: Started cri-containerd-9f9e6b932163589156d5d7fdaf6f9ee903260462bb84d6476590385397c6e7fc.scope - libcontainer container 9f9e6b932163589156d5d7fdaf6f9ee903260462bb84d6476590385397c6e7fc. Feb 13 23:11:14.461423 containerd[1511]: time="2025-02-13T23:11:14.461159466Z" level=info msg="StartContainer for \"9f9e6b932163589156d5d7fdaf6f9ee903260462bb84d6476590385397c6e7fc\" returns successfully" Feb 13 23:11:15.243751 kubelet[1856]: E0213 23:11:15.243637 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:15.538692 systemd-networkd[1433]: flannel.1: Link UP Feb 13 23:11:15.538702 systemd-networkd[1433]: flannel.1: Gained carrier Feb 13 23:11:16.244762 kubelet[1856]: E0213 23:11:16.244612 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:17.148935 systemd-networkd[1433]: flannel.1: Gained IPv6LL Feb 13 23:11:17.245316 kubelet[1856]: E0213 23:11:17.245122 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:18.245659 kubelet[1856]: E0213 23:11:18.245542 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:19.246096 kubelet[1856]: E0213 23:11:19.245977 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:20.246481 kubelet[1856]: E0213 23:11:20.246352 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:20.921175 kubelet[1856]: I0213 23:11:20.921006 1856 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="kube-flannel/kube-flannel-ds-6lszk" podStartSLOduration=9.69621664 podStartE2EDuration="17.920924557s" podCreationTimestamp="2025-02-13 23:11:03 +0000 UTC" firstStartedPulling="2025-02-13 23:11:04.803101938 +0000 UTC m=+2.869459887" lastFinishedPulling="2025-02-13 23:11:13.819599851 +0000 UTC m=+11.094167804" observedRunningTime="2025-02-13 23:11:15.404306164 +0000 UTC m=+12.678874194" watchObservedRunningTime="2025-02-13 23:11:20.920924557 +0000 UTC m=+18.195492545" Feb 13 23:11:20.938375 systemd[1]: Created slice kubepods-besteffort-podc39377b1_f6c0_4e00_b6e5_4e4919be99a4.slice - libcontainer container kubepods-besteffort-podc39377b1_f6c0_4e00_b6e5_4e4919be99a4.slice. Feb 13 23:11:20.975600 kubelet[1856]: I0213 23:11:20.975507 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-bxbvt\" (UniqueName: \"kubernetes.io/projected/c39377b1-f6c0-4e00-b6e5-4e4919be99a4-kube-api-access-bxbvt\") pod \"nginx-deployment-7fcdb87857-f28qv\" (UID: \"c39377b1-f6c0-4e00-b6e5-4e4919be99a4\") " pod="default/nginx-deployment-7fcdb87857-f28qv" Feb 13 23:11:21.242776 containerd[1511]: time="2025-02-13T23:11:21.242627999Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-f28qv,Uid:c39377b1-f6c0-4e00-b6e5-4e4919be99a4,Namespace:default,Attempt:0,}" Feb 13 23:11:21.247001 kubelet[1856]: E0213 23:11:21.246925 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:21.276587 systemd-networkd[1433]: cni0: Link UP Feb 13 23:11:21.276595 systemd-networkd[1433]: cni0: Gained carrier Feb 13 23:11:21.279303 systemd-networkd[1433]: cni0: Lost carrier Feb 13 23:11:21.284515 systemd-networkd[1433]: veth58f96b93: Link UP Feb 13 23:11:21.288724 kernel: cni0: port 1(veth58f96b93) entered blocking state Feb 13 23:11:21.288990 kernel: cni0: port 1(veth58f96b93) entered disabled state Feb 13 23:11:21.289675 kernel: veth58f96b93: entered allmulticast mode Feb 13 23:11:21.290455 kernel: veth58f96b93: entered promiscuous mode Feb 13 23:11:21.291596 kernel: cni0: port 1(veth58f96b93) entered blocking state Feb 13 23:11:21.291640 kernel: cni0: port 1(veth58f96b93) entered forwarding state Feb 13 23:11:21.292758 kernel: cni0: port 1(veth58f96b93) entered disabled state Feb 13 23:11:21.298472 kernel: cni0: port 1(veth58f96b93) entered blocking state Feb 13 23:11:21.298539 kernel: cni0: port 1(veth58f96b93) entered forwarding state Feb 13 23:11:21.301749 systemd-networkd[1433]: veth58f96b93: Gained carrier Feb 13 23:11:21.305599 containerd[1511]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00001e938), "name":"cbr0", "type":"bridge"} Feb 13 23:11:21.305599 containerd[1511]: delegateAdd: netconf sent to delegate plugin: Feb 13 23:11:21.305961 systemd-networkd[1433]: cni0: Gained carrier Feb 13 23:11:21.340203 containerd[1511]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-02-13T23:11:21.340119587Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 23:11:21.340471 containerd[1511]: time="2025-02-13T23:11:21.340185156Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 23:11:21.340471 containerd[1511]: time="2025-02-13T23:11:21.340196753Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:21.340471 containerd[1511]: time="2025-02-13T23:11:21.340272681Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:21.365617 systemd[1]: Started cri-containerd-5f49f84f3d5de56c66cf3b3272ae7dcb14fc5d7c920ba3b9e18ebb4460a73b6b.scope - libcontainer container 5f49f84f3d5de56c66cf3b3272ae7dcb14fc5d7c920ba3b9e18ebb4460a73b6b. Feb 13 23:11:21.410964 containerd[1511]: time="2025-02-13T23:11:21.410930531Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nginx-deployment-7fcdb87857-f28qv,Uid:c39377b1-f6c0-4e00-b6e5-4e4919be99a4,Namespace:default,Attempt:0,} returns sandbox id \"5f49f84f3d5de56c66cf3b3272ae7dcb14fc5d7c920ba3b9e18ebb4460a73b6b\"" Feb 13 23:11:21.412086 containerd[1511]: time="2025-02-13T23:11:21.412063209Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Feb 13 23:11:22.094993 systemd[1]: run-containerd-runc-k8s.io-5f49f84f3d5de56c66cf3b3272ae7dcb14fc5d7c920ba3b9e18ebb4460a73b6b-runc.HMtJWf.mount: Deactivated successfully. Feb 13 23:11:22.248251 kubelet[1856]: E0213 23:11:22.248153 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:22.460890 systemd-networkd[1433]: veth58f96b93: Gained IPv6LL Feb 13 23:11:22.972707 systemd-networkd[1433]: cni0: Gained IPv6LL Feb 13 23:11:23.231886 kubelet[1856]: E0213 23:11:23.231495 1856 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:23.248498 kubelet[1856]: E0213 23:11:23.248426 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:24.250090 kubelet[1856]: E0213 23:11:24.250026 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:24.346815 update_engine[1490]: I20250213 23:11:24.346699 1490 update_attempter.cc:509] Updating boot flags... Feb 13 23:11:24.401435 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2560) Feb 13 23:11:24.483409 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (2558) Feb 13 23:11:25.250326 kubelet[1856]: E0213 23:11:25.250222 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:25.291006 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount1737596409.mount: Deactivated successfully. Feb 13 23:11:26.251593 kubelet[1856]: E0213 23:11:26.251466 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:26.422417 containerd[1511]: time="2025-02-13T23:11:26.422008448Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:26.423094 containerd[1511]: time="2025-02-13T23:11:26.422706234Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=73054493" Feb 13 23:11:26.423094 containerd[1511]: time="2025-02-13T23:11:26.423043515Z" level=info msg="ImageCreate event name:\"sha256:fe94eb5f0c9c8d0ca277aa8cd5940f1faf5970175bf373932babc578545deda8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:26.426153 containerd[1511]: time="2025-02-13T23:11:26.426108587Z" level=info msg="ImageCreate event name:\"ghcr.io/flatcar/nginx@sha256:d9bc3da999da9f147f1277c7b18292486847e8f39f95fcf81d914d0c22815faf\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:26.427605 containerd[1511]: time="2025-02-13T23:11:26.426931928Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:fe94eb5f0c9c8d0ca277aa8cd5940f1faf5970175bf373932babc578545deda8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d9bc3da999da9f147f1277c7b18292486847e8f39f95fcf81d914d0c22815faf\", size \"73054371\" in 5.014741096s" Feb 13 23:11:26.427605 containerd[1511]: time="2025-02-13T23:11:26.426964614Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:fe94eb5f0c9c8d0ca277aa8cd5940f1faf5970175bf373932babc578545deda8\"" Feb 13 23:11:26.429802 containerd[1511]: time="2025-02-13T23:11:26.429554605Z" level=info msg="CreateContainer within sandbox \"5f49f84f3d5de56c66cf3b3272ae7dcb14fc5d7c920ba3b9e18ebb4460a73b6b\" for container &ContainerMetadata{Name:nginx,Attempt:0,}" Feb 13 23:11:26.451234 containerd[1511]: time="2025-02-13T23:11:26.451199110Z" level=info msg="CreateContainer within sandbox \"5f49f84f3d5de56c66cf3b3272ae7dcb14fc5d7c920ba3b9e18ebb4460a73b6b\" for &ContainerMetadata{Name:nginx,Attempt:0,} returns container id \"94f589baf098e7c62ac80e4151cbd41ab713b4df9647fa171bfe723eec343913\"" Feb 13 23:11:26.452602 containerd[1511]: time="2025-02-13T23:11:26.451900094Z" level=info msg="StartContainer for \"94f589baf098e7c62ac80e4151cbd41ab713b4df9647fa171bfe723eec343913\"" Feb 13 23:11:26.494541 systemd[1]: Started cri-containerd-94f589baf098e7c62ac80e4151cbd41ab713b4df9647fa171bfe723eec343913.scope - libcontainer container 94f589baf098e7c62ac80e4151cbd41ab713b4df9647fa171bfe723eec343913. Feb 13 23:11:26.518281 containerd[1511]: time="2025-02-13T23:11:26.517042228Z" level=info msg="StartContainer for \"94f589baf098e7c62ac80e4151cbd41ab713b4df9647fa171bfe723eec343913\" returns successfully" Feb 13 23:11:26.912311 systemd[1]: Started sshd@8-10.244.92.250:22-162.19.243.145:51390.service - OpenSSH per-connection server daemon (162.19.243.145:51390). Feb 13 23:11:27.143490 sshd[2666]: Invalid user radio from 162.19.243.145 port 51390 Feb 13 23:11:27.177318 sshd[2666]: Received disconnect from 162.19.243.145 port 51390:11: Bye Bye [preauth] Feb 13 23:11:27.177318 sshd[2666]: Disconnected from invalid user radio 162.19.243.145 port 51390 [preauth] Feb 13 23:11:27.181093 systemd[1]: sshd@8-10.244.92.250:22-162.19.243.145:51390.service: Deactivated successfully. Feb 13 23:11:27.252748 kubelet[1856]: E0213 23:11:27.252657 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:27.433557 kubelet[1856]: I0213 23:11:27.433313 1856 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nginx-deployment-7fcdb87857-f28qv" podStartSLOduration=2.416933996 podStartE2EDuration="7.433243824s" podCreationTimestamp="2025-02-13 23:11:20 +0000 UTC" firstStartedPulling="2025-02-13 23:11:21.411805411 +0000 UTC m=+18.686373352" lastFinishedPulling="2025-02-13 23:11:26.428115243 +0000 UTC m=+23.702683180" observedRunningTime="2025-02-13 23:11:27.433117226 +0000 UTC m=+24.707685240" watchObservedRunningTime="2025-02-13 23:11:27.433243824 +0000 UTC m=+24.707811833" Feb 13 23:11:28.253990 kubelet[1856]: E0213 23:11:28.253891 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:29.254779 kubelet[1856]: E0213 23:11:29.254683 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:30.256357 kubelet[1856]: E0213 23:11:30.256119 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:31.257126 kubelet[1856]: E0213 23:11:31.256887 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:32.257883 kubelet[1856]: E0213 23:11:32.257763 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:33.259113 kubelet[1856]: E0213 23:11:33.258993 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:34.259365 kubelet[1856]: E0213 23:11:34.259224 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:34.271415 kubelet[1856]: I0213 23:11:34.271340 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-fhzr9\" (UniqueName: \"kubernetes.io/projected/807bca8b-1f76-4abb-a4c5-29e255d2d70f-kube-api-access-fhzr9\") pod \"nfs-server-provisioner-0\" (UID: \"807bca8b-1f76-4abb-a4c5-29e255d2d70f\") " pod="default/nfs-server-provisioner-0" Feb 13 23:11:34.271669 kubelet[1856]: I0213 23:11:34.271487 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"data\" (UniqueName: \"kubernetes.io/empty-dir/807bca8b-1f76-4abb-a4c5-29e255d2d70f-data\") pod \"nfs-server-provisioner-0\" (UID: \"807bca8b-1f76-4abb-a4c5-29e255d2d70f\") " pod="default/nfs-server-provisioner-0" Feb 13 23:11:34.279179 systemd[1]: Created slice kubepods-besteffort-pod807bca8b_1f76_4abb_a4c5_29e255d2d70f.slice - libcontainer container kubepods-besteffort-pod807bca8b_1f76_4abb_a4c5_29e255d2d70f.slice. Feb 13 23:11:34.583719 containerd[1511]: time="2025-02-13T23:11:34.583561011Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:807bca8b-1f76-4abb-a4c5-29e255d2d70f,Namespace:default,Attempt:0,}" Feb 13 23:11:34.618749 systemd-networkd[1433]: veth565949ce: Link UP Feb 13 23:11:34.621933 kernel: cni0: port 2(veth565949ce) entered blocking state Feb 13 23:11:34.622001 kernel: cni0: port 2(veth565949ce) entered disabled state Feb 13 23:11:34.622022 kernel: veth565949ce: entered allmulticast mode Feb 13 23:11:34.622043 kernel: veth565949ce: entered promiscuous mode Feb 13 23:11:34.627596 kernel: cni0: port 2(veth565949ce) entered blocking state Feb 13 23:11:34.627648 kernel: cni0: port 2(veth565949ce) entered forwarding state Feb 13 23:11:34.628661 systemd-networkd[1433]: veth565949ce: Gained carrier Feb 13 23:11:34.631728 containerd[1511]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc0000a48e8), "name":"cbr0", "type":"bridge"} Feb 13 23:11:34.631728 containerd[1511]: delegateAdd: netconf sent to delegate plugin: Feb 13 23:11:34.656718 containerd[1511]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-02-13T23:11:34.656476246Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 23:11:34.656718 containerd[1511]: time="2025-02-13T23:11:34.656542220Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 23:11:34.656718 containerd[1511]: time="2025-02-13T23:11:34.656552295Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:34.656718 containerd[1511]: time="2025-02-13T23:11:34.656659983Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:34.682576 systemd[1]: Started cri-containerd-a8db8d295f2b604a6d24170d7780c4a9dd728e07398f8c67dc121ece20bc1525.scope - libcontainer container a8db8d295f2b604a6d24170d7780c4a9dd728e07398f8c67dc121ece20bc1525. Feb 13 23:11:34.726781 containerd[1511]: time="2025-02-13T23:11:34.726738295Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:nfs-server-provisioner-0,Uid:807bca8b-1f76-4abb-a4c5-29e255d2d70f,Namespace:default,Attempt:0,} returns sandbox id \"a8db8d295f2b604a6d24170d7780c4a9dd728e07398f8c67dc121ece20bc1525\"" Feb 13 23:11:34.728770 containerd[1511]: time="2025-02-13T23:11:34.728552979Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\"" Feb 13 23:11:35.259671 kubelet[1856]: E0213 23:11:35.259560 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:35.964851 systemd-networkd[1433]: veth565949ce: Gained IPv6LL Feb 13 23:11:36.261833 kubelet[1856]: E0213 23:11:36.260730 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:37.262375 kubelet[1856]: E0213 23:11:37.261744 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:37.376122 systemd[1]: var-lib-containerd-tmpmounts-containerd\x2dmount3842385874.mount: Deactivated successfully. Feb 13 23:11:38.262497 kubelet[1856]: E0213 23:11:38.262458 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:39.147929 containerd[1511]: time="2025-02-13T23:11:39.146813918Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:39.148926 containerd[1511]: time="2025-02-13T23:11:39.148891297Z" level=info msg="stop pulling image registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8: active requests=0, bytes read=91039414" Feb 13 23:11:39.151298 containerd[1511]: time="2025-02-13T23:11:39.150079708Z" level=info msg="ImageCreate event name:\"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:39.174473 containerd[1511]: time="2025-02-13T23:11:39.174414290Z" level=info msg="ImageCreate event name:\"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:39.175559 containerd[1511]: time="2025-02-13T23:11:39.175509465Z" level=info msg="Pulled image \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" with image id \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\", repo tag \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\", repo digest \"registry.k8s.io/sig-storage/nfs-provisioner@sha256:c825f3d5e28bde099bd7a3daace28772d412c9157ad47fa752a9ad0baafc118d\", size \"91036984\" in 4.446913376s" Feb 13 23:11:39.175669 containerd[1511]: time="2025-02-13T23:11:39.175581488Z" level=info msg="PullImage \"registry.k8s.io/sig-storage/nfs-provisioner:v4.0.8\" returns image reference \"sha256:fd0b16f70b66b72bcb2f91d556fa33eba02729c44ffc5f2c16130e7f9fbed3c4\"" Feb 13 23:11:39.180616 containerd[1511]: time="2025-02-13T23:11:39.180259704Z" level=info msg="CreateContainer within sandbox \"a8db8d295f2b604a6d24170d7780c4a9dd728e07398f8c67dc121ece20bc1525\" for container &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,}" Feb 13 23:11:39.199212 containerd[1511]: time="2025-02-13T23:11:39.199124270Z" level=info msg="CreateContainer within sandbox \"a8db8d295f2b604a6d24170d7780c4a9dd728e07398f8c67dc121ece20bc1525\" for &ContainerMetadata{Name:nfs-server-provisioner,Attempt:0,} returns container id \"ed3ceed8e34b52f48b56889bc03549e0e5e22c9c555f6f1f9d0ebbcf4c2ad48b\"" Feb 13 23:11:39.200860 containerd[1511]: time="2025-02-13T23:11:39.199837439Z" level=info msg="StartContainer for \"ed3ceed8e34b52f48b56889bc03549e0e5e22c9c555f6f1f9d0ebbcf4c2ad48b\"" Feb 13 23:11:39.263136 kubelet[1856]: E0213 23:11:39.263071 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:39.311546 systemd[1]: Started cri-containerd-ed3ceed8e34b52f48b56889bc03549e0e5e22c9c555f6f1f9d0ebbcf4c2ad48b.scope - libcontainer container ed3ceed8e34b52f48b56889bc03549e0e5e22c9c555f6f1f9d0ebbcf4c2ad48b. Feb 13 23:11:39.340370 containerd[1511]: time="2025-02-13T23:11:39.340309261Z" level=info msg="StartContainer for \"ed3ceed8e34b52f48b56889bc03549e0e5e22c9c555f6f1f9d0ebbcf4c2ad48b\" returns successfully" Feb 13 23:11:39.480070 kubelet[1856]: I0213 23:11:39.479898 1856 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/nfs-server-provisioner-0" podStartSLOduration=1.030147782 podStartE2EDuration="5.479840646s" podCreationTimestamp="2025-02-13 23:11:34 +0000 UTC" firstStartedPulling="2025-02-13 23:11:34.727963121 +0000 UTC m=+32.002531059" lastFinishedPulling="2025-02-13 23:11:39.177655967 +0000 UTC m=+36.452223923" observedRunningTime="2025-02-13 23:11:39.479794797 +0000 UTC m=+36.754362738" watchObservedRunningTime="2025-02-13 23:11:39.479840646 +0000 UTC m=+36.754408586" Feb 13 23:11:40.264122 kubelet[1856]: E0213 23:11:40.263920 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:41.264992 kubelet[1856]: E0213 23:11:41.264870 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:42.265827 kubelet[1856]: E0213 23:11:42.265711 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:43.230755 kubelet[1856]: E0213 23:11:43.230669 1856 file.go:104] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:43.266661 kubelet[1856]: E0213 23:11:43.266550 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:44.267701 kubelet[1856]: E0213 23:11:44.267593 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:45.268102 kubelet[1856]: E0213 23:11:45.267992 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:46.268648 kubelet[1856]: E0213 23:11:46.268533 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:47.269627 kubelet[1856]: E0213 23:11:47.269522 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:48.270540 kubelet[1856]: E0213 23:11:48.270345 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:48.951898 systemd[1]: Created slice kubepods-besteffort-pod2f010758_2f95_447a_982d_0a72df6da545.slice - libcontainer container kubepods-besteffort-pod2f010758_2f95_447a_982d_0a72df6da545.slice. Feb 13 23:11:48.969665 kubelet[1856]: I0213 23:11:48.969618 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"pvc-1905781d-c9a5-45b1-9a1b-9a519df2b36b\" (UniqueName: \"kubernetes.io/nfs/2f010758-2f95-447a-982d-0a72df6da545-pvc-1905781d-c9a5-45b1-9a1b-9a519df2b36b\") pod \"test-pod-1\" (UID: \"2f010758-2f95-447a-982d-0a72df6da545\") " pod="default/test-pod-1" Feb 13 23:11:48.969826 kubelet[1856]: I0213 23:11:48.969663 1856 reconciler_common.go:251] "operationExecutor.VerifyControllerAttachedVolume started for volume \"kube-api-access-xcqhq\" (UniqueName: \"kubernetes.io/projected/2f010758-2f95-447a-982d-0a72df6da545-kube-api-access-xcqhq\") pod \"test-pod-1\" (UID: \"2f010758-2f95-447a-982d-0a72df6da545\") " pod="default/test-pod-1" Feb 13 23:11:49.116447 kernel: FS-Cache: Loaded Feb 13 23:11:49.191691 kernel: RPC: Registered named UNIX socket transport module. Feb 13 23:11:49.191862 kernel: RPC: Registered udp transport module. Feb 13 23:11:49.191915 kernel: RPC: Registered tcp transport module. Feb 13 23:11:49.191971 kernel: RPC: Registered tcp-with-tls transport module. Feb 13 23:11:49.192799 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 13 23:11:49.271493 kubelet[1856]: E0213 23:11:49.271375 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:49.520310 kernel: NFS: Registering the id_resolver key type Feb 13 23:11:49.522768 kernel: Key type id_resolver registered Feb 13 23:11:49.522885 kernel: Key type id_legacy registered Feb 13 23:11:49.576240 nfsidmap[2973]: nss_getpwnam: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'gb1.brightbox.com' Feb 13 23:11:49.584125 nfsidmap[2976]: nss_name_to_gid: name 'root@nfs-server-provisioner.default.svc.cluster.local' does not map into domain 'gb1.brightbox.com' Feb 13 23:11:49.857051 containerd[1511]: time="2025-02-13T23:11:49.856821783Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2f010758-2f95-447a-982d-0a72df6da545,Namespace:default,Attempt:0,}" Feb 13 23:11:49.896288 systemd-networkd[1433]: vethd5f1b079: Link UP Feb 13 23:11:49.896675 kernel: cni0: port 3(vethd5f1b079) entered blocking state Feb 13 23:11:49.896710 kernel: cni0: port 3(vethd5f1b079) entered disabled state Feb 13 23:11:49.898258 kernel: vethd5f1b079: entered allmulticast mode Feb 13 23:11:49.898585 kernel: vethd5f1b079: entered promiscuous mode Feb 13 23:11:49.907515 kernel: cni0: port 3(vethd5f1b079) entered blocking state Feb 13 23:11:49.907570 kernel: cni0: port 3(vethd5f1b079) entered forwarding state Feb 13 23:11:49.908049 systemd-networkd[1433]: vethd5f1b079: Gained carrier Feb 13 23:11:49.909969 containerd[1511]: map[string]interface {}{"cniVersion":"0.3.1", "hairpinMode":true, "ipMasq":false, "ipam":map[string]interface {}{"ranges":[][]map[string]interface {}{[]map[string]interface {}{map[string]interface {}{"subnet":"192.168.1.0/24"}}}, "routes":[]types.Route{types.Route{Dst:net.IPNet{IP:net.IP{0xc0, 0xa8, 0x0, 0x0}, Mask:net.IPMask{0xff, 0xff, 0x80, 0x0}}, GW:net.IP(nil)}}, "type":"host-local"}, "isDefaultGateway":true, "isGateway":true, "mtu":(*uint)(0xc00009c8e8), "name":"cbr0", "type":"bridge"} Feb 13 23:11:49.909969 containerd[1511]: delegateAdd: netconf sent to delegate plugin: Feb 13 23:11:49.942589 containerd[1511]: {"cniVersion":"0.3.1","hairpinMode":true,"ipMasq":false,"ipam":{"ranges":[[{"subnet":"192.168.1.0/24"}]],"routes":[{"dst":"192.168.0.0/17"}],"type":"host-local"},"isDefaultGateway":true,"isGateway":true,"mtu":1450,"name":"cbr0","type":"bridge"}time="2025-02-13T23:11:49.942501006Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Feb 13 23:11:49.942589 containerd[1511]: time="2025-02-13T23:11:49.942565890Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Feb 13 23:11:49.942928 containerd[1511]: time="2025-02-13T23:11:49.942778105Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:49.942928 containerd[1511]: time="2025-02-13T23:11:49.942889530Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Feb 13 23:11:49.966555 systemd[1]: Started cri-containerd-f92f2b16c27183aca1a1dc3d6c063617387a87718f07fd13b411ebee921f8590.scope - libcontainer container f92f2b16c27183aca1a1dc3d6c063617387a87718f07fd13b411ebee921f8590. Feb 13 23:11:50.016729 containerd[1511]: time="2025-02-13T23:11:50.016391359Z" level=info msg="RunPodSandbox for &PodSandboxMetadata{Name:test-pod-1,Uid:2f010758-2f95-447a-982d-0a72df6da545,Namespace:default,Attempt:0,} returns sandbox id \"f92f2b16c27183aca1a1dc3d6c063617387a87718f07fd13b411ebee921f8590\"" Feb 13 23:11:50.018613 containerd[1511]: time="2025-02-13T23:11:50.018578030Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\"" Feb 13 23:11:50.271948 kubelet[1856]: E0213 23:11:50.271841 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:50.424006 containerd[1511]: time="2025-02-13T23:11:50.423916207Z" level=info msg="ImageUpdate event name:\"ghcr.io/flatcar/nginx:latest\" labels:{key:\"io.cri-containerd.image\" value:\"managed\"}" Feb 13 23:11:50.425463 containerd[1511]: time="2025-02-13T23:11:50.424709614Z" level=info msg="stop pulling image ghcr.io/flatcar/nginx:latest: active requests=0, bytes read=61" Feb 13 23:11:50.428466 containerd[1511]: time="2025-02-13T23:11:50.428433621Z" level=info msg="Pulled image \"ghcr.io/flatcar/nginx:latest\" with image id \"sha256:fe94eb5f0c9c8d0ca277aa8cd5940f1faf5970175bf373932babc578545deda8\", repo tag \"ghcr.io/flatcar/nginx:latest\", repo digest \"ghcr.io/flatcar/nginx@sha256:d9bc3da999da9f147f1277c7b18292486847e8f39f95fcf81d914d0c22815faf\", size \"73054371\" in 409.818751ms" Feb 13 23:11:50.428564 containerd[1511]: time="2025-02-13T23:11:50.428493302Z" level=info msg="PullImage \"ghcr.io/flatcar/nginx:latest\" returns image reference \"sha256:fe94eb5f0c9c8d0ca277aa8cd5940f1faf5970175bf373932babc578545deda8\"" Feb 13 23:11:50.431042 containerd[1511]: time="2025-02-13T23:11:50.430836885Z" level=info msg="CreateContainer within sandbox \"f92f2b16c27183aca1a1dc3d6c063617387a87718f07fd13b411ebee921f8590\" for container &ContainerMetadata{Name:test,Attempt:0,}" Feb 13 23:11:50.441925 containerd[1511]: time="2025-02-13T23:11:50.441864933Z" level=info msg="CreateContainer within sandbox \"f92f2b16c27183aca1a1dc3d6c063617387a87718f07fd13b411ebee921f8590\" for &ContainerMetadata{Name:test,Attempt:0,} returns container id \"186318e318ff609125c5b0430732ab928d44bc9bb767c1405330146ce27cc50c\"" Feb 13 23:11:50.442755 containerd[1511]: time="2025-02-13T23:11:50.442695589Z" level=info msg="StartContainer for \"186318e318ff609125c5b0430732ab928d44bc9bb767c1405330146ce27cc50c\"" Feb 13 23:11:50.474542 systemd[1]: Started cri-containerd-186318e318ff609125c5b0430732ab928d44bc9bb767c1405330146ce27cc50c.scope - libcontainer container 186318e318ff609125c5b0430732ab928d44bc9bb767c1405330146ce27cc50c. Feb 13 23:11:50.500968 containerd[1511]: time="2025-02-13T23:11:50.500916460Z" level=info msg="StartContainer for \"186318e318ff609125c5b0430732ab928d44bc9bb767c1405330146ce27cc50c\" returns successfully" Feb 13 23:11:51.132928 systemd-networkd[1433]: vethd5f1b079: Gained IPv6LL Feb 13 23:11:51.272422 kubelet[1856]: E0213 23:11:51.272300 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:51.519432 kubelet[1856]: I0213 23:11:51.519290 1856 pod_startup_latency_tracker.go:104] "Observed pod startup duration" pod="default/test-pod-1" podStartSLOduration=15.107800289 podStartE2EDuration="15.519215954s" podCreationTimestamp="2025-02-13 23:11:36 +0000 UTC" firstStartedPulling="2025-02-13 23:11:50.017866412 +0000 UTC m=+47.292434352" lastFinishedPulling="2025-02-13 23:11:50.429282076 +0000 UTC m=+47.703850017" observedRunningTime="2025-02-13 23:11:51.519108432 +0000 UTC m=+48.793676489" watchObservedRunningTime="2025-02-13 23:11:51.519215954 +0000 UTC m=+48.793783941" Feb 13 23:11:52.273473 kubelet[1856]: E0213 23:11:52.273286 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:53.274367 kubelet[1856]: E0213 23:11:53.274268 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:54.274700 kubelet[1856]: E0213 23:11:54.274600 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests" Feb 13 23:11:55.275474 kubelet[1856]: E0213 23:11:55.275334 1856 file_linux.go:61] "Unable to read config path" err="path does not exist, ignoring" path="/etc/kubernetes/manifests"